{"level":"warn","ts":"2026-08-13T00:49:05.786581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T00:49:05.786703Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-08-13T00:49:05.786778Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T00:49:05.786794Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-13T00:49:05.786818Z","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-08-13T00:49:05.787523Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-08-13T00:49:05.787615Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-08-13T00:49:05.790329Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.482616ms"} {"level":"info","ts":"2026-08-13T00:49:05.796629Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-08-13T00:49:05.796742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T00:49:05.796776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-08-13T00:49:05.796791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T00:49:05.796809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-08-13T00:49:05.796858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-08-13T00:49:05.801655Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T00:49:05.804866Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T00:49:05.807807Z","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-08-13T00:49:05.81109Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T00:49:05.811359Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T00:49:05.811415Z","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-08-13T00:49:05.811563Z","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-08-13T00:49:05.811587Z","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-08-13T00:49:05.812251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-08-13T00:49:05.812444Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-13T00:49:05.814085Z","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-08-13T00:49:05.814302Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-13T00:49:05.814369Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-13T00:49:05.814433Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T00:49:05.81447Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T00:49:06.597878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T00:49:06.59798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T00:49:06.598023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-08-13T00:49:06.598048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-08-13T00:49:06.598065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-13T00:49:06.598086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-08-13T00:49:06.59812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-13T00:49:06.599309Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T00:49:06.599334Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:49:06.599373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:49:06.59956Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:49:06.599813Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T00:49:06.599859Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T00:49:06.600439Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:49:06.600583Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:49:06.600629Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:49:06.601866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T00:49:06.605447Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-08-13T00:49:09.218132Z","caller":"traceutil/trace.go:171","msg":"trace[1815785086] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"137.545924ms","start":"2026-08-13T00:49:09.080545Z","end":"2026-08-13T00:49:09.218091Z","steps":["trace[1815785086] 'process raft request' (duration: 121.617376ms)","trace[1815785086] 'compare' (duration: 15.785116ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:23.434654Z","caller":"traceutil/trace.go:171","msg":"trace[161989743] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"197.241472ms","start":"2026-08-13T00:49:23.237381Z","end":"2026-08-13T00:49:23.434623Z","steps":["trace[161989743] 'process raft request' (duration: 197.061788ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:23.579136Z","caller":"traceutil/trace.go:171","msg":"trace[1741866036] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"131.606553ms","start":"2026-08-13T00:49:23.447492Z","end":"2026-08-13T00:49:23.579098Z","steps":["trace[1741866036] 'process raft request' (duration: 131.392439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:23.847737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.894332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:49:23.84791Z","caller":"traceutil/trace.go:171","msg":"trace[1738625434] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"234.499189ms","start":"2026-08-13T00:49:23.613388Z","end":"2026-08-13T00:49:23.847888Z","steps":["trace[1738625434] 'process raft request' (duration: 73.776362ms)","trace[1738625434] 'compare' (duration: 159.746119ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:45.491785Z","caller":"traceutil/trace.go:171","msg":"trace[949575788] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"143.482684ms","start":"2026-08-13T00:49:45.34828Z","end":"2026-08-13T00:49:45.491763Z","steps":["trace[949575788] 'process raft request' (duration: 143.228769ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:45.596172Z","caller":"traceutil/trace.go:171","msg":"trace[1939150503] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"101.339177ms","start":"2026-08-13T00:49:45.494803Z","end":"2026-08-13T00:49:45.596142Z","steps":["trace[1939150503] 'process raft request' (duration: 99.595894ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:48.407274Z","caller":"traceutil/trace.go:171","msg":"trace[1105353244] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"149.6395ms","start":"2026-08-13T00:49:48.257562Z","end":"2026-08-13T00:49:48.407201Z","steps":["trace[1105353244] 'process raft request' (duration: 144.939722ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:48.584598Z","caller":"traceutil/trace.go:171","msg":"trace[1735393224] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"123.130454ms","start":"2026-08-13T00:49:48.461433Z","end":"2026-08-13T00:49:48.584564Z","steps":["trace[1735393224] 'read index received' (duration: 73.497136ms)","trace[1735393224] 'applied index is now lower than readState.Index' (duration: 49.631798ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:48.584758Z","caller":"traceutil/trace.go:171","msg":"trace[2062195436] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"128.714188ms","start":"2026-08-13T00:49:48.456014Z","end":"2026-08-13T00:49:48.584729Z","steps":["trace[2062195436] 'process raft request' (duration: 128.29917ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:48.584761Z","caller":"traceutil/trace.go:171","msg":"trace[1544355191] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"112.312501ms","start":"2026-08-13T00:49:48.472374Z","end":"2026-08-13T00:49:48.584687Z","steps":["trace[1544355191] 'process raft request' (duration: 112.125788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:48.584943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.40833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T00:49:48.584998Z","caller":"traceutil/trace.go:171","msg":"trace[2055655987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"123.569973ms","start":"2026-08-13T00:49:48.461411Z","end":"2026-08-13T00:49:48.584981Z","steps":["trace[2055655987] 'agreement among raft nodes before linearized reading' (duration: 123.291307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:49.080774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.805464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wrxmz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-13T00:49:49.080885Z","caller":"traceutil/trace.go:171","msg":"trace[271728820] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wrxmz; range_end:; response_count:1; response_revision:631; }","duration":"398.967567ms","start":"2026-08-13T00:49:48.681888Z","end":"2026-08-13T00:49:49.080855Z","steps":["trace[271728820] 'range keys from in-memory index tree' (duration: 398.61789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:49.080931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:48.681863Z","time spent":"399.058018ms","remote":"127.0.0.1:35040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-wrxmz\" "} {"level":"warn","ts":"2026-08-13T00:51:31.965006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.59008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3a0f766e-2cdc-4ab6-838e-5dbd970322af\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-13T00:51:31.965203Z","caller":"traceutil/trace.go:171","msg":"trace[492907072] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3a0f766e-2cdc-4ab6-838e-5dbd970322af; range_end:; response_count:1; response_revision:1900; }","duration":"226.864625ms","start":"2026-08-13T00:51:31.738305Z","end":"2026-08-13T00:51:31.965169Z","steps":["trace[492907072] 'range keys from in-memory index tree' (duration: 226.322635ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:51:32.457066Z","caller":"traceutil/trace.go:171","msg":"trace[1489442725] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"228.803961ms","start":"2026-08-13T00:51:32.228227Z","end":"2026-08-13T00:51:32.457031Z","steps":["trace[1489442725] 'process raft request' (duration: 228.583187ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:21.634385Z","caller":"traceutil/trace.go:171","msg":"trace[219369690] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"259.671496ms","start":"2026-08-13T00:52:21.374661Z","end":"2026-08-13T00:52:21.634333Z","steps":["trace[219369690] 'process raft request' (duration: 202.953966ms)","trace[219369690] 'compare' (duration: 56.506086ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:52:21.634512Z","caller":"traceutil/trace.go:171","msg":"trace[712468970] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2302; }","duration":"215.810482ms","start":"2026-08-13T00:52:21.418666Z","end":"2026-08-13T00:52:21.634476Z","steps":["trace[712468970] 'read index received' (duration: 48.578895ms)","trace[712468970] 'applied index is now lower than readState.Index' (duration: 167.227877ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:52:21.6344Z","caller":"traceutil/trace.go:171","msg":"trace[1149839977] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"227.869842ms","start":"2026-08-13T00:52:21.406493Z","end":"2026-08-13T00:52:21.634363Z","steps":["trace[1149839977] 'process raft request' (duration: 227.749489ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:21.634426Z","caller":"traceutil/trace.go:171","msg":"trace[1360104275] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"105.832666ms","start":"2026-08-13T00:52:21.528564Z","end":"2026-08-13T00:52:21.634397Z","steps":["trace[1360104275] 'process raft request' (duration: 105.788265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:21.634845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.182079ms","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-08-13T00:52:21.634904Z","caller":"traceutil/trace.go:171","msg":"trace[564451715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2219; }","duration":"216.281182ms","start":"2026-08-13T00:52:21.418602Z","end":"2026-08-13T00:52:21.634883Z","steps":["trace[564451715] 'agreement among raft nodes before linearized reading' (duration: 216.037707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:21.634895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.502057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T00:52:21.634951Z","caller":"traceutil/trace.go:171","msg":"trace[1154779563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2219; }","duration":"214.63652ms","start":"2026-08-13T00:52:21.420302Z","end":"2026-08-13T00:52:21.634938Z","steps":["trace[1154779563] 'agreement among raft nodes before linearized reading' (duration: 214.365214ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:27.623336Z","caller":"traceutil/trace.go:171","msg":"trace[512735297] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"128.130291ms","start":"2026-08-13T00:52:27.495168Z","end":"2026-08-13T00:52:27.623298Z","steps":["trace[512735297] 'process raft request' (duration: 127.875026ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:27.809893Z","caller":"traceutil/trace.go:171","msg":"trace[210530686] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"231.143584ms","start":"2026-08-13T00:52:27.578703Z","end":"2026-08-13T00:52:27.809847Z","steps":["trace[210530686] 'process raft request' (duration: 171.58395ms)","trace[210530686] 'compare' (duration: 59.244718ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:52:27.81343Z","caller":"traceutil/trace.go:171","msg":"trace[899057725] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"157.529901ms","start":"2026-08-13T00:52:27.655872Z","end":"2026-08-13T00:52:27.813401Z","steps":["trace[899057725] 'process raft request' (duration: 157.320007ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:27.813702Z","caller":"traceutil/trace.go:171","msg":"trace[408194393] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"156.987851ms","start":"2026-08-13T00:52:27.656687Z","end":"2026-08-13T00:52:27.813675Z","steps":["trace[408194393] 'process raft request' (duration: 156.657495ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:27.813977Z","caller":"traceutil/trace.go:171","msg":"trace[1929796496] linearizableReadLoop","detail":"{readStateIndex:2338; appliedIndex:2335; }","duration":"152.370663ms","start":"2026-08-13T00:52:27.661565Z","end":"2026-08-13T00:52:27.813936Z","steps":["trace[1929796496] 'read index received' (duration: 88.789862ms)","trace[1929796496] 'applied index is now lower than readState.Index' (duration: 63.578491ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:52:27.814378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.792252ms","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":"warn","ts":"2026-08-13T00:52:27.814417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.661831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T00:52:27.814444Z","caller":"traceutil/trace.go:171","msg":"trace[298329097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2251; }","duration":"152.911754ms","start":"2026-08-13T00:52:27.661516Z","end":"2026-08-13T00:52:27.814427Z","steps":["trace[298329097] 'agreement among raft nodes before linearized reading' (duration: 152.631378ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:27.814486Z","caller":"traceutil/trace.go:171","msg":"trace[595949918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2251; }","duration":"151.793833ms","start":"2026-08-13T00:52:27.662676Z","end":"2026-08-13T00:52:27.81447Z","steps":["trace[595949918] 'agreement among raft nodes before linearized reading' (duration: 151.541308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:27.814498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.581175ms","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-08-13T00:52:27.814599Z","caller":"traceutil/trace.go:171","msg":"trace[252695608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2251; }","duration":"144.710338ms","start":"2026-08-13T00:52:27.669866Z","end":"2026-08-13T00:52:27.814576Z","steps":["trace[252695608] 'agreement among raft nodes before linearized reading' (duration: 144.442743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:28.152397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.660661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:52:28.152684Z","caller":"traceutil/trace.go:171","msg":"trace[710568727] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2340; }","duration":"192.331994ms","start":"2026-08-13T00:52:27.96033Z","end":"2026-08-13T00:52:28.152662Z","steps":["trace[710568727] 'read index received' (duration: 49.221µs)","trace[710568727] 'applied index is now lower than readState.Index' (duration: 192.281653ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:52:28.152967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.641389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-13T00:52:28.153021Z","caller":"traceutil/trace.go:171","msg":"trace[690904706] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2254; }","duration":"192.750212ms","start":"2026-08-13T00:52:27.960257Z","end":"2026-08-13T00:52:28.153007Z","steps":["trace[690904706] 'agreement among raft nodes before linearized reading' (duration: 192.474916ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:28.152995Z","caller":"traceutil/trace.go:171","msg":"trace[477603287] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"333.605185ms","start":"2026-08-13T00:52:27.819341Z","end":"2026-08-13T00:52:28.152946Z","steps":["trace[477603287] 'process raft request' (duration: 110.252481ms)","trace[477603287] 'compare' (duration: 222.523129ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:52:28.153078Z","caller":"traceutil/trace.go:171","msg":"trace[849617456] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"333.358831ms","start":"2026-08-13T00:52:27.819691Z","end":"2026-08-13T00:52:28.153049Z","steps":["trace[849617456] 'process raft request' (duration: 332.864782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:28.153176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:27.819324Z","time spent":"333.738879ms","remote":"127.0.0.1:35128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:52:28.153275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:27.819681Z","time spent":"333.506445ms","remote":"127.0.0.1:35128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:53:35.491682Z","caller":"traceutil/trace.go:171","msg":"trace[266705947] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"116.582129ms","start":"2026-08-13T00:53:35.375073Z","end":"2026-08-13T00:53:35.491655Z","steps":["trace[266705947] 'process raft request' (duration: 116.396806ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:53:35.682298Z","caller":"traceutil/trace.go:171","msg":"trace[706869056] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"141.686669ms","start":"2026-08-13T00:53:35.540572Z","end":"2026-08-13T00:53:35.682258Z","steps":["trace[706869056] 'process raft request' (duration: 92.096862ms)","trace[706869056] 'compare' (duration: 49.321592ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:28.283758Z","caller":"traceutil/trace.go:171","msg":"trace[881372519] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"244.635651ms","start":"2026-08-13T00:57:28.039097Z","end":"2026-08-13T00:57:28.283733Z","steps":["trace[881372519] 'process raft request' (duration: 244.482968ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:28.345963Z","caller":"traceutil/trace.go:171","msg":"trace[1947077954] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5150; }","duration":"249.239609ms","start":"2026-08-13T00:57:28.096698Z","end":"2026-08-13T00:57:28.345938Z","steps":["trace[1947077954] 'read index received' (duration: 186.876731ms)","trace[1947077954] 'applied index is now lower than readState.Index' (duration: 62.361738ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:28.345966Z","caller":"traceutil/trace.go:171","msg":"trace[831731651] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"302.698127ms","start":"2026-08-13T00:57:28.043233Z","end":"2026-08-13T00:57:28.345931Z","steps":["trace[831731651] 'process raft request' (duration: 301.623387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:28.346241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:57:28.043223Z","time spent":"302.917061ms","remote":"127.0.0.1:35040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3703,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:57:28.346439Z","caller":"traceutil/trace.go:171","msg":"trace[476907254] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"117.680781ms","start":"2026-08-13T00:57:28.228744Z","end":"2026-08-13T00:57:28.346425Z","steps":["trace[476907254] 'process raft request' (duration: 117.113931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:28.346726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.012424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T00:57:28.346769Z","caller":"traceutil/trace.go:171","msg":"trace[392561816] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4963; }","duration":"250.106076ms","start":"2026-08-13T00:57:28.096652Z","end":"2026-08-13T00:57:28.346758Z","steps":["trace[392561816] 'agreement among raft nodes before linearized reading' (duration: 249.976693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:28.347227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.731253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728955"} {"level":"info","ts":"2026-08-13T00:57:28.34732Z","caller":"traceutil/trace.go:171","msg":"trace[674262548] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4963; }","duration":"142.848655ms","start":"2026-08-13T00:57:28.204457Z","end":"2026-08-13T00:57:28.347305Z","steps":["trace[674262548] 'agreement among raft nodes before linearized reading' (duration: 142.286553ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:42.617178Z","caller":"traceutil/trace.go:171","msg":"trace[1150586032] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"432.48337ms","start":"2026-08-13T00:57:42.184665Z","end":"2026-08-13T00:57:42.617149Z","steps":["trace[1150586032] 'process raft request' (duration: 432.272547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:42.61736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:57:42.18464Z","time spent":"432.635734ms","remote":"127.0.0.1:35040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:57:42.617342Z","caller":"traceutil/trace.go:171","msg":"trace[1494546973] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5435; }","duration":"130.316334ms","start":"2026-08-13T00:57:42.486981Z","end":"2026-08-13T00:57:42.617297Z","steps":["trace[1494546973] 'read index received' (duration: 130.292284ms)","trace[1494546973] 'applied index is now lower than readState.Index' (duration: 19.61µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:57:42.617641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.631211ms","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-08-13T00:57:42.617685Z","caller":"traceutil/trace.go:171","msg":"trace[1761447594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5235; }","duration":"130.768243ms","start":"2026-08-13T00:57:42.4869Z","end":"2026-08-13T00:57:42.617668Z","steps":["trace[1761447594] 'agreement among raft nodes before linearized reading' (duration: 130.536479ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:42.697582Z","caller":"traceutil/trace.go:171","msg":"trace[1939741438] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"171.933084ms","start":"2026-08-13T00:57:42.525627Z","end":"2026-08-13T00:57:42.69756Z","steps":["trace[1939741438] 'process raft request' (duration: 171.817402ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:49.906175Z","caller":"traceutil/trace.go:171","msg":"trace[911863956] linearizableReadLoop","detail":"{readStateIndex:5628; appliedIndex:5626; }","duration":"129.529409ms","start":"2026-08-13T00:57:49.776621Z","end":"2026-08-13T00:57:49.90615Z","steps":["trace[911863956] 'read index received' (duration: 44.571287ms)","trace[911863956] 'applied index is now lower than readState.Index' (duration: 84.957252ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:49.906205Z","caller":"traceutil/trace.go:171","msg":"trace[1840937508] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"130.789573ms","start":"2026-08-13T00:57:49.775399Z","end":"2026-08-13T00:57:49.906188Z","steps":["trace[1840937508] 'process raft request' (duration: 130.602529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:49.906461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.795394ms","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-08-13T00:57:49.90655Z","caller":"traceutil/trace.go:171","msg":"trace[214602459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5423; }","duration":"129.943698ms","start":"2026-08-13T00:57:49.776588Z","end":"2026-08-13T00:57:49.906532Z","steps":["trace[214602459] 'agreement among raft nodes before linearized reading' (duration: 129.657602ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:49.923637Z","caller":"traceutil/trace.go:171","msg":"trace[1757893786] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"101.508731ms","start":"2026-08-13T00:57:49.822094Z","end":"2026-08-13T00:57:49.923602Z","steps":["trace[1757893786] 'process raft request' (duration: 89.926277ms)","trace[1757893786] 'compare' (duration: 10.636215ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:59:06.627546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2985} {"level":"info","ts":"2026-08-13T00:59:06.698263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2985,"took":"69.20926ms","hash":4169585588,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":34324480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-13T00:59:06.698334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4169585588,"revision":2985,"compact-revision":-1} {"level":"info","ts":"2026-08-13T01:04:06.638356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-08-13T01:04:06.731277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"91.603635ms","hash":2327847772,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":23552000,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-13T01:04:06.731359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2327847772,"revision":6103,"compact-revision":2985} {"level":"info","ts":"2026-08-13T01:04:07.44265Z","caller":"traceutil/trace.go:171","msg":"trace[1933182690] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"337.375566ms","start":"2026-08-13T01:04:07.105248Z","end":"2026-08-13T01:04:07.442623Z","steps":["trace[1933182690] 'process raft request' (duration: 337.182522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:07.442799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:07.105228Z","time spent":"337.497939ms","remote":"127.0.0.1:35128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:04:07.443655Z","caller":"traceutil/trace.go:171","msg":"trace[918722724] linearizableReadLoop","detail":"{readStateIndex:8575; appliedIndex:8573; }","duration":"103.187958ms","start":"2026-08-13T01:04:07.340441Z","end":"2026-08-13T01:04:07.443629Z","steps":["trace[918722724] 'read index received' (duration: 101.968834ms)","trace[918722724] 'applied index is now lower than readState.Index' (duration: 1.218174ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:07.443778Z","caller":"traceutil/trace.go:171","msg":"trace[1560620242] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"192.125943ms","start":"2026-08-13T01:04:07.251641Z","end":"2026-08-13T01:04:07.443767Z","steps":["trace[1560620242] 'process raft request' (duration: 191.843058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:07.44398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.536495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-13T01:04:07.444038Z","caller":"traceutil/trace.go:171","msg":"trace[1258360537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8279; }","duration":"103.633246ms","start":"2026-08-13T01:04:07.340389Z","end":"2026-08-13T01:04:07.444023Z","steps":["trace[1258360537] 'agreement among raft nodes before linearized reading' (duration: 103.453733ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:16.697899Z","caller":"traceutil/trace.go:171","msg":"trace[1677369937] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"234.680508ms","start":"2026-08-13T01:04:16.463195Z","end":"2026-08-13T01:04:16.697876Z","steps":["trace[1677369937] 'process raft request' (duration: 234.538735ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:16.784217Z","caller":"traceutil/trace.go:171","msg":"trace[386700283] linearizableReadLoop","detail":"{readStateIndex:8627; appliedIndex:8625; }","duration":"217.719752ms","start":"2026-08-13T01:04:16.566478Z","end":"2026-08-13T01:04:16.784198Z","steps":["trace[386700283] 'read index received' (duration: 131.450379ms)","trace[386700283] 'applied index is now lower than readState.Index' (duration: 86.268373ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:16.784515Z","caller":"traceutil/trace.go:171","msg":"trace[956334834] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"320.955801ms","start":"2026-08-13T01:04:16.463545Z","end":"2026-08-13T01:04:16.784501Z","steps":["trace[956334834] 'process raft request' (duration: 286.740036ms)","trace[956334834] 'compare' (duration: 33.743216ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:04:16.784627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:16.463512Z","time spent":"321.040673ms","remote":"127.0.0.1:35128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:04:16.785083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.591919ms","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-08-13T01:04:16.785131Z","caller":"traceutil/trace.go:171","msg":"trace[2124416482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8329; }","duration":"218.679271ms","start":"2026-08-13T01:04:16.566437Z","end":"2026-08-13T01:04:16.785117Z","steps":["trace[2124416482] 'agreement among raft nodes before linearized reading' (duration: 218.537328ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:16.785291Z","caller":"traceutil/trace.go:171","msg":"trace[1532318520] transaction","detail":"{read_only:false; response_revision:8329; number_of_response:1; }","duration":"320.360599ms","start":"2026-08-13T01:04:16.464921Z","end":"2026-08-13T01:04:16.785281Z","steps":["trace[1532318520] 'process raft request' (duration: 319.229887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:16.785355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:16.464909Z","time spent":"320.413279ms","remote":"127.0.0.1:35128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:04:17.651227Z","caller":"traceutil/trace.go:171","msg":"trace[950385452] linearizableReadLoop","detail":"{readStateIndex:8629; appliedIndex:8628; }","duration":"154.328327ms","start":"2026-08-13T01:04:17.496869Z","end":"2026-08-13T01:04:17.651198Z","steps":["trace[950385452] 'read index received' (duration: 154.062962ms)","trace[950385452] 'applied index is now lower than readState.Index' (duration: 263.995µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:17.65133Z","caller":"traceutil/trace.go:171","msg":"trace[1307534493] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"157.159751ms","start":"2026-08-13T01:04:17.494139Z","end":"2026-08-13T01:04:17.651299Z","steps":["trace[1307534493] 'process raft request' (duration: 156.796424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:17.651508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.625923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-13T01:04:17.651572Z","caller":"traceutil/trace.go:171","msg":"trace[870627591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8331; }","duration":"154.731266ms","start":"2026-08-13T01:04:17.496825Z","end":"2026-08-13T01:04:17.651556Z","steps":["trace[870627591] 'agreement among raft nodes before linearized reading' (duration: 154.48882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:17.651566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.667703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2g9hr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-13T01:04:17.65161Z","caller":"traceutil/trace.go:171","msg":"trace[1095063380] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2g9hr; range_end:; response_count:1; response_revision:8331; }","duration":"143.763556ms","start":"2026-08-13T01:04:17.507834Z","end":"2026-08-13T01:04:17.651597Z","steps":["trace[1095063380] 'agreement among raft nodes before linearized reading' (duration: 143.53805ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:17.757881Z","caller":"traceutil/trace.go:171","msg":"trace[793864605] linearizableReadLoop","detail":"{readStateIndex:8630; appliedIndex:8629; }","duration":"100.959525ms","start":"2026-08-13T01:04:17.656877Z","end":"2026-08-13T01:04:17.757837Z","steps":["trace[793864605] 'read index received' (duration: 74.172172ms)","trace[793864605] 'applied index is now lower than readState.Index' (duration: 26.785663ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:17.757969Z","caller":"traceutil/trace.go:171","msg":"trace[388748023] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"101.823041ms","start":"2026-08-13T01:04:17.656117Z","end":"2026-08-13T01:04:17.75794Z","steps":["trace[388748023] 'process raft request' (duration: 74.907686ms)","trace[388748023] 'compare' (duration: 26.602459ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:04:17.759096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.179849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224145"} {"level":"info","ts":"2026-08-13T01:04:17.759162Z","caller":"traceutil/trace.go:171","msg":"trace[1620859967] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8332; }","duration":"102.305091ms","start":"2026-08-13T01:04:17.656842Z","end":"2026-08-13T01:04:17.759147Z","steps":["trace[1620859967] 'agreement among raft nodes before linearized reading' (duration: 101.148249ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:18.268341Z","caller":"traceutil/trace.go:171","msg":"trace[1606587518] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"116.781228ms","start":"2026-08-13T01:04:18.151527Z","end":"2026-08-13T01:04:18.268308Z","steps":["trace[1606587518] 'process raft request' (duration: 43.753319ms)","trace[1606587518] 'compare' (duration: 72.874066ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:04:42.764949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.6024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-rbdplugin-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:42.765075Z","caller":"traceutil/trace.go:171","msg":"trace[374067971] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-rbdplugin-metrics; range_end:; response_count:0; response_revision:8520; }","duration":"122.789703ms","start":"2026-08-13T01:04:42.642261Z","end":"2026-08-13T01:04:42.765051Z","steps":["trace[374067971] 'range keys from in-memory index tree' (duration: 122.507107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:42.765419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.168108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-j78pw\" ","response":"range_response_count:1 size:4455"} {"level":"info","ts":"2026-08-13T01:04:42.765547Z","caller":"traceutil/trace.go:171","msg":"trace[1453581309] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-j78pw; range_end:; response_count:1; response_revision:8520; }","duration":"125.346561ms","start":"2026-08-13T01:04:42.640178Z","end":"2026-08-13T01:04:42.765524Z","steps":["trace[1453581309] 'range keys from in-memory index tree' (duration: 124.884152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:46.072455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.870132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-13T01:04:46.072459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.223706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2g9hr\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-13T01:04:46.072553Z","caller":"traceutil/trace.go:171","msg":"trace[1813200823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8538; }","duration":"139.014165ms","start":"2026-08-13T01:04:45.93352Z","end":"2026-08-13T01:04:46.072534Z","steps":["trace[1813200823] 'range keys from in-memory index tree' (duration: 138.678269ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:46.072559Z","caller":"traceutil/trace.go:171","msg":"trace[962040673] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2g9hr; range_end:; response_count:1; response_revision:8538; }","duration":"130.372679ms","start":"2026-08-13T01:04:45.94217Z","end":"2026-08-13T01:04:46.072542Z","steps":["trace[962040673] 'range keys from in-memory index tree' (duration: 130.066463ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:46.291101Z","caller":"traceutil/trace.go:171","msg":"trace[2023751423] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8844; }","duration":"214.269397ms","start":"2026-08-13T01:04:46.076806Z","end":"2026-08-13T01:04:46.291075Z","steps":["trace[2023751423] 'read index received' (duration: 214.139134ms)","trace[2023751423] 'applied index is now lower than readState.Index' (duration: 129.063µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:46.291156Z","caller":"traceutil/trace.go:171","msg":"trace[1927602062] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"214.96294ms","start":"2026-08-13T01:04:46.076158Z","end":"2026-08-13T01:04:46.291121Z","steps":["trace[1927602062] 'process raft request' (duration: 214.784026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:46.291539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.721195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228873"} {"level":"info","ts":"2026-08-13T01:04:46.291585Z","caller":"traceutil/trace.go:171","msg":"trace[109021780] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8539; }","duration":"214.804646ms","start":"2026-08-13T01:04:46.07677Z","end":"2026-08-13T01:04:46.291574Z","steps":["trace[109021780] 'agreement among raft nodes before linearized reading' (duration: 214.418869ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:46.292174Z","caller":"traceutil/trace.go:171","msg":"trace[765465160] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"215.146842ms","start":"2026-08-13T01:04:46.077011Z","end":"2026-08-13T01:04:46.292158Z","steps":["trace[765465160] 'process raft request' (duration: 214.763655ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:46.292359Z","caller":"traceutil/trace.go:171","msg":"trace[705256371] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"213.747936ms","start":"2026-08-13T01:04:46.078597Z","end":"2026-08-13T01:04:46.292344Z","steps":["trace[705256371] 'process raft request' (duration: 213.256076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:49.233513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.09647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:49.233598Z","caller":"traceutil/trace.go:171","msg":"trace[1599979032] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8557; }","duration":"109.235673ms","start":"2026-08-13T01:04:49.124344Z","end":"2026-08-13T01:04:49.233579Z","steps":["trace[1599979032] 'count revisions from in-memory index tree' (duration: 108.982798ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:58.074888Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022fb.wal"} {"level":"info","ts":"2026-08-13T01:04:58.573673Z","caller":"traceutil/trace.go:171","msg":"trace[708025665] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"125.451083ms","start":"2026-08-13T01:04:58.448201Z","end":"2026-08-13T01:04:58.573652Z","steps":["trace[708025665] 'process raft request' (duration: 125.322161ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:59.111845Z","caller":"traceutil/trace.go:171","msg":"trace[202465180] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"113.488515ms","start":"2026-08-13T01:04:58.998312Z","end":"2026-08-13T01:04:59.111801Z","steps":["trace[202465180] 'process raft request' (duration: 27.765982ms)","trace[202465180] 'compare' (duration: 85.510658ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:59.11186Z","caller":"traceutil/trace.go:171","msg":"trace[1374950697] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"109.801884ms","start":"2026-08-13T01:04:59.002033Z","end":"2026-08-13T01:04:59.111834Z","steps":["trace[1374950697] 'process raft request' (duration: 109.693712ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:59.733524Z","caller":"traceutil/trace.go:171","msg":"trace[221988672] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"111.23831ms","start":"2026-08-13T01:04:59.622248Z","end":"2026-08-13T01:04:59.733486Z","steps":["trace[221988672] 'process raft request' (duration: 46.776295ms)","trace[221988672] 'compare' (duration: 64.296262ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:59.937396Z","caller":"traceutil/trace.go:171","msg":"trace[231419220] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"119.770406ms","start":"2026-08-13T01:04:59.817598Z","end":"2026-08-13T01:04:59.937369Z","steps":["trace[231419220] 'process raft request' (duration: 119.586492ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:06:51.902484Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T01:06:51.908215Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-13T01:06:51.908287Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-13T01:09:06.645911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8277} {"level":"info","ts":"2026-08-13T01:09:06.725971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8277,"took":"77.510215ms","hash":2344036437,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":26488832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-13T01:09:06.726047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2344036437,"revision":8277,"compact-revision":6103} {"level":"info","ts":"2026-08-13T01:10:00.189335Z","caller":"traceutil/trace.go:171","msg":"trace[1059726672] transaction","detail":"{read_only:false; response_revision:11255; number_of_response:1; }","duration":"210.497768ms","start":"2026-08-13T01:09:59.978791Z","end":"2026-08-13T01:10:00.189288Z","steps":["trace[1059726672] 'process raft request' (duration: 169.355445ms)","trace[1059726672] 'compare' (duration: 40.670924ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:14:06.652286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10762} {"level":"info","ts":"2026-08-13T01:14:06.737031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10762,"took":"82.305102ms","hash":1940960550,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":27058176,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-13T01:14:06.737111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1940960550,"revision":10762,"compact-revision":8277} {"level":"info","ts":"2026-08-13T01:16:40.719596Z","caller":"traceutil/trace.go:171","msg":"trace[958056447] transaction","detail":"{read_only:false; response_revision:13910; number_of_response:1; }","duration":"124.074869ms","start":"2026-08-13T01:16:40.595478Z","end":"2026-08-13T01:16:40.719553Z","steps":["trace[958056447] 'process raft request' (duration: 123.863765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:16:57.188968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.296418ms","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-08-13T01:16:57.189094Z","caller":"traceutil/trace.go:171","msg":"trace[1539374623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14005; }","duration":"363.473622ms","start":"2026-08-13T01:16:56.8256Z","end":"2026-08-13T01:16:57.189073Z","steps":["trace[1539374623] 'count revisions from in-memory index tree' (duration: 363.147906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:16:57.189164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:16:56.825588Z","time spent":"363.542853ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-13T01:16:57.189187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.911664ms","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-08-13T01:16:57.189298Z","caller":"traceutil/trace.go:171","msg":"trace[1348695389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14005; }","duration":"326.099238ms","start":"2026-08-13T01:16:56.863172Z","end":"2026-08-13T01:16:57.189271Z","steps":["trace[1348695389] 'range keys from in-memory index tree' (duration: 325.611608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:16:57.189361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:16:56.86316Z","time spent":"326.187589ms","remote":"127.0.0.1:35128","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-08-13T01:16:57.189632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.848863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:16:57.189784Z","caller":"traceutil/trace.go:171","msg":"trace[1198513178] transaction","detail":"{read_only:false; response_revision:14006; number_of_response:1; }","duration":"242.629973ms","start":"2026-08-13T01:16:56.947131Z","end":"2026-08-13T01:16:57.189761Z","steps":["trace[1198513178] 'process raft request' (duration: 61.544545ms)","trace[1198513178] 'compare' (duration: 180.15572ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:18:48.855706Z","caller":"traceutil/trace.go:171","msg":"trace[897291625] transaction","detail":"{read_only:false; response_revision:15145; number_of_response:1; }","duration":"173.623186ms","start":"2026-08-13T01:18:48.682054Z","end":"2026-08-13T01:18:48.855677Z","steps":["trace[897291625] 'process raft request' (duration: 173.381171ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:18:49.734369Z","caller":"traceutil/trace.go:171","msg":"trace[585147491] linearizableReadLoop","detail":"{readStateIndex:15667; appliedIndex:15666; }","duration":"112.502609ms","start":"2026-08-13T01:18:49.621827Z","end":"2026-08-13T01:18:49.734329Z","steps":["trace[585147491] 'read index received' (duration: 110.352138ms)","trace[585147491] 'applied index is now lower than readState.Index' (duration: 2.149091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:18:49.734755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.829605ms","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-08-13T01:18:49.734765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.918967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-13T01:18:49.734826Z","caller":"traceutil/trace.go:171","msg":"trace[167571652] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15147; }","duration":"113.032719ms","start":"2026-08-13T01:18:49.621781Z","end":"2026-08-13T01:18:49.734814Z","steps":["trace[167571652] 'agreement among raft nodes before linearized reading' (duration: 112.833985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:18:49.734936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.610631ms","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-08-13T01:18:49.734972Z","caller":"traceutil/trace.go:171","msg":"trace[908414441] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15147; }","duration":"112.690862ms","start":"2026-08-13T01:18:49.622271Z","end":"2026-08-13T01:18:49.734962Z","steps":["trace[908414441] 'agreement among raft nodes before linearized reading' (duration: 112.628541ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:18:49.734789Z","caller":"traceutil/trace.go:171","msg":"trace[126565483] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15147; }","duration":"112.928846ms","start":"2026-08-13T01:18:49.621849Z","end":"2026-08-13T01:18:49.734778Z","steps":["trace[126565483] 'agreement among raft nodes before linearized reading' (duration: 112.832035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:18:49.735021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.763133ms","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-08-13T01:18:49.735096Z","caller":"traceutil/trace.go:171","msg":"trace[641531221] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15147; }","duration":"112.855695ms","start":"2026-08-13T01:18:49.62222Z","end":"2026-08-13T01:18:49.735076Z","steps":["trace[641531221] 'agreement among raft nodes before linearized reading' (duration: 112.727173ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:18:49.734384Z","caller":"traceutil/trace.go:171","msg":"trace[846726998] transaction","detail":"{read_only:false; response_revision:15147; number_of_response:1; }","duration":"132.514191ms","start":"2026-08-13T01:18:49.601815Z","end":"2026-08-13T01:18:49.734329Z","steps":["trace[846726998] 'process raft request' (duration: 130.461932ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:19:06.659242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12836} {"level":"info","ts":"2026-08-13T01:19:06.735608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12836,"took":"72.731919ms","hash":1550272833,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":29720576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T01:19:06.735671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1550272833,"revision":12836,"compact-revision":10762} {"level":"info","ts":"2026-08-13T01:24:06.671944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15237} {"level":"info","ts":"2026-08-13T01:24:06.762192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15237,"took":"87.198128ms","hash":3505308867,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-13T01:24:06.762267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3505308867,"revision":15237,"compact-revision":12836} {"level":"info","ts":"2026-08-13T01:24:51.13223Z","caller":"traceutil/trace.go:171","msg":"trace[1905598700] transaction","detail":"{read_only:false; response_revision:17807; number_of_response:1; }","duration":"199.237271ms","start":"2026-08-13T01:24:50.932957Z","end":"2026-08-13T01:24:51.132194Z","steps":["trace[1905598700] 'process raft request' (duration: 199.085578ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:24:51.590586Z","caller":"traceutil/trace.go:171","msg":"trace[779613525] transaction","detail":"{read_only:false; response_revision:17808; number_of_response:1; }","duration":"285.557032ms","start":"2026-08-13T01:24:51.304998Z","end":"2026-08-13T01:24:51.590555Z","steps":["trace[779613525] 'process raft request' (duration: 285.266697ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:24:51.746074Z","caller":"traceutil/trace.go:171","msg":"trace[641480983] transaction","detail":"{read_only:false; response_revision:17809; number_of_response:1; }","duration":"440.944054ms","start":"2026-08-13T01:24:51.305093Z","end":"2026-08-13T01:24:51.746037Z","steps":["trace[641480983] 'process raft request' (duration: 440.682769ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:24:51.746088Z","caller":"traceutil/trace.go:171","msg":"trace[1194949111] transaction","detail":"{read_only:false; response_revision:17810; number_of_response:1; }","duration":"439.275192ms","start":"2026-08-13T01:24:51.306785Z","end":"2026-08-13T01:24:51.74606Z","steps":["trace[1194949111] 'process raft request' (duration: 439.16118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:24:51.746242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:24:51.305058Z","time spent":"441.098327ms","remote":"127.0.0.1:35128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:24:51.746303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:24:51.30676Z","time spent":"439.426176ms","remote":"127.0.0.1:35128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:24:51.746478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.293682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-ttk4r\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-08-13T01:24:51.746554Z","caller":"traceutil/trace.go:171","msg":"trace[2050851378] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-ttk4r; range_end:; response_count:1; response_revision:17810; }","duration":"417.440494ms","start":"2026-08-13T01:24:51.329095Z","end":"2026-08-13T01:24:51.746535Z","steps":["trace[2050851378] 'agreement among raft nodes before linearized reading' (duration: 417.244171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:24:51.746597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:24:51.329079Z","time spent":"417.510226ms","remote":"127.0.0.1:35040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-ttk4r\" "} {"level":"info","ts":"2026-08-13T01:24:51.746669Z","caller":"traceutil/trace.go:171","msg":"trace[8560783] linearizableReadLoop","detail":"{readStateIndex:18421; appliedIndex:18418; }","duration":"416.835493ms","start":"2026-08-13T01:24:51.329174Z","end":"2026-08-13T01:24:51.74601Z","steps":["trace[8560783] 'read index received' (duration: 261.159816ms)","trace[8560783] 'applied index is now lower than readState.Index' (duration: 155.674537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:24:51.74845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.87068ms","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-08-13T01:24:51.748528Z","caller":"traceutil/trace.go:171","msg":"trace[1010156262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17811; }","duration":"283.995392ms","start":"2026-08-13T01:24:51.464515Z","end":"2026-08-13T01:24:51.74851Z","steps":["trace[1010156262] 'agreement among raft nodes before linearized reading' (duration: 283.754408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:24:51.748883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.788915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-13T01:24:51.74892Z","caller":"traceutil/trace.go:171","msg":"trace[540820780] transaction","detail":"{read_only:false; response_revision:17811; number_of_response:1; }","duration":"118.041087ms","start":"2026-08-13T01:24:51.630856Z","end":"2026-08-13T01:24:51.748897Z","steps":["trace[540820780] 'process raft request' (duration: 117.248223ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:24:51.748938Z","caller":"traceutil/trace.go:171","msg":"trace[396959346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17811; }","duration":"184.886606ms","start":"2026-08-13T01:24:51.564038Z","end":"2026-08-13T01:24:51.748925Z","steps":["trace[396959346] 'agreement among raft nodes before linearized reading' (duration: 184.749203ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:27:54.261885Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T01:27:54.268215Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-13T01:27:54.26832Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-13T01:29:06.678014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17511} {"level":"info","ts":"2026-08-13T01:29:06.770441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17511,"took":"89.411181ms","hash":3575246646,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":33808384,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-13T01:29:06.770513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3575246646,"revision":17511,"compact-revision":15237} {"level":"warn","ts":"2026-08-13T01:31:12.431979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.605274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T01:31:12.432103Z","caller":"traceutil/trace.go:171","msg":"trace[526582289] linearizableReadLoop","detail":"{readStateIndex:21376; appliedIndex:21375; }","duration":"264.049511ms","start":"2026-08-13T01:31:12.168034Z","end":"2026-08-13T01:31:12.432083Z","steps":["trace[526582289] 'read index received' (duration: 64.591µs)","trace[526582289] 'applied index is now lower than readState.Index' (duration: 263.98385ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:31:12.432276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.247615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-13T01:31:12.432262Z","caller":"traceutil/trace.go:171","msg":"trace[1287974193] transaction","detail":"{read_only:false; response_revision:20669; number_of_response:1; }","duration":"264.205364ms","start":"2026-08-13T01:31:12.168004Z","end":"2026-08-13T01:31:12.43221Z","steps":["trace[1287974193] 'process raft request' (duration: 128.278654ms)","trace[1287974193] 'compare' (duration: 135.366049ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:31:12.432322Z","caller":"traceutil/trace.go:171","msg":"trace[1566036996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20669; }","duration":"264.331596ms","start":"2026-08-13T01:31:12.167977Z","end":"2026-08-13T01:31:12.432308Z","steps":["trace[1566036996] 'agreement among raft nodes before linearized reading' (duration: 264.157903ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:31:12.589743Z","caller":"traceutil/trace.go:171","msg":"trace[2000096455] transaction","detail":"{read_only:false; response_revision:20670; number_of_response:1; }","duration":"153.092338ms","start":"2026-08-13T01:31:12.436621Z","end":"2026-08-13T01:31:12.589713Z","steps":["trace[2000096455] 'process raft request' (duration: 112.605103ms)","trace[2000096455] 'compare' (duration: 40.258431ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:31:12.881706Z","caller":"traceutil/trace.go:171","msg":"trace[1052758537] linearizableReadLoop","detail":"{readStateIndex:21379; appliedIndex:21378; }","duration":"192.601904ms","start":"2026-08-13T01:31:12.689071Z","end":"2026-08-13T01:31:12.881672Z","steps":["trace[1052758537] 'read index received' (duration: 192.400971ms)","trace[1052758537] 'applied index is now lower than readState.Index' (duration: 199.613µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:31:12.881864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.771638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T01:31:12.8819Z","caller":"traceutil/trace.go:171","msg":"trace[1701432521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20672; }","duration":"192.870249ms","start":"2026-08-13T01:31:12.689016Z","end":"2026-08-13T01:31:12.881886Z","steps":["trace[1701432521] 'agreement among raft nodes before linearized reading' (duration: 192.781418ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:31:12.88185Z","caller":"traceutil/trace.go:171","msg":"trace[1382585150] transaction","detail":"{read_only:false; response_revision:20672; number_of_response:1; }","duration":"250.958161ms","start":"2026-08-13T01:31:12.630844Z","end":"2026-08-13T01:31:12.881802Z","steps":["trace[1382585150] 'process raft request' (duration: 250.646345ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:31:13.051145Z","caller":"traceutil/trace.go:171","msg":"trace[881170560] transaction","detail":"{read_only:false; response_revision:20673; number_of_response:1; }","duration":"110.817949ms","start":"2026-08-13T01:31:12.940287Z","end":"2026-08-13T01:31:13.051105Z","steps":["trace[881170560] 'process raft request' (duration: 109.841ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:34:06.687867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19798} {"level":"info","ts":"2026-08-13T01:34:06.792723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19798,"took":"100.478242ms","hash":2770938979,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":29245440,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-13T01:34:06.792807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2770938979,"revision":19798,"compact-revision":17511} {"level":"info","ts":"2026-08-13T01:35:03.295226Z","caller":"traceutil/trace.go:171","msg":"trace[151580647] transaction","detail":"{read_only:false; response_revision:22893; number_of_response:1; }","duration":"153.32135ms","start":"2026-08-13T01:35:03.141885Z","end":"2026-08-13T01:35:03.295207Z","steps":["trace[151580647] 'process raft request' (duration: 152.915973ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:03.295664Z","caller":"traceutil/trace.go:171","msg":"trace[1519161637] transaction","detail":"{read_only:false; response_revision:22894; number_of_response:1; }","duration":"148.289195ms","start":"2026-08-13T01:35:03.147351Z","end":"2026-08-13T01:35:03.29564Z","steps":["trace[1519161637] 'process raft request' (duration: 147.801314ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:03.29574Z","caller":"traceutil/trace.go:171","msg":"trace[1246843879] transaction","detail":"{read_only:false; response_revision:22895; number_of_response:1; }","duration":"138.490147ms","start":"2026-08-13T01:35:03.157239Z","end":"2026-08-13T01:35:03.295729Z","steps":["trace[1246843879] 'process raft request' (duration: 138.264722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:35:03.29594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.662143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T01:35:03.296019Z","caller":"traceutil/trace.go:171","msg":"trace[1814900963] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io; range_end:; response_count:0; response_revision:22895; }","duration":"147.777795ms","start":"2026-08-13T01:35:03.148226Z","end":"2026-08-13T01:35:03.296004Z","steps":["trace[1814900963] 'agreement among raft nodes before linearized reading' (duration: 147.659273ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:03.295793Z","caller":"traceutil/trace.go:171","msg":"trace[852957538] linearizableReadLoop","detail":"{readStateIndex:23672; appliedIndex:23669; }","duration":"147.51279ms","start":"2026-08-13T01:35:03.148254Z","end":"2026-08-13T01:35:03.295767Z","steps":["trace[852957538] 'read index received' (duration: 15.993857ms)","trace[852957538] 'applied index is now lower than readState.Index' (duration: 131.517163ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:35:03.330832Z","caller":"traceutil/trace.go:171","msg":"trace[1563125696] transaction","detail":"{read_only:false; number_of_response:1; response_revision:22895; }","duration":"130.643446ms","start":"2026-08-13T01:35:03.200155Z","end":"2026-08-13T01:35:03.330798Z","steps":["trace[1563125696] 'process raft request' (duration: 129.7857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:35:03.330916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.112818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-08-13T01:35:03.330971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.544223ms","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-08-13T01:35:03.331047Z","caller":"traceutil/trace.go:171","msg":"trace[733306991] range","detail":"{range_begin:/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager; range_end:; response_count:1; response_revision:22895; }","duration":"164.302542ms","start":"2026-08-13T01:35:03.166715Z","end":"2026-08-13T01:35:03.331017Z","steps":["trace[733306991] 'agreement among raft nodes before linearized reading' (duration: 163.985646ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:03.33107Z","caller":"traceutil/trace.go:171","msg":"trace[1163664620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22895; }","duration":"108.687065ms","start":"2026-08-13T01:35:03.222353Z","end":"2026-08-13T01:35:03.33104Z","steps":["trace[1163664620] 'agreement among raft nodes before linearized reading' (duration: 108.406429ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:08.163707Z","caller":"traceutil/trace.go:171","msg":"trace[374864280] transaction","detail":"{read_only:false; response_revision:23084; number_of_response:1; }","duration":"191.894561ms","start":"2026-08-13T01:35:07.971785Z","end":"2026-08-13T01:35:08.163679Z","steps":["trace[374864280] 'process raft request' (duration: 191.818019ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:08.163724Z","caller":"traceutil/trace.go:171","msg":"trace[270060508] transaction","detail":"{read_only:false; response_revision:23083; number_of_response:1; }","duration":"193.149605ms","start":"2026-08-13T01:35:07.97054Z","end":"2026-08-13T01:35:08.16369Z","steps":["trace[270060508] 'process raft request' (duration: 192.90115ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:08.164101Z","caller":"traceutil/trace.go:171","msg":"trace[559541384] linearizableReadLoop","detail":"{readStateIndex:23865; appliedIndex:23862; }","duration":"185.60087ms","start":"2026-08-13T01:35:07.97848Z","end":"2026-08-13T01:35:08.164081Z","steps":["trace[559541384] 'read index received' (duration: 37.422768ms)","trace[559541384] 'applied index is now lower than readState.Index' (duration: 148.177522ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:35:08.164099Z","caller":"traceutil/trace.go:171","msg":"trace[1042669912] transaction","detail":"{read_only:false; response_revision:23085; number_of_response:1; }","duration":"190.471224ms","start":"2026-08-13T01:35:07.973593Z","end":"2026-08-13T01:35:08.164064Z","steps":["trace[1042669912] 'process raft request' (duration: 190.049246ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:08.16421Z","caller":"traceutil/trace.go:171","msg":"trace[2078476577] transaction","detail":"{read_only:false; response_revision:23086; number_of_response:1; }","duration":"156.077723ms","start":"2026-08-13T01:35:08.008113Z","end":"2026-08-13T01:35:08.164191Z","steps":["trace[2078476577] 'process raft request' (duration: 155.762087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:35:08.164316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.815304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanes.controlplane.cluster.x-k8s.io\" ","response":"range_response_count:1 size:199451"} {"level":"info","ts":"2026-08-13T01:35:08.164346Z","caller":"traceutil/trace.go:171","msg":"trace[1824627179] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/kubeadmcontrolplanes.controlplane.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23086; }","duration":"185.885505ms","start":"2026-08-13T01:35:07.978452Z","end":"2026-08-13T01:35:08.164338Z","steps":["trace[1824627179] 'agreement among raft nodes before linearized reading' (duration: 185.709252ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:08.167701Z","caller":"traceutil/trace.go:171","msg":"trace[13769261] transaction","detail":"{read_only:false; response_revision:23088; number_of_response:1; }","duration":"144.839488ms","start":"2026-08-13T01:35:08.022843Z","end":"2026-08-13T01:35:08.167682Z","steps":["trace[13769261] 'process raft request' (duration: 144.598734ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:35:08.167548Z","caller":"traceutil/trace.go:171","msg":"trace[1790385244] transaction","detail":"{read_only:false; response_revision:23087; number_of_response:1; }","duration":"144.712926ms","start":"2026-08-13T01:35:08.022804Z","end":"2026-08-13T01:35:08.167517Z","steps":["trace[1790385244] 'process raft request' (duration: 144.520612ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:39:06.694373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22026} {"level":"info","ts":"2026-08-13T01:39:06.80122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22026,"took":"102.688599ms","hash":3688248894,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":42442752,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-13T01:39:06.801303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3688248894,"revision":22026,"compact-revision":19798} {"level":"info","ts":"2026-08-13T01:44:06.699945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25207} {"level":"info","ts":"2026-08-13T01:44:06.828424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25207,"took":"125.573898ms","hash":3772425233,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":29700096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T01:44:06.828481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3772425233,"revision":25207,"compact-revision":22026}