{"level":"warn","ts":"2026-10-26T01:01:48.745375Z","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-26T01:01:48.745486Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.113: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.113:2380","--initial-cluster=instance=https://162.253.55.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.113: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-26T01:01:48.745559Z","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-26T01:01:48.74557Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-26T01:01:48.745616Z","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-26T01:01:48.746479Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"]} {"level":"info","ts":"2026-10-26T01:01:48.746581Z","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.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.113: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-26T01:01:48.749876Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.070095ms"} {"level":"info","ts":"2026-10-26T01:01:48.762763Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3b717bddf98d48e3","cluster-id":"cef0f7c69f0576fc"} {"level":"info","ts":"2026-10-26T01:01:48.762847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T01:01:48.762867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 0"} {"level":"info","ts":"2026-10-26T01:01:48.762877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3b717bddf98d48e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T01:01:48.762893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 1"} {"level":"info","ts":"2026-10-26T01:01:48.762927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"warn","ts":"2026-10-26T01:01:48.769532Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T01:01:48.771623Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T01:01:48.773324Z","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-26T01:01:48.775965Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3b717bddf98d48e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T01:01:48.776942Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3b717bddf98d48e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T01:01:48.777063Z","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-26T01:01:48.777103Z","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-26T01:01:48.777118Z","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-26T01:01:48.778397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"info","ts":"2026-10-26T01:01:48.778579Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","added-peer-id":"3b717bddf98d48e3","added-peer-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-26T01:01:48.780129Z","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-26T01:01:48.780231Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-26T01:01:48.780289Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-26T01:01:48.781037Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T01:01:48.781003Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3b717bddf98d48e3","initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T01:01:49.763703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T01:01:49.763773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T01:01:49.76381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgPreVoteResp from 3b717bddf98d48e3 at term 1"} {"level":"info","ts":"2026-10-26T01:01:49.763828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became candidate at term 2"} {"level":"info","ts":"2026-10-26T01:01:49.763838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgVoteResp from 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-26T01:01:49.763853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became leader at term 2"} {"level":"info","ts":"2026-10-26T01:01:49.763866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3b717bddf98d48e3 elected leader 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-26T01:01:49.765133Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:01:49.766552Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3b717bddf98d48e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.113:2379]}","request-path":"/0/members/3b717bddf98d48e3/attributes","cluster-id":"cef0f7c69f0576fc","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T01:01:49.7666Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T01:01:49.766616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T01:01:49.766968Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T01:01:49.767051Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:01:49.767254Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:01:49.767066Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T01:01:49.767315Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:01:49.769737Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.113:2379"} {"level":"info","ts":"2026-10-26T01:01:49.770247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-26T01:02:04.362249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.808702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:02:04.362388Z","caller":"traceutil/trace.go:171","msg":"trace[1951382614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"114.030258ms","start":"2026-10-26T01:02:04.248334Z","end":"2026-10-26T01:02:04.362365Z","steps":["trace[1951382614] 'range keys from in-memory index tree' (duration: 113.658868ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:02:09.977349Z","caller":"traceutil/trace.go:171","msg":"trace[469141919] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"220.93975ms","start":"2026-10-26T01:02:09.756385Z","end":"2026-10-26T01:02:09.977325Z","steps":["trace[469141919] 'read index received' (duration: 220.676834ms)","trace[469141919] 'applied index is now lower than readState.Index' (duration: 261.956µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:02:09.977403Z","caller":"traceutil/trace.go:171","msg":"trace[307458029] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"238.01484ms","start":"2026-10-26T01:02:09.739343Z","end":"2026-10-26T01:02:09.977358Z","steps":["trace[307458029] 'process raft request' (duration: 237.804445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:02:09.977477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.069563ms","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-26T01:02:09.977512Z","caller":"traceutil/trace.go:171","msg":"trace[905769463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"221.143755ms","start":"2026-10-26T01:02:09.756357Z","end":"2026-10-26T01:02:09.977501Z","steps":["trace[905769463] 'agreement among raft nodes before linearized reading' (duration: 221.063723ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:02:34.44662Z","caller":"traceutil/trace.go:171","msg":"trace[2130983810] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"103.339836ms","start":"2026-10-26T01:02:34.343189Z","end":"2026-10-26T01:02:34.446529Z","steps":["trace[2130983810] 'process raft request' (duration: 102.968977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:02:41.126664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.221317ms","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-26T01:02:41.12676Z","caller":"traceutil/trace.go:171","msg":"trace[1838016992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"134.397262ms","start":"2026-10-26T01:02:40.992342Z","end":"2026-10-26T01:02:41.126739Z","steps":["trace[1838016992] 'range keys from in-memory index tree' (duration: 133.9249ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:02:41.568977Z","caller":"traceutil/trace.go:171","msg":"trace[1970518572] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"223.967198ms","start":"2026-10-26T01:02:41.344983Z","end":"2026-10-26T01:02:41.56895Z","steps":["trace[1970518572] 'read index received' (duration: 223.286892ms)","trace[1970518572] 'applied index is now lower than readState.Index' (duration: 679.246µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:02:41.569129Z","caller":"traceutil/trace.go:171","msg":"trace[553024848] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"288.521089ms","start":"2026-10-26T01:02:41.280557Z","end":"2026-10-26T01:02:41.569078Z","steps":["trace[553024848] 'process raft request' (duration: 287.873123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:02:41.569191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.196064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-df6s6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-26T01:02:41.569226Z","caller":"traceutil/trace.go:171","msg":"trace[205603964] range","detail":"{range_begin:/registry/pods/kube-system/cilium-df6s6; range_end:; response_count:1; response_revision:653; }","duration":"224.289397ms","start":"2026-10-26T01:02:41.344924Z","end":"2026-10-26T01:02:41.569214Z","steps":["trace[205603964] 'agreement among raft nodes before linearized reading' (duration: 224.145423ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:02:41.723955Z","caller":"traceutil/trace.go:171","msg":"trace[932311818] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"136.522024ms","start":"2026-10-26T01:02:41.587405Z","end":"2026-10-26T01:02:41.723927Z","steps":["trace[932311818] 'process raft request' (duration: 111.852986ms)","trace[932311818] 'compare' (duration: 24.415762ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:02:57.543881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.901348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-26T01:02:57.543965Z","caller":"traceutil/trace.go:171","msg":"trace[1726796208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"319.040912ms","start":"2026-10-26T01:02:57.224905Z","end":"2026-10-26T01:02:57.543946Z","steps":["trace[1726796208] 'range keys from in-memory index tree' (duration: 318.725214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:02:57.544002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:02:57.224882Z","time spent":"319.111403ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T01:02:57.544112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.231878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2dnkj\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-26T01:02:57.544269Z","caller":"traceutil/trace.go:171","msg":"trace[1015141526] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2dnkj; range_end:; response_count:1; response_revision:821; }","duration":"104.442283ms","start":"2026-10-26T01:02:57.439803Z","end":"2026-10-26T01:02:57.544246Z","steps":["trace[1015141526] 'range keys from in-memory index tree' (duration: 103.991892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:27.452696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.699969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:03:27.452784Z","caller":"traceutil/trace.go:171","msg":"trace[53174233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"203.820332ms","start":"2026-10-26T01:03:27.248941Z","end":"2026-10-26T01:03:27.452761Z","steps":["trace[53174233] 'range keys from in-memory index tree' (duration: 203.591376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:27.452677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.372168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T01:03:27.452958Z","caller":"traceutil/trace.go:171","msg":"trace[1740611097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"196.700727ms","start":"2026-10-26T01:03:27.256222Z","end":"2026-10-26T01:03:27.452923Z","steps":["trace[1740611097] 'range keys from in-memory index tree' (duration: 196.121323ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:04:16.251153Z","caller":"traceutil/trace.go:171","msg":"trace[466793492] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1974; }","duration":"335.85655ms","start":"2026-10-26T01:04:15.915269Z","end":"2026-10-26T01:04:16.251126Z","steps":["trace[466793492] 'read index received' (duration: 9.981746ms)","trace[466793492] 'applied index is now lower than readState.Index' (duration: 325.873484ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:04:16.251231Z","caller":"traceutil/trace.go:171","msg":"trace[1176398025] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"416.430561ms","start":"2026-10-26T01:04:15.834762Z","end":"2026-10-26T01:04:16.251193Z","steps":["trace[1176398025] 'process raft request' (duration: 416.160715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:16.251348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.057014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:04:16.251441Z","caller":"traceutil/trace.go:171","msg":"trace[1137106928] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1902; }","duration":"336.165037ms","start":"2026-10-26T01:04:15.915255Z","end":"2026-10-26T01:04:16.25142Z","steps":["trace[1137106928] 'agreement among raft nodes before linearized reading' (duration: 336.017623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:16.251473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.397402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-26T01:04:16.25149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:04:15.915234Z","time spent":"336.240299ms","remote":"127.0.0.1:35704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-10-26T01:04:16.251533Z","caller":"traceutil/trace.go:171","msg":"trace[792123322] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1902; }","duration":"303.486723ms","start":"2026-10-26T01:04:15.94803Z","end":"2026-10-26T01:04:16.251517Z","steps":["trace[792123322] 'agreement among raft nodes before linearized reading' (duration: 303.278778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:16.251564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:04:15.94802Z","time spent":"303.536565ms","remote":"127.0.0.1:48804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5723,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-26T01:04:16.252009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:04:15.834746Z","time spent":"416.566534ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:04:29.053352Z","caller":"traceutil/trace.go:171","msg":"trace[1499020126] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"355.892822ms","start":"2026-10-26T01:04:28.697439Z","end":"2026-10-26T01:04:29.053332Z","steps":["trace[1499020126] 'process raft request' (duration: 355.764659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:29.05349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:04:28.697396Z","time spent":"356.040826ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:04:29.05516Z","caller":"traceutil/trace.go:171","msg":"trace[925394223] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2080; }","duration":"233.986535ms","start":"2026-10-26T01:04:28.821143Z","end":"2026-10-26T01:04:29.055129Z","steps":["trace[925394223] 'read index received' (duration: 232.086028ms)","trace[925394223] 'applied index is now lower than readState.Index' (duration: 1.899177ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:04:29.055213Z","caller":"traceutil/trace.go:171","msg":"trace[1889448559] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"236.020235ms","start":"2026-10-26T01:04:28.819163Z","end":"2026-10-26T01:04:29.055183Z","steps":["trace[1889448559] 'process raft request' (duration: 235.80572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:29.055371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.22045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T01:04:29.055438Z","caller":"traceutil/trace.go:171","msg":"trace[984524951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2005; }","duration":"234.314242ms","start":"2026-10-26T01:04:28.821113Z","end":"2026-10-26T01:04:29.055427Z","steps":["trace[984524951] 'agreement among raft nodes before linearized reading' (duration: 234.156988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:29.055471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.097782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T01:04:29.055488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.998012ms","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-26T01:04:29.055525Z","caller":"traceutil/trace.go:171","msg":"trace[353319251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2005; }","duration":"198.167824ms","start":"2026-10-26T01:04:28.85734Z","end":"2026-10-26T01:04:29.055508Z","steps":["trace[353319251] 'agreement among raft nodes before linearized reading' (duration: 197.958279ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:04:29.055549Z","caller":"traceutil/trace.go:171","msg":"trace[923914176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2005; }","duration":"192.106604ms","start":"2026-10-26T01:04:28.863425Z","end":"2026-10-26T01:04:29.055532Z","steps":["trace[923914176] 'agreement among raft nodes before linearized reading' (duration: 191.902789ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:05:00.428203Z","caller":"traceutil/trace.go:171","msg":"trace[1147106962] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"100.688825ms","start":"2026-10-26T01:05:00.327463Z","end":"2026-10-26T01:05:00.428152Z","steps":["trace[1147106962] 'process raft request' (duration: 37.244656ms)","trace[1147106962] 'compare' (duration: 63.251024ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:05:00.597552Z","caller":"traceutil/trace.go:171","msg":"trace[168425210] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"118.00994ms","start":"2026-10-26T01:05:00.479507Z","end":"2026-10-26T01:05:00.597517Z","steps":["trace[168425210] 'process raft request' (duration: 84.016915ms)","trace[168425210] 'compare' (duration: 33.536814ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:05:06.980555Z","caller":"traceutil/trace.go:171","msg":"trace[268266529] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"311.645338ms","start":"2026-10-26T01:05:06.668874Z","end":"2026-10-26T01:05:06.980519Z","steps":["trace[268266529] 'process raft request' (duration: 311.382212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:05:06.980809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:05:06.668847Z","time spent":"311.835682ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:06:35.780087Z","caller":"traceutil/trace.go:171","msg":"trace[1372073562] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"154.445879ms","start":"2026-10-26T01:06:35.625609Z","end":"2026-10-26T01:06:35.780055Z","steps":["trace[1372073562] 'process raft request' (duration: 154.260054ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:06:35.887054Z","caller":"traceutil/trace.go:171","msg":"trace[1807868310] transaction","detail":"{read_only:false; response_revision:2902; number_of_response:1; }","duration":"102.051283ms","start":"2026-10-26T01:06:35.784973Z","end":"2026-10-26T01:06:35.887024Z","steps":["trace[1807868310] 'process raft request' (duration: 101.92915ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:06:35.887068Z","caller":"traceutil/trace.go:171","msg":"trace[1334660231] transaction","detail":"{read_only:false; response_revision:2903; number_of_response:1; }","duration":"101.94823ms","start":"2026-10-26T01:06:35.785091Z","end":"2026-10-26T01:06:35.887039Z","steps":["trace[1334660231] 'process raft request' (duration: 101.863978ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:06:35.887352Z","caller":"traceutil/trace.go:171","msg":"trace[921668631] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"102.472133ms","start":"2026-10-26T01:06:35.784848Z","end":"2026-10-26T01:06:35.88732Z","steps":["trace[921668631] 'process raft request' (duration: 56.797253ms)","trace[921668631] 'compare' (duration: 45.119016ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:06:36.398274Z","caller":"traceutil/trace.go:171","msg":"trace[1037461817] linearizableReadLoop","detail":"{readStateIndex:3016; appliedIndex:3015; }","duration":"149.995689ms","start":"2026-10-26T01:06:36.248254Z","end":"2026-10-26T01:06:36.39825Z","steps":["trace[1037461817] 'read index received' (duration: 149.840516ms)","trace[1037461817] 'applied index is now lower than readState.Index' (duration: 154.123µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:06:36.398367Z","caller":"traceutil/trace.go:171","msg":"trace[1746682649] transaction","detail":"{read_only:false; response_revision:2904; number_of_response:1; }","duration":"165.939611ms","start":"2026-10-26T01:06:36.232413Z","end":"2026-10-26T01:06:36.398352Z","steps":["trace[1746682649] 'process raft request' (duration: 165.663654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:06:36.398438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.163393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T01:06:36.398514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.057579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-26T01:06:36.398578Z","caller":"traceutil/trace.go:171","msg":"trace[1502004066] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2904; }","duration":"112.139591ms","start":"2026-10-26T01:06:36.286427Z","end":"2026-10-26T01:06:36.398566Z","steps":["trace[1502004066] 'agreement among raft nodes before linearized reading' (duration: 111.960767ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:06:36.398511Z","caller":"traceutil/trace.go:171","msg":"trace[71082708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2904; }","duration":"150.266796ms","start":"2026-10-26T01:06:36.248226Z","end":"2026-10-26T01:06:36.398493Z","steps":["trace[71082708] 'agreement among raft nodes before linearized reading' (duration: 150.148203ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:09:53.265551Z","caller":"traceutil/trace.go:171","msg":"trace[2057500878] linearizableReadLoop","detail":"{readStateIndex:4298; appliedIndex:4297; }","duration":"343.598441ms","start":"2026-10-26T01:09:52.92193Z","end":"2026-10-26T01:09:53.265528Z","steps":["trace[2057500878] 'read index received' (duration: 343.455538ms)","trace[2057500878] 'applied index is now lower than readState.Index' (duration: 142.223µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:09:53.26558Z","caller":"traceutil/trace.go:171","msg":"trace[1342118370] transaction","detail":"{read_only:false; response_revision:4140; number_of_response:1; }","duration":"344.449192ms","start":"2026-10-26T01:09:52.921112Z","end":"2026-10-26T01:09:53.265561Z","steps":["trace[1342118370] 'process raft request' (duration: 344.312979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:09:53.265714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.763955ms","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-26T01:09:53.265728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:09:52.921094Z","time spent":"344.546435ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:09:53.265741Z","caller":"traceutil/trace.go:171","msg":"trace[1229520228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4140; }","duration":"343.825217ms","start":"2026-10-26T01:09:52.921909Z","end":"2026-10-26T01:09:53.265734Z","steps":["trace[1229520228] 'agreement among raft nodes before linearized reading' (duration: 343.709804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:09:53.265766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:09:52.921899Z","time spent":"343.862078ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T01:09:53.294283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.175636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-26T01:09:53.294336Z","caller":"traceutil/trace.go:171","msg":"trace[1007024265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4141; }","duration":"371.260707ms","start":"2026-10-26T01:09:52.923064Z","end":"2026-10-26T01:09:53.294324Z","steps":["trace[1007024265] 'agreement among raft nodes before linearized reading' (duration: 371.077773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:09:53.294359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:09:52.923053Z","time spent":"371.301849ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T01:09:53.294505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.321454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T01:09:53.294523Z","caller":"traceutil/trace.go:171","msg":"trace[1726850186] range","detail":"{range_begin:/registry/masterleases/162.253.55.113; range_end:; response_count:1; response_revision:4141; }","duration":"118.374075ms","start":"2026-10-26T01:09:53.176145Z","end":"2026-10-26T01:09:53.294519Z","steps":["trace[1726850186] 'agreement among raft nodes before linearized reading' (duration: 118.324074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:09:53.294768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.970378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-24946\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T01:09:53.294831Z","caller":"traceutil/trace.go:171","msg":"trace[976432537] range","detail":"{range_begin:/registry/pods/openstack/keepalived-24946; range_end:; response_count:1; response_revision:4141; }","duration":"303.0812ms","start":"2026-10-26T01:09:52.991736Z","end":"2026-10-26T01:09:53.294817Z","steps":["trace[976432537] 'agreement among raft nodes before linearized reading' (duration: 302.829074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:09:53.294854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:09:52.991723Z","time spent":"303.125051ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-24946\" "} {"level":"warn","ts":"2026-10-26T01:09:53.294897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.112624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T01:09:53.294941Z","caller":"traceutil/trace.go:171","msg":"trace[111257501] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4141; }","duration":"144.189786ms","start":"2026-10-26T01:09:53.150738Z","end":"2026-10-26T01:09:53.294928Z","steps":["trace[111257501] 'agreement among raft nodes before linearized reading' (duration: 144.065513ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:17.228303Z","caller":"traceutil/trace.go:171","msg":"trace[284263449] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"119.965224ms","start":"2026-10-26T01:11:17.108314Z","end":"2026-10-26T01:11:17.228279Z","steps":["trace[284263449] 'process raft request' (duration: 119.839631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:11:17.228786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.358924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-26T01:11:17.228828Z","caller":"traceutil/trace.go:171","msg":"trace[1071201909] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5170; }","duration":"120.467916ms","start":"2026-10-26T01:11:17.108349Z","end":"2026-10-26T01:11:17.228817Z","steps":["trace[1071201909] 'agreement among raft nodes before linearized reading' (duration: 120.298232ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:17.228597Z","caller":"traceutil/trace.go:171","msg":"trace[267295353] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5366; }","duration":"120.162249ms","start":"2026-10-26T01:11:17.108403Z","end":"2026-10-26T01:11:17.228565Z","steps":["trace[267295353] 'read index received' (duration: 120.088957ms)","trace[267295353] 'applied index is now lower than readState.Index' (duration: 71.592µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:11:18.505481Z","caller":"traceutil/trace.go:171","msg":"trace[1991150601] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"177.334527ms","start":"2026-10-26T01:11:18.32813Z","end":"2026-10-26T01:11:18.505464Z","steps":["trace[1991150601] 'process raft request' (duration: 177.176343ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:21.134268Z","caller":"traceutil/trace.go:171","msg":"trace[79561678] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"124.244459ms","start":"2026-10-26T01:11:21.010002Z","end":"2026-10-26T01:11:21.134246Z","steps":["trace[79561678] 'process raft request' (duration: 124.082425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:11:23.292848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.959007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:11:23.292947Z","caller":"traceutil/trace.go:171","msg":"trace[1705867589] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5366; }","duration":"114.109291ms","start":"2026-10-26T01:11:23.178816Z","end":"2026-10-26T01:11:23.292925Z","steps":["trace[1705867589] 'count revisions from in-memory index tree' (duration: 113.835534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:11:23.292955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.782199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T01:11:23.29304Z","caller":"traceutil/trace.go:171","msg":"trace[1361941514] range","detail":"{range_begin:/registry/masterleases/162.253.55.113; range_end:; response_count:1; response_revision:5366; }","duration":"110.906742ms","start":"2026-10-26T01:11:23.182115Z","end":"2026-10-26T01:11:23.293022Z","steps":["trace[1361941514] 'range keys from in-memory index tree' (duration: 110.568824ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:36.460876Z","caller":"traceutil/trace.go:171","msg":"trace[2089517911] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"102.103757ms","start":"2026-10-26T01:11:36.358739Z","end":"2026-10-26T01:11:36.460842Z","steps":["trace[2089517911] 'process raft request' (duration: 23.209277ms)","trace[2089517911] 'compare' (duration: 78.668224ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:11:44.798498Z","caller":"traceutil/trace.go:171","msg":"trace[1961044868] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"124.091295ms","start":"2026-10-26T01:11:44.674367Z","end":"2026-10-26T01:11:44.798459Z","steps":["trace[1961044868] 'process raft request' (duration: 123.831528ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:44.865606Z","caller":"traceutil/trace.go:171","msg":"trace[1030353717] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"155.437591ms","start":"2026-10-26T01:11:44.710141Z","end":"2026-10-26T01:11:44.865579Z","steps":["trace[1030353717] 'process raft request' (duration: 155.271607ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:44.865786Z","caller":"traceutil/trace.go:171","msg":"trace[1276059363] linearizableReadLoop","detail":"{readStateIndex:5989; appliedIndex:5987; }","duration":"148.580993ms","start":"2026-10-26T01:11:44.717166Z","end":"2026-10-26T01:11:44.865747Z","steps":["trace[1276059363] 'read index received' (duration: 81.000481ms)","trace[1276059363] 'applied index is now lower than readState.Index' (duration: 67.577302ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:11:44.866068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.888341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T01:11:44.866129Z","caller":"traceutil/trace.go:171","msg":"trace[62231019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5774; }","duration":"149.003794ms","start":"2026-10-26T01:11:44.717107Z","end":"2026-10-26T01:11:44.86611Z","steps":["trace[62231019] 'agreement among raft nodes before linearized reading' (duration: 148.789759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:11:45.070868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.294959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:11:45.071055Z","caller":"traceutil/trace.go:171","msg":"trace[355258958] linearizableReadLoop","detail":"{readStateIndex:5992; appliedIndex:5990; }","duration":"125.710114ms","start":"2026-10-26T01:11:44.945329Z","end":"2026-10-26T01:11:45.071039Z","steps":["trace[355258958] 'read index received' (duration: 12.315401ms)","trace[355258958] 'applied index is now lower than readState.Index' (duration: 113.393693ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:11:45.07107Z","caller":"traceutil/trace.go:171","msg":"trace[1790975008] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"201.089538ms","start":"2026-10-26T01:11:44.869942Z","end":"2026-10-26T01:11:45.071032Z","steps":["trace[1790975008] 'process raft request' (duration: 56.390599ms)","trace[1790975008] 'compare' (duration: 143.571711ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:11:45.071201Z","caller":"traceutil/trace.go:171","msg":"trace[2040181421] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"194.647831ms","start":"2026-10-26T01:11:44.876541Z","end":"2026-10-26T01:11:45.071189Z","steps":["trace[2040181421] 'process raft request' (duration: 194.426395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:11:45.071318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.982051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:11:45.07137Z","caller":"traceutil/trace.go:171","msg":"trace[1250815179] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5776; }","duration":"126.070903ms","start":"2026-10-26T01:11:44.945289Z","end":"2026-10-26T01:11:45.07136Z","steps":["trace[1250815179] 'agreement among raft nodes before linearized reading' (duration: 125.997881ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:49.801612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-10-26T01:11:49.878162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"75.107887ms","hash":4055612463,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":32272384,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T01:11:49.878342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4055612463,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-10-26T01:16:49.809419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5875} {"level":"info","ts":"2026-10-26T01:16:49.906245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5875,"took":"94.580104ms","hash":1290411549,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":26832896,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T01:16:49.906318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1290411549,"revision":5875,"compact-revision":2978} {"level":"info","ts":"2026-10-26T01:18:15.303549Z","caller":"traceutil/trace.go:171","msg":"trace[1218863006] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"300.506049ms","start":"2026-10-26T01:18:15.003004Z","end":"2026-10-26T01:18:15.30351Z","steps":["trace[1218863006] 'process raft request' (duration: 300.167441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:18:15.30377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:18:15.002981Z","time spent":"300.676653ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:18:16.123815Z","caller":"traceutil/trace.go:171","msg":"trace[15010744] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"110.607654ms","start":"2026-10-26T01:18:16.013172Z","end":"2026-10-26T01:18:16.12378Z","steps":["trace[15010744] 'process raft request' (duration: 110.404909ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:16.123644Z","caller":"traceutil/trace.go:171","msg":"trace[1423369522] transaction","detail":"{read_only:false; response_revision:8743; number_of_response:1; }","duration":"111.073305ms","start":"2026-10-26T01:18:16.012543Z","end":"2026-10-26T01:18:16.123616Z","steps":["trace[1423369522] 'process raft request' (duration: 110.8941ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:27.40292Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a6.wal"} {"level":"info","ts":"2026-10-26T01:18:45.612201Z","caller":"traceutil/trace.go:171","msg":"trace[1237587988] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"295.298892ms","start":"2026-10-26T01:18:45.316865Z","end":"2026-10-26T01:18:45.612164Z","steps":["trace[1237587988] 'process raft request' (duration: 294.8283ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:45.611902Z","caller":"traceutil/trace.go:171","msg":"trace[2128113594] linearizableReadLoop","detail":"{readStateIndex:9275; appliedIndex:9274; }","duration":"196.871976ms","start":"2026-10-26T01:18:45.414989Z","end":"2026-10-26T01:18:45.611861Z","steps":["trace[2128113594] 'read index received' (duration: 196.612909ms)","trace[2128113594] 'applied index is now lower than readState.Index' (duration: 257.927µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:18:45.612556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.523901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T01:18:45.612597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.215525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T01:18:45.612654Z","caller":"traceutil/trace.go:171","msg":"trace[237613414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8954; }","duration":"126.321176ms","start":"2026-10-26T01:18:45.486319Z","end":"2026-10-26T01:18:45.61264Z","steps":["trace[237613414] 'agreement among raft nodes before linearized reading' (duration: 126.105761ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:45.61265Z","caller":"traceutil/trace.go:171","msg":"trace[324098619] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8954; }","duration":"197.684135ms","start":"2026-10-26T01:18:45.414945Z","end":"2026-10-26T01:18:45.612629Z","steps":["trace[324098619] 'agreement among raft nodes before linearized reading' (duration: 197.439879ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:45.88132Z","caller":"traceutil/trace.go:171","msg":"trace[600489771] linearizableReadLoop","detail":"{readStateIndex:9276; appliedIndex:9275; }","duration":"238.494058ms","start":"2026-10-26T01:18:45.642784Z","end":"2026-10-26T01:18:45.881278Z","steps":["trace[600489771] 'read index received' (duration: 209.887041ms)","trace[600489771] 'applied index is now lower than readState.Index' (duration: 28.605347ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:18:45.881415Z","caller":"traceutil/trace.go:171","msg":"trace[1948492071] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"263.894487ms","start":"2026-10-26T01:18:45.617495Z","end":"2026-10-26T01:18:45.881389Z","steps":["trace[1948492071] 'process raft request' (duration: 235.348312ms)","trace[1948492071] 'compare' (duration: 27.609922ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:18:45.881534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.719624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T01:18:45.881587Z","caller":"traceutil/trace.go:171","msg":"trace[52532223] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8955; }","duration":"238.815346ms","start":"2026-10-26T01:18:45.642759Z","end":"2026-10-26T01:18:45.881574Z","steps":["trace[52532223] 'agreement among raft nodes before linearized reading' (duration: 238.667483ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:54.756467Z","caller":"traceutil/trace.go:171","msg":"trace[1793967851] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"105.1024ms","start":"2026-10-26T01:18:54.651337Z","end":"2026-10-26T01:18:54.75644Z","steps":["trace[1793967851] 'process raft request' (duration: 104.919435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:18:59.19886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.76364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:18:59.199221Z","caller":"traceutil/trace.go:171","msg":"trace[185974434] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9034; }","duration":"267.27118ms","start":"2026-10-26T01:18:58.931917Z","end":"2026-10-26T01:18:59.199188Z","steps":["trace[185974434] 'process raft request' (duration: 97.913545ms)","trace[185974434] 'compare' (duration: 168.292839ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:18:59.264816Z","caller":"traceutil/trace.go:171","msg":"trace[1258627945] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"332.789955ms","start":"2026-10-26T01:18:58.931996Z","end":"2026-10-26T01:18:59.264786Z","steps":["trace[1258627945] 'process raft request' (duration: 332.59792ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:59.264961Z","caller":"traceutil/trace.go:171","msg":"trace[1339648935] linearizableReadLoop","detail":"{readStateIndex:9360; appliedIndex:9358; }","duration":"159.375131ms","start":"2026-10-26T01:18:59.105549Z","end":"2026-10-26T01:18:59.264924Z","steps":["trace[1339648935] 'read index received' (duration: 158.983572ms)","trace[1339648935] 'applied index is now lower than readState.Index' (duration: 387.769µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:18:59.264995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:18:58.931978Z","time spent":"332.921078ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4719,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:18:59.2652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.653558ms","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-26T01:18:59.265267Z","caller":"traceutil/trace.go:171","msg":"trace[1386326746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9035; }","duration":"159.776992ms","start":"2026-10-26T01:18:59.105478Z","end":"2026-10-26T01:18:59.265255Z","steps":["trace[1386326746] 'agreement among raft nodes before linearized reading' (duration: 159.540496ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:59.265715Z","caller":"traceutil/trace.go:171","msg":"trace[392004657] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9035; }","duration":"333.126763ms","start":"2026-10-26T01:18:58.932113Z","end":"2026-10-26T01:18:59.26524Z","steps":["trace[392004657] 'process raft request' (duration: 332.635531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:18:59.265988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:18:58.932088Z","time spent":"333.724108ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4768,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:18:59.547917Z","caller":"traceutil/trace.go:171","msg":"trace[1604086400] transaction","detail":"{read_only:false; response_revision:9036; number_of_response:1; }","duration":"276.569576ms","start":"2026-10-26T01:18:59.271314Z","end":"2026-10-26T01:18:59.547884Z","steps":["trace[1604086400] 'process raft request' (duration: 276.368451ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:59.596291Z","caller":"traceutil/trace.go:171","msg":"trace[1893395649] transaction","detail":"{read_only:false; response_revision:9037; number_of_response:1; }","duration":"324.911603ms","start":"2026-10-26T01:18:59.271343Z","end":"2026-10-26T01:18:59.596255Z","steps":["trace[1893395649] 'process raft request' (duration: 324.615336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:18:59.596475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:18:59.271288Z","time spent":"325.088547ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:18:59.811691Z","caller":"traceutil/trace.go:171","msg":"trace[2084476417] transaction","detail":"{read_only:false; response_revision:9039; number_of_response:1; }","duration":"205.587457ms","start":"2026-10-26T01:18:59.606069Z","end":"2026-10-26T01:18:59.811656Z","steps":["trace[2084476417] 'process raft request' (duration: 205.414323ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:19:59.32553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:19:59.333999Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T01:19:59.334109Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T01:20:12.870724Z","caller":"traceutil/trace.go:171","msg":"trace[1238800431] transaction","detail":"{read_only:false; response_revision:9798; number_of_response:1; }","duration":"156.627515ms","start":"2026-10-26T01:20:12.714034Z","end":"2026-10-26T01:20:12.870661Z","steps":["trace[1238800431] 'process raft request' (duration: 101.642926ms)","trace[1238800431] 'compare' (duration: 54.828945ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:20:12.870803Z","caller":"traceutil/trace.go:171","msg":"trace[2131469268] linearizableReadLoop","detail":"{readStateIndex:10146; appliedIndex:10145; }","duration":"124.866061ms","start":"2026-10-26T01:20:12.745889Z","end":"2026-10-26T01:20:12.870755Z","steps":["trace[2131469268] 'read index received' (duration: 69.80441ms)","trace[2131469268] 'applied index is now lower than readState.Index' (duration: 55.057201ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:20:12.871075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.161048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-545686557b-965hb.18e1ef8309116cb4\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-26T01:20:12.871178Z","caller":"traceutil/trace.go:171","msg":"trace[415985563] range","detail":"{range_begin:/registry/events/openstack/barbican-api-545686557b-965hb.18e1ef8309116cb4; range_end:; response_count:1; response_revision:9800; }","duration":"125.292852ms","start":"2026-10-26T01:20:12.745856Z","end":"2026-10-26T01:20:12.871149Z","steps":["trace[415985563] 'agreement among raft nodes before linearized reading' (duration: 125.012565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:20:26.772268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.713905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:20:26.772872Z","caller":"traceutil/trace.go:171","msg":"trace[894121994] transaction","detail":"{read_only:false; response_revision:9876; number_of_response:1; }","duration":"165.511271ms","start":"2026-10-26T01:20:26.607333Z","end":"2026-10-26T01:20:26.772844Z","steps":["trace[894121994] 'process raft request' (duration: 36.094439ms)","trace[894121994] 'compare' (duration: 128.591592ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:21:49.817555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8055} {"level":"info","ts":"2026-10-26T01:21:49.900759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8055,"took":"81.048081ms","hash":2498405695,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":28282880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T01:21:49.900849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2498405695,"revision":8055,"compact-revision":5875} {"level":"info","ts":"2026-10-26T01:26:09.478989Z","caller":"traceutil/trace.go:171","msg":"trace[1564345013] transaction","detail":"{read_only:false; response_revision:12332; number_of_response:1; }","duration":"145.662106ms","start":"2026-10-26T01:26:09.333304Z","end":"2026-10-26T01:26:09.478966Z","steps":["trace[1564345013] 'process raft request' (duration: 145.526543ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:26:49.825259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10452} {"level":"info","ts":"2026-10-26T01:26:49.917773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10452,"took":"90.285601ms","hash":3727916942,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":25915392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T01:26:49.917845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3727916942,"revision":10452,"compact-revision":8055} {"level":"info","ts":"2026-10-26T01:31:49.831771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12616} {"level":"info","ts":"2026-10-26T01:31:49.913356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12616,"took":"79.336664ms","hash":3454674142,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":27873280,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T01:31:49.913421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3454674142,"revision":12616,"compact-revision":10452} {"level":"warn","ts":"2026-10-26T01:32:30.140089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.134396ms","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-26T01:32:30.140179Z","caller":"traceutil/trace.go:171","msg":"trace[1867179829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14885; }","duration":"123.27434ms","start":"2026-10-26T01:32:30.016884Z","end":"2026-10-26T01:32:30.140159Z","steps":["trace[1867179829] 'range keys from in-memory index tree' (duration: 122.936501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:30.359423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.412266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:32:30.359509Z","caller":"traceutil/trace.go:171","msg":"trace[924761033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14886; }","duration":"112.57553ms","start":"2026-10-26T01:32:30.246912Z","end":"2026-10-26T01:32:30.359488Z","steps":["trace[924761033] 'range keys from in-memory index tree' (duration: 112.284783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:38.92835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.858793ms","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-26T01:32:38.928438Z","caller":"traceutil/trace.go:171","msg":"trace[911629275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14930; }","duration":"144.999677ms","start":"2026-10-26T01:32:38.783417Z","end":"2026-10-26T01:32:38.928417Z","steps":["trace[911629275] 'range keys from in-memory index tree' (duration: 144.642278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:38.928466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.969325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-26T01:32:38.928511Z","caller":"traceutil/trace.go:171","msg":"trace[686553166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14930; }","duration":"145.043018ms","start":"2026-10-26T01:32:38.783454Z","end":"2026-10-26T01:32:38.928497Z","steps":["trace[686553166] 'range keys from in-memory index tree' (duration: 144.831912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:48.483737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.021113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:32:48.483913Z","caller":"traceutil/trace.go:171","msg":"trace[1955287980] linearizableReadLoop","detail":"{readStateIndex:15519; appliedIndex:15517; }","duration":"580.175779ms","start":"2026-10-26T01:32:47.903723Z","end":"2026-10-26T01:32:48.483899Z","steps":["trace[1955287980] 'read index received' (duration: 162.951982ms)","trace[1955287980] 'applied index is now lower than readState.Index' (duration: 417.222897ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:32:48.484006Z","caller":"traceutil/trace.go:171","msg":"trace[1957473322] transaction","detail":"{read_only:false; response_revision:14993; number_of_response:1; }","duration":"615.082226ms","start":"2026-10-26T01:32:47.868914Z","end":"2026-10-26T01:32:48.483997Z","steps":["trace[1957473322] 'process raft request' (duration: 614.935572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:48.484066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:32:47.868889Z","time spent":"615.143958ms","remote":"127.0.0.1:38924","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-26T01:32:48.484086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.656441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:32:48.484132Z","caller":"traceutil/trace.go:171","msg":"trace[1129809914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14993; }","duration":"234.738063ms","start":"2026-10-26T01:32:48.24938Z","end":"2026-10-26T01:32:48.484118Z","steps":["trace[1129809914] 'agreement among raft nodes before linearized reading' (duration: 234.671091ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:32:48.484Z","caller":"traceutil/trace.go:171","msg":"trace[1825856868] transaction","detail":"{read_only:false; response_revision:14992; number_of_response:1; }","duration":"665.810226ms","start":"2026-10-26T01:32:47.818098Z","end":"2026-10-26T01:32:48.483908Z","steps":["trace[1825856868] 'process raft request' (duration: 248.522766ms)","trace[1825856868] 'compare' (duration: 416.928751ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:32:48.484352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.62788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-tlxmt\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-10-26T01:32:48.484385Z","caller":"traceutil/trace.go:171","msg":"trace[466982894] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-tlxmt; range_end:; response_count:1; response_revision:14993; }","duration":"580.704082ms","start":"2026-10-26T01:32:47.903672Z","end":"2026-10-26T01:32:48.484376Z","steps":["trace[466982894] 'agreement among raft nodes before linearized reading' (duration: 580.60043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:48.484408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:32:47.903651Z","time spent":"580.751293ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12387,"request content":"key:\"/registry/pods/openstack/openvswitch-tlxmt\" "} {"level":"warn","ts":"2026-10-26T01:32:48.484407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.073792ms","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-26T01:32:48.484439Z","caller":"traceutil/trace.go:171","msg":"trace[1455899595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14993; }","duration":"233.124723ms","start":"2026-10-26T01:32:48.251303Z","end":"2026-10-26T01:32:48.484428Z","steps":["trace[1455899595] 'agreement among raft nodes before linearized reading' (duration: 233.036621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:48.484365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:32:47.818081Z","time spent":"666.179244ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:32:59.030853Z","caller":"traceutil/trace.go:171","msg":"trace[920378485] transaction","detail":"{read_only:false; response_revision:15169; number_of_response:1; }","duration":"160.428661ms","start":"2026-10-26T01:32:58.870383Z","end":"2026-10-26T01:32:59.030811Z","steps":["trace[920378485] 'process raft request' (duration: 141.740838ms)","trace[920378485] 'compare' (duration: 18.472668ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:32:59.430476Z","caller":"traceutil/trace.go:171","msg":"trace[140583012] linearizableReadLoop","detail":"{readStateIndex:15700; appliedIndex:15699; }","duration":"390.794837ms","start":"2026-10-26T01:32:59.039651Z","end":"2026-10-26T01:32:59.430446Z","steps":["trace[140583012] 'read index received' (duration: 390.642383ms)","trace[140583012] 'applied index is now lower than readState.Index' (duration: 151.134µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:32:59.430715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.75906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T01:32:59.430822Z","caller":"traceutil/trace.go:171","msg":"trace[952235603] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15170; }","duration":"297.904124ms","start":"2026-10-26T01:32:59.132899Z","end":"2026-10-26T01:32:59.430803Z","steps":["trace[952235603] 'agreement among raft nodes before linearized reading' (duration: 297.72294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:59.430811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.095364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T01:32:59.43086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.694517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T01:32:59.430935Z","caller":"traceutil/trace.go:171","msg":"trace[1076963012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15170; }","duration":"386.79479ms","start":"2026-10-26T01:32:59.044102Z","end":"2026-10-26T01:32:59.430896Z","steps":["trace[1076963012] 'agreement among raft nodes before linearized reading' (duration: 386.496862ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:32:59.430939Z","caller":"traceutil/trace.go:171","msg":"trace[853620595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15170; }","duration":"391.242497ms","start":"2026-10-26T01:32:59.039637Z","end":"2026-10-26T01:32:59.430879Z","steps":["trace[853620595] 'agreement among raft nodes before linearized reading' (duration: 390.95183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:59.430959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.587225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-c4pvl.18e1f055b6fb2654\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-10-26T01:32:59.430999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:32:59.03962Z","time spent":"391.363171ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-26T01:32:59.431068Z","caller":"traceutil/trace.go:171","msg":"trace[125174653] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-c4pvl.18e1f055b6fb2654; range_end:; response_count:1; response_revision:15170; }","duration":"299.733978ms","start":"2026-10-26T01:32:59.131307Z","end":"2026-10-26T01:32:59.431041Z","steps":["trace[125174653] 'agreement among raft nodes before linearized reading' (duration: 299.41242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:59.430961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.001325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4be270b4-dc08-4ad1-8255-5bce7940aeb5\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-10-26T01:32:59.431154Z","caller":"traceutil/trace.go:171","msg":"trace[329945066] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4be270b4-dc08-4ad1-8255-5bce7940aeb5; range_end:; response_count:1; response_revision:15170; }","duration":"347.22775ms","start":"2026-10-26T01:32:59.083896Z","end":"2026-10-26T01:32:59.431124Z","steps":["trace[329945066] 'agreement among raft nodes before linearized reading' (duration: 346.841741ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:32:59.431198Z","caller":"traceutil/trace.go:171","msg":"trace[1926225520] transaction","detail":"{read_only:false; response_revision:15170; number_of_response:1; }","duration":"397.215893ms","start":"2026-10-26T01:32:59.033311Z","end":"2026-10-26T01:32:59.430527Z","steps":["trace[1926225520] 'process raft request' (duration: 396.971537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:32:59.431229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:32:59.083877Z","time spent":"347.331352ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3630,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4be270b4-dc08-4ad1-8255-5bce7940aeb5\" "} {"level":"warn","ts":"2026-10-26T01:32:59.431328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:32:59.03329Z","time spent":"397.95699ms","remote":"127.0.0.1:38748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:32:59.43098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:32:59.044098Z","time spent":"386.870691ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T01:34:25.041525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.532279ms","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-26T01:34:25.041607Z","caller":"traceutil/trace.go:171","msg":"trace[335552668] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16190; }","duration":"108.669973ms","start":"2026-10-26T01:34:24.93292Z","end":"2026-10-26T01:34:25.04159Z","steps":["trace[335552668] 'agreement among raft nodes before linearized reading' (duration: 108.392896ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:30.358445Z","caller":"traceutil/trace.go:171","msg":"trace[764055430] transaction","detail":"{read_only:false; response_revision:16216; number_of_response:1; }","duration":"267.941172ms","start":"2026-10-26T01:34:30.090478Z","end":"2026-10-26T01:34:30.358419Z","steps":["trace[764055430] 'process raft request' (duration: 267.801889ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:30.358599Z","caller":"traceutil/trace.go:171","msg":"trace[1999586919] linearizableReadLoop","detail":"{readStateIndex:16774; appliedIndex:16774; }","duration":"255.494311ms","start":"2026-10-26T01:34:30.103079Z","end":"2026-10-26T01:34:30.358574Z","steps":["trace[1999586919] 'read index received' (duration: 255.48233ms)","trace[1999586919] 'applied index is now lower than readState.Index' (duration: 9.841µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:34:30.358839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.722066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:34:30.358937Z","caller":"traceutil/trace.go:171","msg":"trace[1683026648] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16216; }","duration":"255.87719ms","start":"2026-10-26T01:34:30.103037Z","end":"2026-10-26T01:34:30.358915Z","steps":["trace[1683026648] 'agreement among raft nodes before linearized reading' (duration: 255.675495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.37547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.662312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:34:30.375581Z","caller":"traceutil/trace.go:171","msg":"trace[1403382017] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16217; }","duration":"271.828316ms","start":"2026-10-26T01:34:30.103731Z","end":"2026-10-26T01:34:30.37556Z","steps":["trace[1403382017] 'agreement among raft nodes before linearized reading' (duration: 271.655252ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:30.375897Z","caller":"traceutil/trace.go:171","msg":"trace[1508871261] transaction","detail":"{read_only:false; response_revision:16217; number_of_response:1; }","duration":"151.004198ms","start":"2026-10-26T01:34:30.224881Z","end":"2026-10-26T01:34:30.375885Z","steps":["trace[1508871261] 'process raft request' (duration: 150.347322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.376133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.197379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T01:34:30.376167Z","caller":"traceutil/trace.go:171","msg":"trace[237024316] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16217; }","duration":"105.25053ms","start":"2026-10-26T01:34:30.270908Z","end":"2026-10-26T01:34:30.376159Z","steps":["trace[237024316] 'agreement among raft nodes before linearized reading' (duration: 105.122467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.376355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.926201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-10-26T01:34:30.376387Z","caller":"traceutil/trace.go:171","msg":"trace[991511967] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16217; }","duration":"106.980832ms","start":"2026-10-26T01:34:30.269399Z","end":"2026-10-26T01:34:30.37638Z","steps":["trace[991511967] 'agreement among raft nodes before linearized reading' (duration: 106.88678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.376586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.4199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T01:34:30.376627Z","caller":"traceutil/trace.go:171","msg":"trace[267697770] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16217; }","duration":"267.481431ms","start":"2026-10-26T01:34:30.109136Z","end":"2026-10-26T01:34:30.376617Z","steps":["trace[267697770] 'agreement among raft nodes before linearized reading' (duration: 267.387819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.376804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.883121ms","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-26T01:34:30.376839Z","caller":"traceutil/trace.go:171","msg":"trace[2095276511] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16217; }","duration":"272.933283ms","start":"2026-10-26T01:34:30.103898Z","end":"2026-10-26T01:34:30.376831Z","steps":["trace[2095276511] 'agreement among raft nodes before linearized reading' (duration: 272.880572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.376764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.370322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T01:34:30.376953Z","caller":"traceutil/trace.go:171","msg":"trace[646793966] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:16217; }","duration":"204.70976ms","start":"2026-10-26T01:34:30.172213Z","end":"2026-10-26T01:34:30.376923Z","steps":["trace[646793966] 'agreement among raft nodes before linearized reading' (duration: 204.244659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.377703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.385655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:34:30.377748Z","caller":"traceutil/trace.go:171","msg":"trace[1863683775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16217; }","duration":"129.451767ms","start":"2026-10-26T01:34:30.248285Z","end":"2026-10-26T01:34:30.377737Z","steps":["trace[1863683775] 'agreement among raft nodes before linearized reading' (duration: 129.338505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.377764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.38131ms","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-26T01:34:30.377855Z","caller":"traceutil/trace.go:171","msg":"trace[487524914] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16217; }","duration":"109.513054ms","start":"2026-10-26T01:34:30.268326Z","end":"2026-10-26T01:34:30.377839Z","steps":["trace[487524914] 'agreement among raft nodes before linearized reading' (duration: 109.382881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.377918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.554904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:34:30.377947Z","caller":"traceutil/trace.go:171","msg":"trace[533365975] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16217; }","duration":"109.602706ms","start":"2026-10-26T01:34:30.268336Z","end":"2026-10-26T01:34:30.377939Z","steps":["trace[533365975] 'agreement among raft nodes before linearized reading' (duration: 109.552235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.377699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.275513ms","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-26T01:34:30.378007Z","caller":"traceutil/trace.go:171","msg":"trace[343984831] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16217; }","duration":"108.595251ms","start":"2026-10-26T01:34:30.269386Z","end":"2026-10-26T01:34:30.377982Z","steps":["trace[343984831] 'agreement among raft nodes before linearized reading' (duration: 108.13337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:30.379643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.177377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T01:34:30.379741Z","caller":"traceutil/trace.go:171","msg":"trace[330655328] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16217; }","duration":"159.32352ms","start":"2026-10-26T01:34:30.2204Z","end":"2026-10-26T01:34:30.379724Z","steps":["trace[330655328] 'agreement among raft nodes before linearized reading' (duration: 157.328482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.407291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.286383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:34:31.407383Z","caller":"traceutil/trace.go:171","msg":"trace[1789037037] linearizableReadLoop","detail":"{readStateIndex:16777; appliedIndex:16776; }","duration":"326.000208ms","start":"2026-10-26T01:34:31.081367Z","end":"2026-10-26T01:34:31.407367Z","steps":["trace[1789037037] 'read index received' (duration: 191.54576ms)","trace[1789037037] 'applied index is now lower than readState.Index' (duration: 134.453398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:34:31.407538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.200153ms","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-26T01:34:31.407567Z","caller":"traceutil/trace.go:171","msg":"trace[1438341576] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16219; }","duration":"352.270374ms","start":"2026-10-26T01:34:31.055288Z","end":"2026-10-26T01:34:31.407559Z","steps":["trace[1438341576] 'agreement among raft nodes before linearized reading' (duration: 352.162252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.407554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.588738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-26T01:34:31.407592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:34:31.055267Z","time spent":"352.317336ms","remote":"127.0.0.1:38830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-10-26T01:34:31.407612Z","caller":"traceutil/trace.go:171","msg":"trace[1952413039] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16219; }","duration":"351.68926ms","start":"2026-10-26T01:34:31.055907Z","end":"2026-10-26T01:34:31.407596Z","steps":["trace[1952413039] 'agreement among raft nodes before linearized reading' (duration: 351.519186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.407651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:34:31.055887Z","time spent":"351.754362ms","remote":"127.0.0.1:38830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-26T01:34:31.407968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.300171ms","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-26T01:34:31.408009Z","caller":"traceutil/trace.go:171","msg":"trace[1185190813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16219; }","duration":"280.382293ms","start":"2026-10-26T01:34:31.127612Z","end":"2026-10-26T01:34:31.407995Z","steps":["trace[1185190813] 'agreement among raft nodes before linearized reading' (duration: 280.085276ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:31.40798Z","caller":"traceutil/trace.go:171","msg":"trace[740605035] transaction","detail":"{read_only:false; response_revision:16219; number_of_response:1; }","duration":"374.701158ms","start":"2026-10-26T01:34:31.033213Z","end":"2026-10-26T01:34:31.407914Z","steps":["trace[740605035] 'process raft request' (duration: 239.692567ms)","trace[740605035] 'compare' (duration: 134.094739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:34:31.408051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.557844ms","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-26T01:34:31.408107Z","caller":"traceutil/trace.go:171","msg":"trace[1949114235] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16219; }","duration":"187.666756ms","start":"2026-10-26T01:34:31.220425Z","end":"2026-10-26T01:34:31.408091Z","steps":["trace[1949114235] 'agreement among raft nodes before linearized reading' (duration: 187.293157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.408081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.588983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-26T01:34:31.408133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.797556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-26T01:34:31.408217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:34:31.033195Z","time spent":"374.885072ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:34:31.408229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.313517ms","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-26T01:34:31.408236Z","caller":"traceutil/trace.go:171","msg":"trace[190408161] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16219; }","duration":"250.937759ms","start":"2026-10-26T01:34:31.15727Z","end":"2026-10-26T01:34:31.408208Z","steps":["trace[190408161] 'agreement among raft nodes before linearized reading' (duration: 250.433467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.408268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.111664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T01:34:31.408313Z","caller":"traceutil/trace.go:171","msg":"trace[620700920] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16219; }","duration":"353.156025ms","start":"2026-10-26T01:34:31.055148Z","end":"2026-10-26T01:34:31.408304Z","steps":["trace[620700920] 'agreement among raft nodes before linearized reading' (duration: 353.050103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.408327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.851246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T01:34:31.408352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:34:31.055088Z","time spent":"353.255338ms","remote":"127.0.0.1:38898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-10-26T01:34:31.40837Z","caller":"traceutil/trace.go:171","msg":"trace[489768085] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16219; }","duration":"264.908068ms","start":"2026-10-26T01:34:31.143451Z","end":"2026-10-26T01:34:31.408359Z","steps":["trace[489768085] 'agreement among raft nodes before linearized reading' (duration: 264.851156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.408391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.223813ms","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-26T01:34:31.408416Z","caller":"traceutil/trace.go:171","msg":"trace[610035011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16219; }","duration":"274.285985ms","start":"2026-10-26T01:34:31.134123Z","end":"2026-10-26T01:34:31.408409Z","steps":["trace[610035011] 'agreement among raft nodes before linearized reading' (duration: 274.225103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:34:31.408421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.069336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T01:34:31.40832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.365725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:34:31.408476Z","caller":"traceutil/trace.go:171","msg":"trace[1841766937] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16219; }","duration":"188.144937ms","start":"2026-10-26T01:34:31.220319Z","end":"2026-10-26T01:34:31.408464Z","steps":["trace[1841766937] 'agreement among raft nodes before linearized reading' (duration: 188.047555ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:31.408487Z","caller":"traceutil/trace.go:171","msg":"trace[1598924264] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16219; }","duration":"264.59775ms","start":"2026-10-26T01:34:31.143877Z","end":"2026-10-26T01:34:31.408474Z","steps":["trace[1598924264] 'agreement among raft nodes before linearized reading' (duration: 264.288103ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:31.40828Z","caller":"traceutil/trace.go:171","msg":"trace[1951424096] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16219; }","duration":"265.379349ms","start":"2026-10-26T01:34:31.142884Z","end":"2026-10-26T01:34:31.408263Z","steps":["trace[1951424096] 'agreement among raft nodes before linearized reading' (duration: 265.309438ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:31.408166Z","caller":"traceutil/trace.go:171","msg":"trace[1101019373] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16219; }","duration":"182.703326ms","start":"2026-10-26T01:34:31.225443Z","end":"2026-10-26T01:34:31.408146Z","steps":["trace[1101019373] 'agreement among raft nodes before linearized reading' (duration: 182.263165ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:34:31.621719Z","caller":"traceutil/trace.go:171","msg":"trace[1646102937] transaction","detail":"{read_only:false; response_revision:16220; number_of_response:1; }","duration":"207.074387ms","start":"2026-10-26T01:34:31.414588Z","end":"2026-10-26T01:34:31.621662Z","steps":["trace[1646102937] 'process raft request' (duration: 138.768392ms)","trace[1646102937] 'compare' (duration: 68.189522ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:34:31.626333Z","caller":"traceutil/trace.go:171","msg":"trace[1638612163] transaction","detail":"{read_only:false; response_revision:16221; number_of_response:1; }","duration":"211.313419ms","start":"2026-10-26T01:34:31.414989Z","end":"2026-10-26T01:34:31.626302Z","steps":["trace[1638612163] 'process raft request' (duration: 211.140525ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:36:49.846229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14631} {"level":"info","ts":"2026-10-26T01:36:49.937688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14631,"took":"88.489814ms","hash":938864803,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":32235520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T01:36:49.937761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":938864803,"revision":14631,"compact-revision":12616} {"level":"info","ts":"2026-10-26T01:40:19.37286Z","caller":"traceutil/trace.go:171","msg":"trace[1654870861] transaction","detail":"{read_only:false; response_revision:18751; number_of_response:1; }","duration":"130.825598ms","start":"2026-10-26T01:40:19.242009Z","end":"2026-10-26T01:40:19.372834Z","steps":["trace[1654870861] 'process raft request' (duration: 130.642673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:40:23.771846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.83408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-26T01:40:23.771922Z","caller":"traceutil/trace.go:171","msg":"trace[1907121552] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:18783; }","duration":"159.958254ms","start":"2026-10-26T01:40:23.611948Z","end":"2026-10-26T01:40:23.771906Z","steps":["trace[1907121552] 'count revisions from in-memory index tree' (duration: 159.704767ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:40:24.965399Z","caller":"traceutil/trace.go:171","msg":"trace[100282116] linearizableReadLoop","detail":"{readStateIndex:19442; appliedIndex:19441; }","duration":"117.896444ms","start":"2026-10-26T01:40:24.847485Z","end":"2026-10-26T01:40:24.965381Z","steps":["trace[100282116] 'read index received' (duration: 117.740411ms)","trace[100282116] 'applied index is now lower than readState.Index' (duration: 155.513µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:40:24.965538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.041728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:40:24.965561Z","caller":"traceutil/trace.go:171","msg":"trace[1463674141] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18797; }","duration":"118.080759ms","start":"2026-10-26T01:40:24.847473Z","end":"2026-10-26T01:40:24.965554Z","steps":["trace[1463674141] 'agreement among raft nodes before linearized reading' (duration: 118.000327ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:40:24.965817Z","caller":"traceutil/trace.go:171","msg":"trace[1750051362] transaction","detail":"{read_only:false; response_revision:18797; number_of_response:1; }","duration":"195.230087ms","start":"2026-10-26T01:40:24.770574Z","end":"2026-10-26T01:40:24.965804Z","steps":["trace[1750051362] 'process raft request' (duration: 194.696934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:40:25.175175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.029694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T01:40:25.175272Z","caller":"traceutil/trace.go:171","msg":"trace[428080347] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:18797; }","duration":"174.160227ms","start":"2026-10-26T01:40:25.00109Z","end":"2026-10-26T01:40:25.17525Z","steps":["trace[428080347] 'count revisions from in-memory index tree' (duration: 173.969162ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:40:25.175688Z","caller":"traceutil/trace.go:171","msg":"trace[1524701221] transaction","detail":"{read_only:false; response_revision:18798; number_of_response:1; }","duration":"133.865522ms","start":"2026-10-26T01:40:25.041791Z","end":"2026-10-26T01:40:25.175656Z","steps":["trace[1524701221] 'process raft request' (duration: 103.391394ms)","trace[1524701221] 'compare' (duration: 30.357525ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:40:34.41312Z","caller":"traceutil/trace.go:171","msg":"trace[240308785] transaction","detail":"{read_only:false; response_revision:18990; number_of_response:1; }","duration":"101.956009ms","start":"2026-10-26T01:40:34.311134Z","end":"2026-10-26T01:40:34.41309Z","steps":["trace[240308785] 'process raft request' (duration: 101.772865ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:41:07.975179Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:41:07.981898Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T01:41:07.982148Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-10-26T01:41:20.295661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.34982ms","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-26T01:41:20.29583Z","caller":"traceutil/trace.go:171","msg":"trace[1029137365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19489; }","duration":"100.561045ms","start":"2026-10-26T01:41:20.195251Z","end":"2026-10-26T01:41:20.295812Z","steps":["trace[1029137365] 'range keys from in-memory index tree' (duration: 100.229047ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:41:49.853576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17141} {"level":"info","ts":"2026-10-26T01:41:49.959956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17141,"took":"103.181338ms","hash":3416858789,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":34611200,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T01:41:49.96006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3416858789,"revision":17141,"compact-revision":14631} {"level":"warn","ts":"2026-10-26T01:46:04.508031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.231104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:46:04.508134Z","caller":"traceutil/trace.go:171","msg":"trace[284963379] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:21458; }","duration":"173.398338ms","start":"2026-10-26T01:46:04.334716Z","end":"2026-10-26T01:46:04.508114Z","steps":["trace[284963379] 'count revisions from in-memory index tree' (duration: 173.100011ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:46:21.502172Z","caller":"traceutil/trace.go:171","msg":"trace[1452672056] linearizableReadLoop","detail":"{readStateIndex:22278; appliedIndex:22277; }","duration":"252.359511ms","start":"2026-10-26T01:46:21.249786Z","end":"2026-10-26T01:46:21.502145Z","steps":["trace[1452672056] 'read index received' (duration: 252.280549ms)","trace[1452672056] 'applied index is now lower than readState.Index' (duration: 77.642µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:46:21.502243Z","caller":"traceutil/trace.go:171","msg":"trace[639176041] transaction","detail":"{read_only:false; response_revision:21542; number_of_response:1; }","duration":"252.852973ms","start":"2026-10-26T01:46:21.249334Z","end":"2026-10-26T01:46:21.502187Z","steps":["trace[639176041] 'process raft request' (duration: 252.642198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:21.502387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.559675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:46:21.502429Z","caller":"traceutil/trace.go:171","msg":"trace[773933254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21542; }","duration":"252.684448ms","start":"2026-10-26T01:46:21.249732Z","end":"2026-10-26T01:46:21.502416Z","steps":["trace[773933254] 'agreement among raft nodes before linearized reading' (duration: 252.571536ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:46:21.544492Z","caller":"traceutil/trace.go:171","msg":"trace[118017863] transaction","detail":"{read_only:false; response_revision:21543; number_of_response:1; }","duration":"286.964798ms","start":"2026-10-26T01:46:21.2575Z","end":"2026-10-26T01:46:21.544464Z","steps":["trace[118017863] 'process raft request' (duration: 273.921143ms)","trace[118017863] 'compare' (duration: 12.876211ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:46:21.544585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.407681ms","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-26T01:46:21.544666Z","caller":"traceutil/trace.go:171","msg":"trace[1091315465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21543; }","duration":"209.531483ms","start":"2026-10-26T01:46:21.335116Z","end":"2026-10-26T01:46:21.544648Z","steps":["trace[1091315465] 'agreement among raft nodes before linearized reading' (duration: 209.310248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:21.544717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.97322ms","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-26T01:46:21.544775Z","caller":"traceutil/trace.go:171","msg":"trace[235799446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21543; }","duration":"202.107034ms","start":"2026-10-26T01:46:21.342654Z","end":"2026-10-26T01:46:21.544762Z","steps":["trace[235799446] 'agreement among raft nodes before linearized reading' (duration: 201.907889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:21.544839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.691115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-26T01:46:21.544965Z","caller":"traceutil/trace.go:171","msg":"trace[73239842] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:21543; }","duration":"142.885479ms","start":"2026-10-26T01:46:21.402059Z","end":"2026-10-26T01:46:21.544945Z","steps":["trace[73239842] 'agreement among raft nodes before linearized reading' (duration: 142.370217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:21.544917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.690697ms","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-26T01:46:21.545092Z","caller":"traceutil/trace.go:171","msg":"trace[1747964820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21543; }","duration":"185.900461ms","start":"2026-10-26T01:46:21.359172Z","end":"2026-10-26T01:46:21.545072Z","steps":["trace[1747964820] 'agreement among raft nodes before linearized reading' (duration: 185.347348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:21.867484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.291839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:46:21.867757Z","caller":"traceutil/trace.go:171","msg":"trace[1180526237] transaction","detail":"{read_only:false; response_revision:21544; number_of_response:1; }","duration":"318.892211ms","start":"2026-10-26T01:46:21.548819Z","end":"2026-10-26T01:46:21.867711Z","steps":["trace[1180526237] 'process raft request' (duration: 156.273224ms)","trace[1180526237] 'compare' (duration: 161.4953ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:46:21.86781Z","caller":"traceutil/trace.go:171","msg":"trace[2107483669] transaction","detail":"{read_only:false; response_revision:21547; number_of_response:1; }","duration":"317.814045ms","start":"2026-10-26T01:46:21.549973Z","end":"2026-10-26T01:46:21.867787Z","steps":["trace[2107483669] 'process raft request' (duration: 317.758054ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:46:21.867889Z","caller":"traceutil/trace.go:171","msg":"trace[1990032766] transaction","detail":"{read_only:false; response_revision:21545; number_of_response:1; }","duration":"318.83886ms","start":"2026-10-26T01:46:21.549031Z","end":"2026-10-26T01:46:21.867869Z","steps":["trace[1990032766] 'process raft request' (duration: 318.564504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:21.867952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:46:21.548796Z","time spent":"319.056246ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:46:21.867968Z","caller":"traceutil/trace.go:171","msg":"trace[1677056339] transaction","detail":"{read_only:false; response_revision:21546; number_of_response:1; }","duration":"318.491732ms","start":"2026-10-26T01:46:21.549442Z","end":"2026-10-26T01:46:21.867934Z","steps":["trace[1677056339] 'process raft request' (duration: 318.233216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:21.868021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:46:21.549951Z","time spent":"317.913408ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:46:21.867983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:46:21.54901Z","time spent":"318.929253ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:46:21.868128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:46:21.549424Z","time spent":"318.629056ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:46:22.119683Z","caller":"traceutil/trace.go:171","msg":"trace[332640587] linearizableReadLoop","detail":"{readStateIndex:22284; appliedIndex:22283; }","duration":"145.704948ms","start":"2026-10-26T01:46:21.97395Z","end":"2026-10-26T01:46:22.119654Z","steps":["trace[332640587] 'read index received' (duration: 131.257238ms)","trace[332640587] 'applied index is now lower than readState.Index' (duration: 14.44638ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:46:22.119767Z","caller":"traceutil/trace.go:171","msg":"trace[889370403] transaction","detail":"{read_only:false; response_revision:21548; number_of_response:1; }","duration":"256.625033ms","start":"2026-10-26T01:46:21.863106Z","end":"2026-10-26T01:46:22.119731Z","steps":["trace[889370403] 'process raft request' (duration: 242.076281ms)","trace[889370403] 'compare' (duration: 14.334577ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:46:22.119894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.925883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T01:46:22.119953Z","caller":"traceutil/trace.go:171","msg":"trace[286982076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21548; }","duration":"146.031945ms","start":"2026-10-26T01:46:21.973906Z","end":"2026-10-26T01:46:22.119938Z","steps":["trace[286982076] 'agreement among raft nodes before linearized reading' (duration: 145.845101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:46:22.119964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.675102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T01:46:22.120012Z","caller":"traceutil/trace.go:171","msg":"trace[707938196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21548; }","duration":"139.768304ms","start":"2026-10-26T01:46:21.98023Z","end":"2026-10-26T01:46:22.119998Z","steps":["trace[707938196] 'agreement among raft nodes before linearized reading' (duration: 139.61952ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:46:49.869317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19704} {"level":"info","ts":"2026-10-26T01:46:49.99402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19704,"took":"120.790595ms","hash":1559675407,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":29794304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T01:46:49.994086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1559675407,"revision":19704,"compact-revision":17141} {"level":"info","ts":"2026-10-26T01:51:49.882309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21692} {"level":"info","ts":"2026-10-26T01:51:49.993579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21692,"took":"107.017318ms","hash":2938772756,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":43393024,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-26T01:51:49.99365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2938772756,"revision":21692,"compact-revision":19704} {"level":"info","ts":"2026-10-26T01:53:58.247579Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006952.wal"} {"level":"info","ts":"2026-10-26T01:54:08.861916Z","caller":"traceutil/trace.go:171","msg":"trace[931662518] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"193.718994ms","start":"2026-10-26T01:54:08.66816Z","end":"2026-10-26T01:54:08.861879Z","steps":["trace[931662518] 'process raft request' (duration: 193.479158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:08.862313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.316173ms","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-26T01:54:08.862504Z","caller":"traceutil/trace.go:171","msg":"trace[350717753] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26181; }","duration":"133.626031ms","start":"2026-10-26T01:54:08.728838Z","end":"2026-10-26T01:54:08.862464Z","steps":["trace[350717753] 'agreement among raft nodes before linearized reading' (duration: 133.219191ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:54:08.862447Z","caller":"traceutil/trace.go:171","msg":"trace[1409756968] linearizableReadLoop","detail":"{readStateIndex:27051; appliedIndex:27050; }","duration":"133.526878ms","start":"2026-10-26T01:54:08.7289Z","end":"2026-10-26T01:54:08.862426Z","steps":["trace[1409756968] 'read index received' (duration: 132.670577ms)","trace[1409756968] 'applied index is now lower than readState.Index' (duration: 262.357µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:54:09.208077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.108724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:54:09.208186Z","caller":"traceutil/trace.go:171","msg":"trace[1131878100] linearizableReadLoop","detail":"{readStateIndex:27052; appliedIndex:27051; }","duration":"345.599269ms","start":"2026-10-26T01:54:08.862569Z","end":"2026-10-26T01:54:09.208168Z","steps":["trace[1131878100] 'read index received' (duration: 156.27722ms)","trace[1131878100] 'applied index is now lower than readState.Index' (duration: 189.320919ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:54:09.208361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.044464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11793"} {"level":"warn","ts":"2026-10-26T01:54:09.208413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.080946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T01:54:09.208429Z","caller":"traceutil/trace.go:171","msg":"trace[2087374511] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26182; }","duration":"364.163037ms","start":"2026-10-26T01:54:08.844255Z","end":"2026-10-26T01:54:09.208418Z","steps":["trace[2087374511] 'agreement among raft nodes before linearized reading' (duration: 363.978142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.208462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:08.84423Z","time spent":"364.226799ms","remote":"127.0.0.1:49706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11816,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-26T01:54:09.208467Z","caller":"traceutil/trace.go:171","msg":"trace[1798565400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26182; }","duration":"465.173109ms","start":"2026-10-26T01:54:08.743276Z","end":"2026-10-26T01:54:09.20845Z","steps":["trace[1798565400] 'agreement among raft nodes before linearized reading' (duration: 464.934843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.208447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.093717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-26T01:54:09.208515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:08.743208Z","time spent":"465.296072ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T01:54:09.20853Z","caller":"traceutil/trace.go:171","msg":"trace[2018262632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26182; }","duration":"465.21038ms","start":"2026-10-26T01:54:08.743298Z","end":"2026-10-26T01:54:09.208508Z","steps":["trace[2018262632] 'agreement among raft nodes before linearized reading' (duration: 464.940873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.208571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:08.743277Z","time spent":"465.285441ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-26T01:54:09.208326Z","caller":"traceutil/trace.go:171","msg":"trace[994292568] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"388.766311ms","start":"2026-10-26T01:54:08.819512Z","end":"2026-10-26T01:54:09.208278Z","steps":["trace[994292568] 'process raft request' (duration: 199.34253ms)","trace[994292568] 'compare' (duration: 188.743275ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:54:09.208759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:08.819489Z","time spent":"389.188811ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:54:09.234446Z","caller":"traceutil/trace.go:171","msg":"trace[607703332] transaction","detail":"{read_only:false; response_revision:26183; number_of_response:1; }","duration":"104.894437ms","start":"2026-10-26T01:54:09.129514Z","end":"2026-10-26T01:54:09.234408Z","steps":["trace[607703332] 'process raft request' (duration: 104.682552ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:54:09.568409Z","caller":"traceutil/trace.go:171","msg":"trace[1495000015] linearizableReadLoop","detail":"{readStateIndex:27055; appliedIndex:27052; }","duration":"352.125636ms","start":"2026-10-26T01:54:09.216263Z","end":"2026-10-26T01:54:09.568389Z","steps":["trace[1495000015] 'read index received' (duration: 17.919663ms)","trace[1495000015] 'applied index is now lower than readState.Index' (duration: 334.205143ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:54:09.568494Z","caller":"traceutil/trace.go:171","msg":"trace[1250787366] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"354.699788ms","start":"2026-10-26T01:54:09.213783Z","end":"2026-10-26T01:54:09.568483Z","steps":["trace[1250787366] 'process raft request' (duration: 354.553395ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:54:09.568494Z","caller":"traceutil/trace.go:171","msg":"trace[430781392] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"355.249912ms","start":"2026-10-26T01:54:09.213184Z","end":"2026-10-26T01:54:09.568434Z","steps":["trace[430781392] 'process raft request' (duration: 314.052946ms)","trace[430781392] 'compare' (duration: 41.001271ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:54:09.568573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.213775Z","time spent":"354.73613ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:54:09.568655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.213168Z","time spent":"355.402205ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:54:09.568848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.581007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-26T01:54:09.568889Z","caller":"traceutil/trace.go:171","msg":"trace[1136984857] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26185; }","duration":"352.647469ms","start":"2026-10-26T01:54:09.216226Z","end":"2026-10-26T01:54:09.568874Z","steps":["trace[1136984857] 'agreement among raft nodes before linearized reading' (duration: 352.493925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.569102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.21621Z","time spent":"352.879235ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-10-26T01:54:09.878922Z","caller":"traceutil/trace.go:171","msg":"trace[37950285] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"520.394696ms","start":"2026-10-26T01:54:09.358501Z","end":"2026-10-26T01:54:09.878896Z","steps":["trace[37950285] 'process raft request' (duration: 520.308144ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:54:09.87893Z","caller":"traceutil/trace.go:171","msg":"trace[1451439946] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"566.761337ms","start":"2026-10-26T01:54:09.312123Z","end":"2026-10-26T01:54:09.878885Z","steps":["trace[1451439946] 'process raft request' (duration: 558.741674ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:54:09.879044Z","caller":"traceutil/trace.go:171","msg":"trace[1541725541] linearizableReadLoop","detail":"{readStateIndex:27058; appliedIndex:27055; }","duration":"310.55404ms","start":"2026-10-26T01:54:09.56846Z","end":"2026-10-26T01:54:09.879014Z","steps":["trace[1541725541] 'read index received' (duration: 302.116877ms)","trace[1541725541] 'applied index is now lower than readState.Index' (duration: 8.433313ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:54:09.87909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.358479Z","time spent":"520.524709ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:54:09.879099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.312097Z","time spent":"566.919241ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:54:09.879203Z","caller":"traceutil/trace.go:171","msg":"trace[1344495977] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"396.334696ms","start":"2026-10-26T01:54:09.482807Z","end":"2026-10-26T01:54:09.879141Z","steps":["trace[1344495977] 'process raft request' (duration: 396.048979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.879316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.44817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12433"} {"level":"warn","ts":"2026-10-26T01:54:09.879331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.921693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9c4c2a2b-7c3f-4439-aa59-a2805c5cb789\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-10-26T01:54:09.879354Z","caller":"traceutil/trace.go:171","msg":"trace[1640169438] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26188; }","duration":"492.533572ms","start":"2026-10-26T01:54:09.386808Z","end":"2026-10-26T01:54:09.879341Z","steps":["trace[1640169438] 'agreement among raft nodes before linearized reading' (duration: 492.335748ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:54:09.879389Z","caller":"traceutil/trace.go:171","msg":"trace[1024309369] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9c4c2a2b-7c3f-4439-aa59-a2805c5cb789; range_end:; response_count:1; response_revision:26188; }","duration":"307.010795ms","start":"2026-10-26T01:54:09.572346Z","end":"2026-10-26T01:54:09.879357Z","steps":["trace[1024309369] 'agreement among raft nodes before linearized reading' (duration: 306.876092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.879419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.572331Z","time spent":"307.081417ms","remote":"127.0.0.1:38820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1299,"request content":"key:\"/registry/persistentvolumes/pvc-9c4c2a2b-7c3f-4439-aa59-a2805c5cb789\" "} {"level":"warn","ts":"2026-10-26T01:54:09.879416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.386796Z","time spent":"492.612594ms","remote":"127.0.0.1:38844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12456,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-26T01:54:09.879431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.180652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T01:54:09.879488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.482783Z","time spent":"396.54091ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:54:09.879506Z","caller":"traceutil/trace.go:171","msg":"trace[252449344] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26188; }","duration":"279.287545ms","start":"2026-10-26T01:54:09.600196Z","end":"2026-10-26T01:54:09.879483Z","steps":["trace[252449344] 'agreement among raft nodes before linearized reading' (duration: 279.158492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.879601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.581495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11793"} {"level":"info","ts":"2026-10-26T01:54:09.879698Z","caller":"traceutil/trace.go:171","msg":"trace[1763935083] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26188; }","duration":"662.715458ms","start":"2026-10-26T01:54:09.216957Z","end":"2026-10-26T01:54:09.879672Z","steps":["trace[1763935083] 'agreement among raft nodes before linearized reading' (duration: 662.394721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:54:09.879748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:54:09.216942Z","time spent":"662.79751ms","remote":"127.0.0.1:49706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11816,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-26T01:54:30.083766Z","caller":"traceutil/trace.go:171","msg":"trace[164024276] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"118.209318ms","start":"2026-10-26T01:54:29.965525Z","end":"2026-10-26T01:54:30.083734Z","steps":["trace[164024276] 'process raft request' (duration: 117.712766ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:54:30.083937Z","caller":"traceutil/trace.go:171","msg":"trace[325465104] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"115.880253ms","start":"2026-10-26T01:54:29.968044Z","end":"2026-10-26T01:54:30.083924Z","steps":["trace[325465104] 'process raft request' (duration: 115.510004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:55:08.375418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.069803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-97775c495-2wnv6.18e1efdcea19008e\" ","response":"range_response_count:1 size:2105"} {"level":"info","ts":"2026-10-26T01:55:08.375497Z","caller":"traceutil/trace.go:171","msg":"trace[2137383609] range","detail":"{range_begin:/registry/events/openstack/glance-api-97775c495-2wnv6.18e1efdcea19008e; range_end:; response_count:1; response_revision:26741; }","duration":"261.216067ms","start":"2026-10-26T01:55:08.11426Z","end":"2026-10-26T01:55:08.375476Z","steps":["trace[2137383609] 'range keys from in-memory index tree' (duration: 260.872619ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:55:08.391739Z","caller":"traceutil/trace.go:171","msg":"trace[343478580] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27623; }","duration":"174.044279ms","start":"2026-10-26T01:55:08.217661Z","end":"2026-10-26T01:55:08.391705Z","steps":["trace[343478580] 'read index received' (duration: 173.903375ms)","trace[343478580] 'applied index is now lower than readState.Index' (duration: 139.454µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:55:08.392154Z","caller":"traceutil/trace.go:171","msg":"trace[1267629861] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"191.546962ms","start":"2026-10-26T01:55:08.200592Z","end":"2026-10-26T01:55:08.392139Z","steps":["trace[1267629861] 'process raft request' (duration: 190.876196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:55:08.392316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.620283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T01:55:08.392449Z","caller":"traceutil/trace.go:171","msg":"trace[632956442] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:26742; }","duration":"174.773547ms","start":"2026-10-26T01:55:08.217647Z","end":"2026-10-26T01:55:08.39242Z","steps":["trace[632956442] 'agreement among raft nodes before linearized reading' (duration: 174.541382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:55:08.39612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.554323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:55:08.396205Z","caller":"traceutil/trace.go:171","msg":"trace[111996564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26747; }","duration":"148.664525ms","start":"2026-10-26T01:55:08.24752Z","end":"2026-10-26T01:55:08.396184Z","steps":["trace[111996564] 'agreement among raft nodes before linearized reading' (duration: 148.531822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:55:08.396605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.695622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-26T01:55:08.396672Z","caller":"traceutil/trace.go:171","msg":"trace[1907743376] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26747; }","duration":"116.780275ms","start":"2026-10-26T01:55:08.279873Z","end":"2026-10-26T01:55:08.396653Z","steps":["trace[1907743376] 'agreement among raft nodes before linearized reading' (duration: 116.59583ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:55:10.322433Z","caller":"traceutil/trace.go:171","msg":"trace[1918938209] transaction","detail":"{read_only:false; response_revision:26759; number_of_response:1; }","duration":"175.539275ms","start":"2026-10-26T01:55:10.146825Z","end":"2026-10-26T01:55:10.322364Z","steps":["trace[1918938209] 'process raft request' (duration: 173.918726ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:56:49.890446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24948} {"level":"info","ts":"2026-10-26T01:56:50.04443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24948,"took":"151.223522ms","hash":2972817118,"current-db-size-bytes":59293696,"current-db-size":"59 MB","current-db-size-in-use-bytes":36401152,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T01:56:50.044511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2972817118,"revision":24948,"compact-revision":21692} {"level":"info","ts":"2026-10-26T01:59:03.404099Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":30004,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:59:03.411304Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-10-26T01:59:03.411432Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-10-26T01:59:27.592531Z","caller":"traceutil/trace.go:171","msg":"trace[967031725] linearizableReadLoop","detail":"{readStateIndex:30216; appliedIndex:30214; }","duration":"138.965477ms","start":"2026-10-26T01:59:27.453532Z","end":"2026-10-26T01:59:27.592497Z","steps":["trace[967031725] 'read index received' (duration: 30.255061ms)","trace[967031725] 'applied index is now lower than readState.Index' (duration: 108.708576ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:59:27.592696Z","caller":"traceutil/trace.go:171","msg":"trace[782505673] transaction","detail":"{read_only:false; response_revision:29270; number_of_response:1; }","duration":"143.135088ms","start":"2026-10-26T01:59:27.449519Z","end":"2026-10-26T01:59:27.592654Z","steps":["trace[782505673] 'process raft request' (duration: 121.521216ms)","trace[782505673] 'compare' (duration: 21.288554ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:59:27.59273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.158592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:59:27.592898Z","caller":"traceutil/trace.go:171","msg":"trace[1094000684] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:29270; }","duration":"139.380446ms","start":"2026-10-26T01:59:27.453497Z","end":"2026-10-26T01:59:27.592878Z","steps":["trace[1094000684] 'agreement among raft nodes before linearized reading' (duration: 139.152001ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:59:27.620769Z","caller":"traceutil/trace.go:171","msg":"trace[1603966347] transaction","detail":"{read_only:false; response_revision:29272; number_of_response:1; }","duration":"127.07814ms","start":"2026-10-26T01:59:27.493678Z","end":"2026-10-26T01:59:27.620757Z","steps":["trace[1603966347] 'process raft request' (duration: 126.817104ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:59:27.620995Z","caller":"traceutil/trace.go:171","msg":"trace[648320190] transaction","detail":"{read_only:false; response_revision:29273; number_of_response:1; }","duration":"118.317578ms","start":"2026-10-26T01:59:27.502652Z","end":"2026-10-26T01:59:27.620969Z","steps":["trace[648320190] 'process raft request' (duration: 118.060282ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:59:27.621107Z","caller":"traceutil/trace.go:171","msg":"trace[413473327] transaction","detail":"{read_only:false; response_revision:29274; number_of_response:1; }","duration":"101.487252ms","start":"2026-10-26T01:59:27.519602Z","end":"2026-10-26T01:59:27.62109Z","steps":["trace[413473327] 'process raft request' (duration: 101.280527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:59:27.621333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.137117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T01:59:27.62138Z","caller":"traceutil/trace.go:171","msg":"trace[1701410004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29274; }","duration":"167.201579ms","start":"2026-10-26T01:59:27.454166Z","end":"2026-10-26T01:59:27.621368Z","steps":["trace[1701410004] 'agreement among raft nodes before linearized reading' (duration: 167.043355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:59:27.621503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.552319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:59:27.621551Z","caller":"traceutil/trace.go:171","msg":"trace[822845877] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:29274; }","duration":"117.660542ms","start":"2026-10-26T01:59:27.503879Z","end":"2026-10-26T01:59:27.62154Z","steps":["trace[822845877] 'agreement among raft nodes before linearized reading' (duration: 117.57349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:59:27.622452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.260747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T01:59:27.622579Z","caller":"traceutil/trace.go:171","msg":"trace[889857528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29274; }","duration":"142.466312ms","start":"2026-10-26T01:59:27.480092Z","end":"2026-10-26T01:59:27.622558Z","steps":["trace[889857528] 'agreement among raft nodes before linearized reading' (duration: 141.425626ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:59:27.62062Z","caller":"traceutil/trace.go:171","msg":"trace[40599368] transaction","detail":"{read_only:false; response_revision:29271; number_of_response:1; }","duration":"127.333896ms","start":"2026-10-26T01:59:27.493232Z","end":"2026-10-26T01:59:27.620566Z","steps":["trace[40599368] 'process raft request' (duration: 127.08814ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T02:01:49.898596Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27734} {"level":"info","ts":"2026-10-26T02:01:50.047995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27734,"took":"144.694976ms","hash":3325438853,"current-db-size-bytes":59293696,"current-db-size":"59 MB","current-db-size-in-use-bytes":35074048,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T02:01:50.048152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3325438853,"revision":27734,"compact-revision":24948} {"level":"info","ts":"2026-10-26T02:06:49.908439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30434} {"level":"info","ts":"2026-10-26T02:06:50.059702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30434,"took":"146.451836ms","hash":2712249752,"current-db-size-bytes":59293696,"current-db-size":"59 MB","current-db-size-in-use-bytes":31244288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T02:06:50.05982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2712249752,"revision":30434,"compact-revision":27734} {"level":"info","ts":"2026-10-26T02:11:49.915277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32719} {"level":"info","ts":"2026-10-26T02:11:50.091497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32719,"took":"170.791744ms","hash":3174545624,"current-db-size-bytes":60338176,"current-db-size":"60 MB","current-db-size-in-use-bytes":30289920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T02:11:50.091552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3174545624,"revision":32719,"compact-revision":30434} {"level":"info","ts":"2026-10-26T02:16:49.925508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34946} {"level":"info","ts":"2026-10-26T02:16:50.058695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34946,"took":"127.970191ms","hash":4241138812,"current-db-size-bytes":60338176,"current-db-size":"60 MB","current-db-size-in-use-bytes":31121408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T02:16:50.058753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4241138812,"revision":34946,"compact-revision":32719} {"level":"info","ts":"2026-10-26T02:20:11.313693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T02:20:11.319595Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-10-26T02:20:11.319695Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-10-26T02:21:49.933715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37243} {"level":"info","ts":"2026-10-26T02:21:50.065396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37243,"took":"128.597321ms","hash":1474276856,"current-db-size-bytes":60338176,"current-db-size":"60 MB","current-db-size-in-use-bytes":32370688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T02:21:50.065462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1474276856,"revision":37243,"compact-revision":34946} {"level":"info","ts":"2026-10-26T02:26:49.940573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39517} {"level":"info","ts":"2026-10-26T02:26:50.075105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39517,"took":"130.630089ms","hash":2804318151,"current-db-size-bytes":62464000,"current-db-size":"62 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T02:26:50.075159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2804318151,"revision":39517,"compact-revision":37243}