{"level":"warn","ts":"2026-10-26T23:51:50.501416Z","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-26T23:51:50.501524Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.126: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.126:2380","--initial-cluster=instance=https://162.253.55.126:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.126:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.126: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-26T23:51:50.501623Z","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-26T23:51:50.501714Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.126:2380"]} {"level":"info","ts":"2026-10-26T23:51:50.501749Z","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-26T23:51:50.502829Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"]} {"level":"info","ts":"2026-10-26T23:51:50.502923Z","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.126:2380"],"listen-peer-urls":["https://162.253.55.126:2380"],"advertise-client-urls":["https://162.253.55.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.126: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-26T23:51:50.505867Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.300304ms"} {"level":"info","ts":"2026-10-26T23:51:50.512116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"85ebf4ab7bdf9269","cluster-id":"f923bca632e7887a"} {"level":"info","ts":"2026-10-26T23:51:50.512357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T23:51:50.512385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became follower at term 0"} {"level":"info","ts":"2026-10-26T23:51:50.512446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 85ebf4ab7bdf9269 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T23:51:50.512511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became follower at term 1"} {"level":"info","ts":"2026-10-26T23:51:50.512591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=(9650075643926254185)"} {"level":"warn","ts":"2026-10-26T23:51:50.51876Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T23:51:50.530126Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T23:51:50.531758Z","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-26T23:51:50.534997Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"85ebf4ab7bdf9269","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T23:51:50.535263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"85ebf4ab7bdf9269","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T23:51:50.536613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 switched to configuration voters=(9650075643926254185)"} {"level":"info","ts":"2026-10-26T23:51:50.53685Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f923bca632e7887a","local-member-id":"85ebf4ab7bdf9269","added-peer-id":"85ebf4ab7bdf9269","added-peer-peer-urls":["https://162.253.55.126:2380"]} {"level":"info","ts":"2026-10-26T23:51:50.537057Z","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-26T23:51:50.537152Z","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-26T23:51:50.537184Z","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-26T23:51:50.546168Z","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-26T23:51:50.546285Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.126:2380"} {"level":"info","ts":"2026-10-26T23:51:50.546343Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.126:2380"} {"level":"info","ts":"2026-10-26T23:51:50.547952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"85ebf4ab7bdf9269","initial-advertise-peer-urls":["https://162.253.55.126:2380"],"listen-peer-urls":["https://162.253.55.126:2380"],"advertise-client-urls":["https://162.253.55.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T23:51:50.54804Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T23:51:50.713539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T23:51:50.713609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T23:51:50.713682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 received MsgPreVoteResp from 85ebf4ab7bdf9269 at term 1"} {"level":"info","ts":"2026-10-26T23:51:50.713702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became candidate at term 2"} {"level":"info","ts":"2026-10-26T23:51:50.713713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 received MsgVoteResp from 85ebf4ab7bdf9269 at term 2"} {"level":"info","ts":"2026-10-26T23:51:50.713727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"85ebf4ab7bdf9269 became leader at term 2"} {"level":"info","ts":"2026-10-26T23:51:50.71374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 85ebf4ab7bdf9269 elected leader 85ebf4ab7bdf9269 at term 2"} {"level":"info","ts":"2026-10-26T23:51:50.714881Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"85ebf4ab7bdf9269","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.126:2379]}","request-path":"/0/members/85ebf4ab7bdf9269/attributes","cluster-id":"f923bca632e7887a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T23:51:50.71492Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T23:51:50.714926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:50.715001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T23:51:50.715124Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T23:51:50.715377Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T23:51:50.716011Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f923bca632e7887a","local-member-id":"85ebf4ab7bdf9269","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:50.716126Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:50.71617Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T23:51:50.717749Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.126:2379"} {"level":"info","ts":"2026-10-26T23:51:50.71858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T23:52:45.246502Z","caller":"traceutil/trace.go:171","msg":"trace[1958919387] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"157.243382ms","start":"2026-10-26T23:52:45.089222Z","end":"2026-10-26T23:52:45.246465Z","steps":["trace[1958919387] 'process raft request' (duration: 82.608811ms)","trace[1958919387] 'compare' (duration: 74.464226ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T23:52:45.428855Z","caller":"traceutil/trace.go:171","msg":"trace[420917800] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"123.478019ms","start":"2026-10-26T23:52:45.305339Z","end":"2026-10-26T23:52:45.428817Z","steps":["trace[420917800] 'process raft request' (duration: 105.568935ms)","trace[420917800] 'compare' (duration: 17.711609ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T23:52:45.890515Z","caller":"traceutil/trace.go:171","msg":"trace[1444677391] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"205.860643ms","start":"2026-10-26T23:52:45.684617Z","end":"2026-10-26T23:52:45.890477Z","steps":["trace[1444677391] 'process raft request' (duration: 204.826475ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:53:01.071797Z","caller":"traceutil/trace.go:171","msg":"trace[1830199026] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"183.230739ms","start":"2026-10-26T23:53:00.888534Z","end":"2026-10-26T23:53:01.071765Z","steps":["trace[1830199026] 'process raft request' (duration: 177.719017ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:53:30.740689Z","caller":"traceutil/trace.go:171","msg":"trace[1733971151] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"142.125086ms","start":"2026-10-26T23:53:30.59852Z","end":"2026-10-26T23:53:30.740645Z","steps":["trace[1733971151] 'process raft request' (duration: 141.952821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:54:19.413094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.934567ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-10-26T23:54:19.849183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.91116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T23:54:19.849295Z","caller":"traceutil/trace.go:171","msg":"trace[2101895300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1912; }","duration":"150.189078ms","start":"2026-10-26T23:54:19.69908Z","end":"2026-10-26T23:54:19.849269Z","steps":["trace[2101895300] 'range keys from in-memory index tree' (duration: 149.826758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:54:19.85034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.83416ms","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-26T23:54:19.85048Z","caller":"traceutil/trace.go:171","msg":"trace[349072518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1912; }","duration":"184.28205ms","start":"2026-10-26T23:54:19.666169Z","end":"2026-10-26T23:54:19.850451Z","steps":["trace[349072518] 'range keys from in-memory index tree' (duration: 182.624194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:55:10.040299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.658904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T23:55:10.040413Z","caller":"traceutil/trace.go:171","msg":"trace[2035456005] linearizableReadLoop","detail":"{readStateIndex:2321; appliedIndex:2320; }","duration":"228.787281ms","start":"2026-10-26T23:55:09.811609Z","end":"2026-10-26T23:55:10.040397Z","steps":["trace[2035456005] 'read index received' (duration: 96.519741ms)","trace[2035456005] 'applied index is now lower than readState.Index' (duration: 132.26632ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:55:10.040563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.948765ms","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-26T23:55:10.040622Z","caller":"traceutil/trace.go:171","msg":"trace[558482981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2233; }","duration":"229.057719ms","start":"2026-10-26T23:55:09.811554Z","end":"2026-10-26T23:55:10.040611Z","steps":["trace[558482981] 'agreement among raft nodes before linearized reading' (duration: 228.916825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:55:10.040665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.807748ms","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-26T23:55:10.040755Z","caller":"traceutil/trace.go:171","msg":"trace[1911200037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2233; }","duration":"203.985882ms","start":"2026-10-26T23:55:09.836752Z","end":"2026-10-26T23:55:10.040738Z","steps":["trace[1911200037] 'agreement among raft nodes before linearized reading' (duration: 203.738776ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:55:10.040452Z","caller":"traceutil/trace.go:171","msg":"trace[1298380040] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"253.705943ms","start":"2026-10-26T23:55:09.786714Z","end":"2026-10-26T23:55:10.04042Z","steps":["trace[1298380040] 'process raft request' (duration: 121.493775ms)","trace[1298380040] 'compare' (duration: 131.55342ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T23:55:10.191848Z","caller":"traceutil/trace.go:171","msg":"trace[1552695134] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"147.386663ms","start":"2026-10-26T23:55:10.044435Z","end":"2026-10-26T23:55:10.191822Z","steps":["trace[1552695134] 'process raft request' (duration: 147.237839ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:55:10.203521Z","caller":"traceutil/trace.go:171","msg":"trace[1410883036] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"159.042173ms","start":"2026-10-26T23:55:10.044462Z","end":"2026-10-26T23:55:10.203504Z","steps":["trace[1410883036] 'process raft request' (duration: 158.898979ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:55:15.579735Z","caller":"traceutil/trace.go:171","msg":"trace[1947664330] linearizableReadLoop","detail":"{readStateIndex:2351; appliedIndex:2350; }","duration":"151.449755ms","start":"2026-10-26T23:55:15.428265Z","end":"2026-10-26T23:55:15.579715Z","steps":["trace[1947664330] 'read index received' (duration: 151.220708ms)","trace[1947664330] 'applied index is now lower than readState.Index' (duration: 228.187µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T23:55:15.579842Z","caller":"traceutil/trace.go:171","msg":"trace[1895743862] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"184.065508ms","start":"2026-10-26T23:55:15.395754Z","end":"2026-10-26T23:55:15.57982Z","steps":["trace[1895743862] 'process raft request' (duration: 183.812481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:55:15.57991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.634779ms","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":"info","ts":"2026-10-26T23:55:15.579942Z","caller":"traceutil/trace.go:171","msg":"trace[1504338732] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2262; }","duration":"151.701302ms","start":"2026-10-26T23:55:15.428231Z","end":"2026-10-26T23:55:15.579932Z","steps":["trace[1504338732] 'agreement among raft nodes before linearized reading' (duration: 151.584858ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:56:34.73914Z","caller":"traceutil/trace.go:171","msg":"trace[1315547758] linearizableReadLoop","detail":"{readStateIndex:2996; appliedIndex:2995; }","duration":"152.262485ms","start":"2026-10-26T23:56:34.586847Z","end":"2026-10-26T23:56:34.739109Z","steps":["trace[1315547758] 'read index received' (duration: 119.49577ms)","trace[1315547758] 'applied index is now lower than readState.Index' (duration: 32.765355ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T23:56:34.739152Z","caller":"traceutil/trace.go:171","msg":"trace[1703732793] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"153.042636ms","start":"2026-10-26T23:56:34.586083Z","end":"2026-10-26T23:56:34.739126Z","steps":["trace[1703732793] 'process raft request' (duration: 120.350714ms)","trace[1703732793] 'compare' (duration: 32.403015ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:56:34.739391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.529022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T23:56:34.73943Z","caller":"traceutil/trace.go:171","msg":"trace[487616472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2884; }","duration":"152.581844ms","start":"2026-10-26T23:56:34.586838Z","end":"2026-10-26T23:56:34.73942Z","steps":["trace[487616472] 'agreement among raft nodes before linearized reading' (duration: 152.424479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:56:34.73946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.198705ms","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-26T23:56:34.739511Z","caller":"traceutil/trace.go:171","msg":"trace[1078311320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2884; }","duration":"101.325509ms","start":"2026-10-26T23:56:34.63817Z","end":"2026-10-26T23:56:34.739496Z","steps":["trace[1078311320] 'agreement among raft nodes before linearized reading' (duration: 101.099582ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:56:34.937477Z","caller":"traceutil/trace.go:171","msg":"trace[359386946] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"137.806276ms","start":"2026-10-26T23:56:34.799639Z","end":"2026-10-26T23:56:34.937446Z","steps":["trace[359386946] 'process raft request' (duration: 115.782617ms)","trace[359386946] 'compare' (duration: 21.848964ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:56:35.248282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.901185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T23:56:35.248639Z","caller":"traceutil/trace.go:171","msg":"trace[1872801811] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"268.860025ms","start":"2026-10-26T23:56:34.979747Z","end":"2026-10-26T23:56:35.248607Z","steps":["trace[1872801811] 'process raft request' (duration: 78.513618ms)","trace[1872801811] 'compare' (duration: 189.594456ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T23:59:41.824066Z","caller":"traceutil/trace.go:171","msg":"trace[177140200] transaction","detail":"{read_only:false; response_revision:4072; number_of_response:1; }","duration":"153.235915ms","start":"2026-10-26T23:59:41.67081Z","end":"2026-10-26T23:59:41.824046Z","steps":["trace[177140200] 'process raft request' (duration: 153.136113ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T23:59:41.824221Z","caller":"traceutil/trace.go:171","msg":"trace[46223577] linearizableReadLoop","detail":"{readStateIndex:4228; appliedIndex:4228; }","duration":"152.771303ms","start":"2026-10-26T23:59:41.671421Z","end":"2026-10-26T23:59:41.824193Z","steps":["trace[46223577] 'read index received' (duration: 152.757602ms)","trace[46223577] 'applied index is now lower than readState.Index' (duration: 11.33µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T23:59:41.824477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.378628ms","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-26T23:59:41.824515Z","caller":"traceutil/trace.go:171","msg":"trace[1458396524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4072; }","duration":"145.45689ms","start":"2026-10-26T23:59:41.679048Z","end":"2026-10-26T23:59:41.824505Z","steps":["trace[1458396524] 'agreement among raft nodes before linearized reading' (duration: 145.275025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T23:59:41.824578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.141203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T23:59:41.824624Z","caller":"traceutil/trace.go:171","msg":"trace[10994442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4072; }","duration":"153.217704ms","start":"2026-10-26T23:59:41.671395Z","end":"2026-10-26T23:59:41.824613Z","steps":["trace[10994442] 'agreement among raft nodes before linearized reading' (duration: 153.08277ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.412369Z","caller":"traceutil/trace.go:171","msg":"trace[1928022612] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"153.059568ms","start":"2026-10-27T00:01:10.259281Z","end":"2026-10-27T00:01:10.412341Z","steps":["trace[1928022612] 'process raft request' (duration: 145.377865ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.413493Z","caller":"traceutil/trace.go:171","msg":"trace[724887847] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"153.284555ms","start":"2026-10-27T00:01:10.260169Z","end":"2026-10-27T00:01:10.413453Z","steps":["trace[724887847] 'process raft request' (duration: 153.133951ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.413582Z","caller":"traceutil/trace.go:171","msg":"trace[1243086002] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"154.178309ms","start":"2026-10-27T00:01:10.259368Z","end":"2026-10-27T00:01:10.413546Z","steps":["trace[1243086002] 'process raft request' (duration: 152.899624ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.413593Z","caller":"traceutil/trace.go:171","msg":"trace[812524133] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"154.120338ms","start":"2026-10-27T00:01:10.259444Z","end":"2026-10-27T00:01:10.413564Z","steps":["trace[812524133] 'process raft request' (duration: 153.798739ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.413687Z","caller":"traceutil/trace.go:171","msg":"trace[163951519] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"139.421831ms","start":"2026-10-27T00:01:10.274238Z","end":"2026-10-27T00:01:10.41366Z","steps":["trace[163951519] 'process raft request' (duration: 139.155733ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.413708Z","caller":"traceutil/trace.go:171","msg":"trace[1523907364] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5360; }","duration":"133.403924ms","start":"2026-10-27T00:01:10.28028Z","end":"2026-10-27T00:01:10.413684Z","steps":["trace[1523907364] 'read index received' (duration: 124.477327ms)","trace[1523907364] 'applied index is now lower than readState.Index' (duration: 8.925447ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:01:10.413753Z","caller":"traceutil/trace.go:171","msg":"trace[1162525331] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"153.044388ms","start":"2026-10-27T00:01:10.260694Z","end":"2026-10-27T00:01:10.413738Z","steps":["trace[1162525331] 'process raft request' (duration: 152.640856ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.413779Z","caller":"traceutil/trace.go:171","msg":"trace[623985430] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"128.266412ms","start":"2026-10-27T00:01:10.2855Z","end":"2026-10-27T00:01:10.413766Z","steps":["trace[623985430] 'process raft request' (duration: 127.967514ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.413819Z","caller":"traceutil/trace.go:171","msg":"trace[270536221] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"127.10225ms","start":"2026-10-27T00:01:10.286704Z","end":"2026-10-27T00:01:10.413806Z","steps":["trace[270536221] 'process raft request' (duration: 126.882573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:01:10.413972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.186902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-memcached-results-cache.18e239e8005006e6\" ","response":"range_response_count:1 size:725"} {"level":"warn","ts":"2026-10-27T00:01:10.414003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.775532ms","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-27T00:01:10.414015Z","caller":"traceutil/trace.go:171","msg":"trace[740096839] range","detail":"{range_begin:/registry/events/monitoring/loki-memcached-results-cache.18e239e8005006e6; range_end:; response_count:1; response_revision:5168; }","duration":"131.271485ms","start":"2026-10-27T00:01:10.28273Z","end":"2026-10-27T00:01:10.414001Z","steps":["trace[740096839] 'agreement among raft nodes before linearized reading' (duration: 131.10442ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:10.414041Z","caller":"traceutil/trace.go:171","msg":"trace[2019442697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5168; }","duration":"134.868455ms","start":"2026-10-27T00:01:10.279159Z","end":"2026-10-27T00:01:10.414027Z","steps":["trace[2019442697] 'agreement among raft nodes before linearized reading' (duration: 134.68867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:01:10.414177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.042381ms","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-27T00:01:10.414253Z","caller":"traceutil/trace.go:171","msg":"trace[1426640043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5168; }","duration":"130.153524ms","start":"2026-10-27T00:01:10.284081Z","end":"2026-10-27T00:01:10.414235Z","steps":["trace[1426640043] 'agreement among raft nodes before linearized reading' (duration: 129.935978ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:11.956188Z","caller":"traceutil/trace.go:171","msg":"trace[1968112594] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"151.717662ms","start":"2026-10-27T00:01:11.804445Z","end":"2026-10-27T00:01:11.956162Z","steps":["trace[1968112594] 'process raft request' (duration: 151.556957ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:12.021134Z","caller":"traceutil/trace.go:171","msg":"trace[362823887] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"124.59992ms","start":"2026-10-27T00:01:11.896502Z","end":"2026-10-27T00:01:12.021102Z","steps":["trace[362823887] 'process raft request' (duration: 119.039696ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:12.02118Z","caller":"traceutil/trace.go:171","msg":"trace[1819694304] linearizableReadLoop","detail":"{readStateIndex:5473; appliedIndex:5471; }","duration":"119.079427ms","start":"2026-10-27T00:01:11.902075Z","end":"2026-10-27T00:01:12.021155Z","steps":["trace[1819694304] 'read index received' (duration: 53.918003ms)","trace[1819694304] 'applied index is now lower than readState.Index' (duration: 65.158944ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:01:12.021427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.441712ms","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-27T00:01:12.021486Z","caller":"traceutil/trace.go:171","msg":"trace[1360212456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5273; }","duration":"104.550115ms","start":"2026-10-27T00:01:11.916924Z","end":"2026-10-27T00:01:12.021474Z","steps":["trace[1360212456] 'agreement among raft nodes before linearized reading' (duration: 104.405961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:01:12.021519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.417957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-10-27T00:01:12.02168Z","caller":"traceutil/trace.go:171","msg":"trace[1759684782] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5273; }","duration":"119.611412ms","start":"2026-10-27T00:01:11.902012Z","end":"2026-10-27T00:01:12.021624Z","steps":["trace[1759684782] 'agreement among raft nodes before linearized reading' (duration: 119.280513ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:14.16059Z","caller":"traceutil/trace.go:171","msg":"trace[1200262812] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"100.268236ms","start":"2026-10-27T00:01:14.060296Z","end":"2026-10-27T00:01:14.160564Z","steps":["trace[1200262812] 'process raft request' (duration: 94.331922ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:23.186031Z","caller":"traceutil/trace.go:171","msg":"trace[496249227] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"103.815594ms","start":"2026-10-27T00:01:23.082172Z","end":"2026-10-27T00:01:23.185987Z","steps":["trace[496249227] 'process raft request' (duration: 90.238618ms)","trace[496249227] 'compare' (duration: 13.268278ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:01:27.45805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.035613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:01:27.458158Z","caller":"traceutil/trace.go:171","msg":"trace[185732743] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5544; }","duration":"168.197428ms","start":"2026-10-27T00:01:27.289941Z","end":"2026-10-27T00:01:27.458139Z","steps":["trace[185732743] 'range keys from in-memory index tree' (duration: 167.93605ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:35.089417Z","caller":"traceutil/trace.go:171","msg":"trace[1523190022] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"130.085783ms","start":"2026-10-27T00:01:34.959309Z","end":"2026-10-27T00:01:35.089395Z","steps":["trace[1523190022] 'process raft request' (duration: 129.946279ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:36.658463Z","caller":"traceutil/trace.go:171","msg":"trace[1083399967] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"112.32583ms","start":"2026-10-27T00:01:36.546103Z","end":"2026-10-27T00:01:36.658428Z","steps":["trace[1083399967] 'process raft request' (duration: 99.199117ms)","trace[1083399967] 'compare' (duration: 12.927848ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:01:37.131671Z","caller":"traceutil/trace.go:171","msg":"trace[538907910] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"228.569579ms","start":"2026-10-27T00:01:36.903077Z","end":"2026-10-27T00:01:37.131647Z","steps":["trace[538907910] 'process raft request' (duration: 228.318672ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:38.355938Z","caller":"traceutil/trace.go:171","msg":"trace[944044189] transaction","detail":"{read_only:false; response_revision:5739; number_of_response:1; }","duration":"100.438711ms","start":"2026-10-27T00:01:38.25547Z","end":"2026-10-27T00:01:38.355909Z","steps":["trace[944044189] 'process raft request' (duration: 98.901709ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:01:50.814584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2971} {"level":"info","ts":"2026-10-27T00:01:50.895637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2971,"took":"79.695997ms","hash":2842078130,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":32358400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T00:01:50.895722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2842078130,"revision":2971,"compact-revision":-1} {"level":"info","ts":"2026-10-27T00:02:29.656633Z","caller":"traceutil/trace.go:171","msg":"trace[737212159] transaction","detail":"{read_only:false; response_revision:6241; number_of_response:1; }","duration":"109.78535ms","start":"2026-10-27T00:02:29.546825Z","end":"2026-10-27T00:02:29.656611Z","steps":["trace[737212159] 'process raft request' (duration: 109.615406ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:06:50.825016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5906} {"level":"info","ts":"2026-10-27T00:06:50.925615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5906,"took":"97.806767ms","hash":3158809940,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":26308608,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T00:06:50.926152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3158809940,"revision":5906,"compact-revision":2971} {"level":"info","ts":"2026-10-27T00:07:55.944333Z","caller":"traceutil/trace.go:171","msg":"trace[1621131264] linearizableReadLoop","detail":"{readStateIndex:8927; appliedIndex:8926; }","duration":"275.487076ms","start":"2026-10-27T00:07:55.668818Z","end":"2026-10-27T00:07:55.944305Z","steps":["trace[1621131264] 'read index received' (duration: 275.2776ms)","trace[1621131264] 'applied index is now lower than readState.Index' (duration: 208.356µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:07:55.944389Z","caller":"traceutil/trace.go:171","msg":"trace[1047234170] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"442.800146ms","start":"2026-10-27T00:07:55.501554Z","end":"2026-10-27T00:07:55.944354Z","steps":["trace[1047234170] 'process raft request' (duration: 442.57879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:55.944555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.21023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:07:55.944599Z","caller":"traceutil/trace.go:171","msg":"trace[1098015493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8622; }","duration":"244.365834ms","start":"2026-10-27T00:07:55.700223Z","end":"2026-10-27T00:07:55.944589Z","steps":["trace[1098015493] 'agreement among raft nodes before linearized reading' (duration: 244.248291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:55.94459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.501523Z","time spent":"442.9363ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:07:55.944682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.848665ms","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-27T00:07:55.944763Z","caller":"traceutil/trace.go:171","msg":"trace[63860010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8622; }","duration":"275.99995ms","start":"2026-10-27T00:07:55.668741Z","end":"2026-10-27T00:07:55.944741Z","steps":["trace[63860010] 'agreement among raft nodes before linearized reading' (duration: 275.714912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:56.3113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.767246ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:07:56.311473Z","caller":"traceutil/trace.go:171","msg":"trace[592603528] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8622; }","duration":"179.9171ms","start":"2026-10-27T00:07:56.131498Z","end":"2026-10-27T00:07:56.311415Z","steps":["trace[592603528] 'range keys from in-memory index tree' (duration: 179.737495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:07:56.312218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.794545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T00:07:56.312476Z","caller":"traceutil/trace.go:171","msg":"trace[1358005801] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"363.177934ms","start":"2026-10-27T00:07:55.949274Z","end":"2026-10-27T00:07:56.312451Z","steps":["trace[1358005801] 'process raft request' (duration: 164.768101ms)","trace[1358005801] 'compare' (duration: 197.327693ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:07:56.312622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:07:55.949254Z","time spent":"363.332277ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:07:56.312647Z","caller":"traceutil/trace.go:171","msg":"trace[342156752] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"299.518091ms","start":"2026-10-27T00:07:56.013108Z","end":"2026-10-27T00:07:56.312626Z","steps":["trace[342156752] 'process raft request' (duration: 299.246864ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:07:56.313201Z","caller":"traceutil/trace.go:171","msg":"trace[1761820921] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"241.342511ms","start":"2026-10-27T00:07:56.071833Z","end":"2026-10-27T00:07:56.313176Z","steps":["trace[1761820921] 'process raft request' (duration: 241.230047ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:07:56.313135Z","caller":"traceutil/trace.go:171","msg":"trace[471606007] linearizableReadLoop","detail":"{readStateIndex:8930; appliedIndex:8927; }","duration":"235.839138ms","start":"2026-10-27T00:07:56.077281Z","end":"2026-10-27T00:07:56.31312Z","steps":["trace[471606007] 'read index received' (duration: 36.769087ms)","trace[471606007] 'applied index is now lower than readState.Index' (duration: 199.069411ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:07:56.314133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.837606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tm9k8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-27T00:07:56.314247Z","caller":"traceutil/trace.go:171","msg":"trace[1350894580] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tm9k8; range_end:; response_count:1; response_revision:8625; }","duration":"236.98424ms","start":"2026-10-27T00:07:56.077244Z","end":"2026-10-27T00:07:56.314229Z","steps":["trace[1350894580] 'agreement among raft nodes before linearized reading' (duration: 236.528557ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.821055Z","caller":"traceutil/trace.go:171","msg":"trace[1948719827] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"464.243811ms","start":"2026-10-27T00:08:08.356781Z","end":"2026-10-27T00:08:08.821024Z","steps":["trace[1948719827] 'process raft request' (duration: 464.008405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.821241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.356758Z","time spent":"464.387985ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:08:08.835276Z","caller":"traceutil/trace.go:171","msg":"trace[1077962369] linearizableReadLoop","detail":"{readStateIndex:8995; appliedIndex:8994; }","duration":"464.831537ms","start":"2026-10-27T00:08:08.370411Z","end":"2026-10-27T00:08:08.835242Z","steps":["trace[1077962369] 'read index received' (duration: 450.598303ms)","trace[1077962369] 'applied index is now lower than readState.Index' (duration: 14.231114ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:08.835365Z","caller":"traceutil/trace.go:171","msg":"trace[1879520053] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"477.127708ms","start":"2026-10-27T00:08:08.358169Z","end":"2026-10-27T00:08:08.835297Z","steps":["trace[1879520053] 'process raft request' (duration: 476.899391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.835594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.178057ms","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-27T00:08:08.835625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.110996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tm9k8\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-27T00:08:08.835628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.923859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-27T00:08:08.835658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.35815Z","time spent":"477.329444ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:08:08.835663Z","caller":"traceutil/trace.go:171","msg":"trace[1750659891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8688; }","duration":"465.28429ms","start":"2026-10-27T00:08:08.370359Z","end":"2026-10-27T00:08:08.835643Z","steps":["trace[1750659891] 'agreement among raft nodes before linearized reading' (duration: 465.048044ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.835692Z","caller":"traceutil/trace.go:171","msg":"trace[1830335089] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tm9k8; range_end:; response_count:1; response_revision:8688; }","duration":"439.211658ms","start":"2026-10-27T00:08:08.39646Z","end":"2026-10-27T00:08:08.835672Z","steps":["trace[1830335089] 'agreement among raft nodes before linearized reading' (duration: 438.965271ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.835696Z","caller":"traceutil/trace.go:171","msg":"trace[617490044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8688; }","duration":"168.051982ms","start":"2026-10-27T00:08:08.667629Z","end":"2026-10-27T00:08:08.835681Z","steps":["trace[617490044] 'agreement among raft nodes before linearized reading' (duration: 167.830736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.835718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.370343Z","time spent":"465.367162ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-27T00:08:08.835714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.842437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-27T00:08:08.835737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.467686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:08.835785Z","caller":"traceutil/trace.go:171","msg":"trace[626769587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8688; }","duration":"184.95879ms","start":"2026-10-27T00:08:08.650805Z","end":"2026-10-27T00:08:08.835764Z","steps":["trace[626769587] 'agreement among raft nodes before linearized reading' (duration: 184.780605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.835803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.130746ms","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-10-27T00:08:08.835741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.39645Z","time spent":"439.277951ms","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-tm9k8\" "} {"level":"info","ts":"2026-10-27T00:08:08.835833Z","caller":"traceutil/trace.go:171","msg":"trace[42866728] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8688; }","duration":"309.623501ms","start":"2026-10-27T00:08:08.526184Z","end":"2026-10-27T00:08:08.835808Z","steps":["trace[42866728] 'agreement among raft nodes before linearized reading' (duration: 309.419895ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.835901Z","caller":"traceutil/trace.go:171","msg":"trace[192634860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8688; }","duration":"167.284911ms","start":"2026-10-27T00:08:08.668601Z","end":"2026-10-27T00:08:08.835886Z","steps":["trace[192634860] 'agreement among raft nodes before linearized reading' (duration: 167.039214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.835935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.52616Z","time spent":"309.757455ms","remote":"127.0.0.1:45904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-10-27T00:08:08.836594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.954261ms","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-27T00:08:08.836695Z","caller":"traceutil/trace.go:171","msg":"trace[1567826114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8688; }","duration":"426.089775ms","start":"2026-10-27T00:08:08.410579Z","end":"2026-10-27T00:08:08.836669Z","steps":["trace[1567826114] 'agreement among raft nodes before linearized reading' (duration: 425.021255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.836861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:08.410547Z","time spent":"426.30123ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-27T00:08:08.970861Z","caller":"traceutil/trace.go:171","msg":"trace[1037329088] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"128.151437ms","start":"2026-10-27T00:08:08.842684Z","end":"2026-10-27T00:08:08.970835Z","steps":["trace[1037329088] 'process raft request' (duration: 128.059655ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.971098Z","caller":"traceutil/trace.go:171","msg":"trace[1043006888] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"129.930996ms","start":"2026-10-27T00:08:08.841153Z","end":"2026-10-27T00:08:08.971084Z","steps":["trace[1043006888] 'process raft request' (duration: 123.755035ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.971303Z","caller":"traceutil/trace.go:171","msg":"trace[1015237733] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"126.938024ms","start":"2026-10-27T00:08:08.844342Z","end":"2026-10-27T00:08:08.97128Z","steps":["trace[1015237733] 'process raft request' (duration: 126.45863ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:08.971585Z","caller":"traceutil/trace.go:171","msg":"trace[384476135] linearizableReadLoop","detail":"{readStateIndex:8997; appliedIndex:8995; }","duration":"130.299327ms","start":"2026-10-27T00:08:08.841267Z","end":"2026-10-27T00:08:08.971567Z","steps":["trace[384476135] 'read index received' (duration: 15.651653ms)","trace[384476135] 'applied index is now lower than readState.Index' (duration: 114.645694ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:08.971686Z","caller":"traceutil/trace.go:171","msg":"trace[1959424412] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"127.265002ms","start":"2026-10-27T00:08:08.844405Z","end":"2026-10-27T00:08:08.97167Z","steps":["trace[1959424412] 'process raft request' (duration: 126.79926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:08.972095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.79822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:225374"} {"level":"info","ts":"2026-10-27T00:08:08.972154Z","caller":"traceutil/trace.go:171","msg":"trace[933283444] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8693; }","duration":"130.903143ms","start":"2026-10-27T00:08:08.841237Z","end":"2026-10-27T00:08:08.97214Z","steps":["trace[933283444] 'agreement among raft nodes before linearized reading' (duration: 130.397489ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:09.834803Z","caller":"traceutil/trace.go:171","msg":"trace[1036387875] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9001; }","duration":"134.884243ms","start":"2026-10-27T00:08:09.699884Z","end":"2026-10-27T00:08:09.834768Z","steps":["trace[1036387875] 'read index received' (duration: 79.752187ms)","trace[1036387875] 'applied index is now lower than readState.Index' (duration: 55.130176ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:08:09.83501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.110819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:09.835052Z","caller":"traceutil/trace.go:171","msg":"trace[1808566801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8694; }","duration":"135.231522ms","start":"2026-10-27T00:08:09.699809Z","end":"2026-10-27T00:08:09.83504Z","steps":["trace[1808566801] 'agreement among raft nodes before linearized reading' (duration: 135.076338ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:10.399108Z","caller":"traceutil/trace.go:171","msg":"trace[485664349] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"135.990304ms","start":"2026-10-27T00:08:10.263093Z","end":"2026-10-27T00:08:10.399083Z","steps":["trace[485664349] 'process raft request' (duration: 135.81208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:10.594378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.052693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:10.594465Z","caller":"traceutil/trace.go:171","msg":"trace[791968004] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8696; }","duration":"116.198647ms","start":"2026-10-27T00:08:10.478243Z","end":"2026-10-27T00:08:10.594442Z","steps":["trace[791968004] 'count revisions from in-memory index tree' (duration: 115.9702ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:11.007748Z","caller":"traceutil/trace.go:171","msg":"trace[1353636912] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"180.329152ms","start":"2026-10-27T00:08:10.827395Z","end":"2026-10-27T00:08:11.007724Z","steps":["trace[1353636912] 'process raft request' (duration: 180.149177ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:11.065528Z","caller":"traceutil/trace.go:171","msg":"trace[1820782238] linearizableReadLoop","detail":"{readStateIndex:9007; appliedIndex:9005; }","duration":"203.359839ms","start":"2026-10-27T00:08:10.862113Z","end":"2026-10-27T00:08:11.065472Z","steps":["trace[1820782238] 'read index received' (duration: 145.476067ms)","trace[1820782238] 'applied index is now lower than readState.Index' (duration: 57.882072ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:11.06572Z","caller":"traceutil/trace.go:171","msg":"trace[411191621] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"221.524552ms","start":"2026-10-27T00:08:10.844135Z","end":"2026-10-27T00:08:11.065659Z","steps":["trace[411191621] 'process raft request' (duration: 182.235325ms)","trace[411191621] 'compare' (duration: 38.942578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:08:11.066387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.245273ms","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-27T00:08:11.066448Z","caller":"traceutil/trace.go:171","msg":"trace[700585905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8699; }","duration":"204.366867ms","start":"2026-10-27T00:08:10.862067Z","end":"2026-10-27T00:08:11.066433Z","steps":["trace[700585905] 'agreement among raft nodes before linearized reading' (duration: 203.642857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:40.940299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.443929ms","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-27T00:08:40.940386Z","caller":"traceutil/trace.go:171","msg":"trace[208305081] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8912; }","duration":"309.582592ms","start":"2026-10-27T00:08:40.630784Z","end":"2026-10-27T00:08:40.940367Z","steps":["trace[208305081] 'count revisions from in-memory index tree' (duration: 309.366237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:40.940364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.36662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:40.940447Z","caller":"traceutil/trace.go:171","msg":"trace[1739971847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8912; }","duration":"238.490944ms","start":"2026-10-27T00:08:40.701932Z","end":"2026-10-27T00:08:40.940423Z","steps":["trace[1739971847] 'range keys from in-memory index tree' (duration: 238.288769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:40.940449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:40.630762Z","time spent":"309.676055ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-10-27T00:08:40.940438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.683983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-27T00:08:40.940544Z","caller":"traceutil/trace.go:171","msg":"trace[697736237] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8912; }","duration":"527.801396ms","start":"2026-10-27T00:08:40.412692Z","end":"2026-10-27T00:08:40.940493Z","steps":["trace[697736237] 'range keys from in-memory index tree' (duration: 527.529928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:40.940595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:40.412671Z","time spent":"527.914299ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-10-27T00:08:42.318706Z","caller":"traceutil/trace.go:171","msg":"trace[808127597] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"105.103661ms","start":"2026-10-27T00:08:42.21358Z","end":"2026-10-27T00:08:42.318684Z","steps":["trace[808127597] 'process raft request' (duration: 104.918366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:50.582076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.415172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:08:50.582174Z","caller":"traceutil/trace.go:171","msg":"trace[1224355741] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8966; }","duration":"108.600118ms","start":"2026-10-27T00:08:50.473554Z","end":"2026-10-27T00:08:50.582154Z","steps":["trace[1224355741] 'count revisions from in-memory index tree' (duration: 108.271769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:51.529281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.803353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T00:08:51.529587Z","caller":"traceutil/trace.go:171","msg":"trace[1808399599] transaction","detail":"{read_only:false; response_revision:8968; number_of_response:1; }","duration":"326.589684ms","start":"2026-10-27T00:08:51.202951Z","end":"2026-10-27T00:08:51.529541Z","steps":["trace[1808399599] 'process raft request' (duration: 122.447431ms)","trace[1808399599] 'compare' (duration: 203.493265ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:51.529621Z","caller":"traceutil/trace.go:171","msg":"trace[1620091880] linearizableReadLoop","detail":"{readStateIndex:9287; appliedIndex:9286; }","duration":"248.639205ms","start":"2026-10-27T00:08:51.280957Z","end":"2026-10-27T00:08:51.529596Z","steps":["trace[1620091880] 'read index received' (duration: 44.363389ms)","trace[1620091880] 'applied index is now lower than readState.Index' (duration: 204.273856ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:51.529678Z","caller":"traceutil/trace.go:171","msg":"trace[696639896] transaction","detail":"{read_only:false; response_revision:8969; number_of_response:1; }","duration":"167.305583ms","start":"2026-10-27T00:08:51.362237Z","end":"2026-10-27T00:08:51.529542Z","steps":["trace[696639896] 'process raft request' (duration: 167.195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:51.529774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:51.202926Z","time spent":"326.754129ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:08:51.529885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.367833ms","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-10-27T00:08:51.529874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.890782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-27T00:08:51.529907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.821431ms","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-27T00:08:51.52992Z","caller":"traceutil/trace.go:171","msg":"trace[1273893845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8969; }","duration":"220.427174ms","start":"2026-10-27T00:08:51.309482Z","end":"2026-10-27T00:08:51.529909Z","steps":["trace[1273893845] 'agreement among raft nodes before linearized reading' (duration: 220.28003ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:51.52996Z","caller":"traceutil/trace.go:171","msg":"trace[1389730142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8969; }","duration":"243.895634ms","start":"2026-10-27T00:08:51.286048Z","end":"2026-10-27T00:08:51.529944Z","steps":["trace[1389730142] 'agreement among raft nodes before linearized reading' (duration: 243.717369ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:51.529959Z","caller":"traceutil/trace.go:171","msg":"trace[104556301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8969; }","duration":"249.022026ms","start":"2026-10-27T00:08:51.280916Z","end":"2026-10-27T00:08:51.529938Z","steps":["trace[104556301] 'agreement among raft nodes before linearized reading' (duration: 248.799989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:51.529948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.264834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-27T00:08:51.529886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.671124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tm9k8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-27T00:08:51.53005Z","caller":"traceutil/trace.go:171","msg":"trace[1385211063] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tm9k8; range_end:; response_count:1; response_revision:8969; }","duration":"197.852089ms","start":"2026-10-27T00:08:51.332175Z","end":"2026-10-27T00:08:51.530027Z","steps":["trace[1385211063] 'agreement among raft nodes before linearized reading' (duration: 197.585472ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:51.530104Z","caller":"traceutil/trace.go:171","msg":"trace[1707627876] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8969; }","duration":"218.442159ms","start":"2026-10-27T00:08:51.311603Z","end":"2026-10-27T00:08:51.530045Z","steps":["trace[1707627876] 'agreement among raft nodes before linearized reading' (duration: 218.166931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:51.52989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.858409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-27T00:08:51.530161Z","caller":"traceutil/trace.go:171","msg":"trace[608588404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8969; }","duration":"224.154017ms","start":"2026-10-27T00:08:51.305992Z","end":"2026-10-27T00:08:51.530146Z","steps":["trace[608588404] 'agreement among raft nodes before linearized reading' (duration: 223.793557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:51.529891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.123132ms","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-27T00:08:51.530259Z","caller":"traceutil/trace.go:171","msg":"trace[1214781516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8969; }","duration":"230.519863ms","start":"2026-10-27T00:08:51.299726Z","end":"2026-10-27T00:08:51.530246Z","steps":["trace[1214781516] 'agreement among raft nodes before linearized reading' (duration: 230.066581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.526151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.640615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:4748"} {"level":"info","ts":"2026-10-27T00:08:54.526303Z","caller":"traceutil/trace.go:171","msg":"trace[1984432305] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8995; }","duration":"387.243163ms","start":"2026-10-27T00:08:54.139023Z","end":"2026-10-27T00:08:54.526266Z","steps":["trace[1984432305] 'process raft request' (duration: 207.394903ms)","trace[1984432305] 'compare' (duration: 179.562962ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:54.526383Z","caller":"traceutil/trace.go:171","msg":"trace[1776075313] linearizableReadLoop","detail":"{readStateIndex:9315; appliedIndex:9314; }","duration":"333.778263ms","start":"2026-10-27T00:08:54.192582Z","end":"2026-10-27T00:08:54.52636Z","steps":["trace[1776075313] 'read index received' (duration: 153.914872ms)","trace[1776075313] 'applied index is now lower than readState.Index' (duration: 179.860331ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:54.526405Z","caller":"traceutil/trace.go:171","msg":"trace[920004481] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8996; }","duration":"331.801689ms","start":"2026-10-27T00:08:54.194584Z","end":"2026-10-27T00:08:54.526386Z","steps":["trace[920004481] 'process raft request' (duration: 331.696656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.526463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:54.13901Z","time spent":"387.361507ms","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4772,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:08:54.52658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:54.19457Z","time spent":"331.88487ms","remote":"127.0.0.1:33880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-10-27T00:08:54.526636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.916019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-27T00:08:54.526667Z","caller":"traceutil/trace.go:171","msg":"trace[175909055] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8996; }","duration":"384.978481ms","start":"2026-10-27T00:08:54.141679Z","end":"2026-10-27T00:08:54.526658Z","steps":["trace[175909055] 'agreement among raft nodes before linearized reading' (duration: 384.786166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.526699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:54.141669Z","time spent":"385.018812ms","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12455,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-27T00:08:54.60593Z","caller":"traceutil/trace.go:171","msg":"trace[1424553596] transaction","detail":"{read_only:false; response_revision:8997; number_of_response:1; }","duration":"238.085814ms","start":"2026-10-27T00:08:54.367818Z","end":"2026-10-27T00:08:54.605904Z","steps":["trace[1424553596] 'process raft request' (duration: 237.921299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.60616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.805969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.126\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-27T00:08:54.60625Z","caller":"traceutil/trace.go:171","msg":"trace[1696611610] range","detail":"{range_begin:/registry/masterleases/162.253.55.126; range_end:; response_count:1; response_revision:8997; }","duration":"396.967252ms","start":"2026-10-27T00:08:54.209262Z","end":"2026-10-27T00:08:54.606229Z","steps":["trace[1696611610] 'agreement among raft nodes before linearized reading' (duration: 396.691505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:08:54.6063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:08:54.209229Z","time spent":"397.057455ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.126\" "} {"level":"info","ts":"2026-10-27T00:08:54.708695Z","caller":"traceutil/trace.go:171","msg":"trace[2063780283] linearizableReadLoop","detail":"{readStateIndex:9318; appliedIndex:9317; }","duration":"102.796416ms","start":"2026-10-27T00:08:54.605872Z","end":"2026-10-27T00:08:54.708669Z","steps":["trace[2063780283] 'read index received' (duration: 54.772286ms)","trace[2063780283] 'applied index is now lower than readState.Index' (duration: 48.02298ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:08:54.708755Z","caller":"traceutil/trace.go:171","msg":"trace[416780360] transaction","detail":"{read_only:false; response_revision:8998; number_of_response:1; }","duration":"180.626542ms","start":"2026-10-27T00:08:54.528103Z","end":"2026-10-27T00:08:54.70873Z","steps":["trace[416780360] 'process raft request' (duration: 132.475438ms)","trace[416780360] 'compare' (duration: 47.958679ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:08:54.709045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.226516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-27T00:08:54.709163Z","caller":"traceutil/trace.go:171","msg":"trace[530725565] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8998; }","duration":"178.38654ms","start":"2026-10-27T00:08:54.530758Z","end":"2026-10-27T00:08:54.709144Z","steps":["trace[530725565] 'agreement among raft nodes before linearized reading' (duration: 178.01147ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:08:58.142589Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a0.wal"} {"level":"info","ts":"2026-10-27T00:09:56.605566Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T00:09:56.614571Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-27T00:09:56.614685Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-27T00:10:24.140062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.848821ms","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-27T00:10:24.140138Z","caller":"traceutil/trace.go:171","msg":"trace[1290514485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9846; }","duration":"140.980534ms","start":"2026-10-27T00:10:23.99914Z","end":"2026-10-27T00:10:24.140121Z","steps":["trace[1290514485] 'range keys from in-memory index tree' (duration: 140.744217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:10:24.420904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.016311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T00:10:24.421143Z","caller":"traceutil/trace.go:171","msg":"trace[560808788] linearizableReadLoop","detail":"{readStateIndex:10196; appliedIndex:10191; }","duration":"267.355834ms","start":"2026-10-27T00:10:24.153755Z","end":"2026-10-27T00:10:24.421111Z","steps":["trace[560808788] 'read index received' (duration: 36.621344ms)","trace[560808788] 'applied index is now lower than readState.Index' (duration: 230.73353ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:10:24.421362Z","caller":"traceutil/trace.go:171","msg":"trace[208105114] transaction","detail":"{read_only:false; response_revision:9849; number_of_response:1; }","duration":"276.649771ms","start":"2026-10-27T00:10:24.144682Z","end":"2026-10-27T00:10:24.421332Z","steps":["trace[208105114] 'process raft request' (duration: 276.310482ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:10:24.421458Z","caller":"traceutil/trace.go:171","msg":"trace[801477318] transaction","detail":"{read_only:false; response_revision:9850; number_of_response:1; }","duration":"276.709423ms","start":"2026-10-27T00:10:24.144711Z","end":"2026-10-27T00:10:24.42142Z","steps":["trace[801477318] 'process raft request' (duration: 276.328642ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:10:24.421681Z","caller":"traceutil/trace.go:171","msg":"trace[775848421] transaction","detail":"{read_only:false; response_revision:9848; number_of_response:1; }","duration":"277.073262ms","start":"2026-10-27T00:10:24.144594Z","end":"2026-10-27T00:10:24.421667Z","steps":["trace[775848421] 'process raft request' (duration: 116.224518ms)","trace[775848421] 'compare' (duration: 159.927429ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:10:24.421712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.218596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.126\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-27T00:10:24.421708Z","caller":"traceutil/trace.go:171","msg":"trace[904912016] transaction","detail":"{read_only:false; response_revision:9851; number_of_response:1; }","duration":"276.938069ms","start":"2026-10-27T00:10:24.144737Z","end":"2026-10-27T00:10:24.421675Z","steps":["trace[904912016] 'process raft request' (duration: 276.337732ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:10:24.421753Z","caller":"traceutil/trace.go:171","msg":"trace[394507885] range","detail":"{range_begin:/registry/masterleases/162.253.55.126; range_end:; response_count:1; response_revision:9851; }","duration":"208.295878ms","start":"2026-10-27T00:10:24.213446Z","end":"2026-10-27T00:10:24.421742Z","steps":["trace[394507885] 'agreement among raft nodes before linearized reading' (duration: 208.037181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:10:24.421785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.989211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tm9k8\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-27T00:10:24.421803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.838303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-27T00:10:24.42223Z","caller":"traceutil/trace.go:171","msg":"trace[1451089901] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tm9k8; range_end:; response_count:1; response_revision:9851; }","duration":"268.424923ms","start":"2026-10-27T00:10:24.153747Z","end":"2026-10-27T00:10:24.422172Z","steps":["trace[1451089901] 'agreement among raft nodes before linearized reading' (duration: 267.640682ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:10:24.422293Z","caller":"traceutil/trace.go:171","msg":"trace[1949843350] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:9851; }","duration":"200.358648ms","start":"2026-10-27T00:10:24.221913Z","end":"2026-10-27T00:10:24.422272Z","steps":["trace[1949843350] 'agreement among raft nodes before linearized reading' (duration: 199.71209ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:10:24.651443Z","caller":"traceutil/trace.go:171","msg":"trace[2119813485] linearizableReadLoop","detail":"{readStateIndex:10197; appliedIndex:10196; }","duration":"225.954627ms","start":"2026-10-27T00:10:24.425454Z","end":"2026-10-27T00:10:24.651409Z","steps":["trace[2119813485] 'read index received' (duration: 176.995031ms)","trace[2119813485] 'applied index is now lower than readState.Index' (duration: 48.957676ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:10:24.651787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.320887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9f1d3f23-900e-42f1-8eb8-fd5d56c209d3\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-10-27T00:10:24.651837Z","caller":"traceutil/trace.go:171","msg":"trace[2093141384] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9f1d3f23-900e-42f1-8eb8-fd5d56c209d3; range_end:; response_count:1; response_revision:9851; }","duration":"226.41506ms","start":"2026-10-27T00:10:24.425406Z","end":"2026-10-27T00:10:24.651821Z","steps":["trace[2093141384] 'agreement among raft nodes before linearized reading' (duration: 226.216084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:10:24.651943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.365596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:293634"} {"level":"info","ts":"2026-10-27T00:10:24.652073Z","caller":"traceutil/trace.go:171","msg":"trace[403499077] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9851; }","duration":"223.511739ms","start":"2026-10-27T00:10:24.428548Z","end":"2026-10-27T00:10:24.65206Z","steps":["trace[403499077] 'agreement among raft nodes before linearized reading' (duration: 223.134609ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:11:50.832348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8050} {"level":"info","ts":"2026-10-27T00:11:50.915826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8050,"took":"81.012919ms","hash":3154805724,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":28078080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T00:11:50.915901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3154805724,"revision":8050,"compact-revision":5906} {"level":"info","ts":"2026-10-27T00:16:05.166087Z","caller":"traceutil/trace.go:171","msg":"trace[1721049528] transaction","detail":"{read_only:false; response_revision:12289; number_of_response:1; }","duration":"107.670771ms","start":"2026-10-27T00:16:05.058385Z","end":"2026-10-27T00:16:05.166056Z","steps":["trace[1721049528] 'process raft request' (duration: 107.445215ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:16:05.515745Z","caller":"traceutil/trace.go:171","msg":"trace[1986459378] transaction","detail":"{read_only:false; response_revision:12290; number_of_response:1; }","duration":"282.145732ms","start":"2026-10-27T00:16:05.23357Z","end":"2026-10-27T00:16:05.515716Z","steps":["trace[1986459378] 'process raft request' (duration: 281.989788ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:16:05.545802Z","caller":"traceutil/trace.go:171","msg":"trace[2085337881] linearizableReadLoop","detail":"{readStateIndex:12717; appliedIndex:12715; }","duration":"305.069308ms","start":"2026-10-27T00:16:05.240714Z","end":"2026-10-27T00:16:05.545783Z","steps":["trace[2085337881] 'read index received' (duration: 274.838801ms)","trace[2085337881] 'applied index is now lower than readState.Index' (duration: 30.229357ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:16:05.545845Z","caller":"traceutil/trace.go:171","msg":"trace[1055148754] transaction","detail":"{read_only:false; response_revision:12291; number_of_response:1; }","duration":"311.253409ms","start":"2026-10-27T00:16:05.23456Z","end":"2026-10-27T00:16:05.545814Z","steps":["trace[1055148754] 'process raft request' (duration: 311.040333ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:16:05.545894Z","caller":"traceutil/trace.go:171","msg":"trace[688328444] transaction","detail":"{read_only:false; response_revision:12292; number_of_response:1; }","duration":"115.402026ms","start":"2026-10-27T00:16:05.430481Z","end":"2026-10-27T00:16:05.545883Z","steps":["trace[688328444] 'process raft request' (duration: 115.252322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.546072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.234546Z","time spent":"311.365562ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:16:05.546089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.357906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T00:16:05.546159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.948847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-27T00:16:05.546164Z","caller":"traceutil/trace.go:171","msg":"trace[1718901289] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12292; }","duration":"305.475629ms","start":"2026-10-27T00:16:05.240669Z","end":"2026-10-27T00:16:05.546145Z","steps":["trace[1718901289] 'agreement among raft nodes before linearized reading' (duration: 305.352756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.546215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.240647Z","time spent":"305.561432ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"info","ts":"2026-10-27T00:16:05.546214Z","caller":"traceutil/trace.go:171","msg":"trace[633990104] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12292; }","duration":"304.018879ms","start":"2026-10-27T00:16:05.242178Z","end":"2026-10-27T00:16:05.546197Z","steps":["trace[633990104] 'agreement among raft nodes before linearized reading' (duration: 303.842344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.54609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.087119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T00:16:05.546343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.887573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:16:05.546378Z","caller":"traceutil/trace.go:171","msg":"trace[759179388] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12292; }","duration":"304.976845ms","start":"2026-10-27T00:16:05.241392Z","end":"2026-10-27T00:16:05.546369Z","steps":["trace[759179388] 'agreement among raft nodes before linearized reading' (duration: 304.918974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.546415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.241383Z","time spent":"305.020096ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-10-27T00:16:05.546269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.24217Z","time spent":"304.08619ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"info","ts":"2026-10-27T00:16:05.546355Z","caller":"traceutil/trace.go:171","msg":"trace[283453289] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12292; }","duration":"305.379486ms","start":"2026-10-27T00:16:05.240953Z","end":"2026-10-27T00:16:05.546332Z","steps":["trace[283453289] 'agreement among raft nodes before linearized reading' (duration: 305.071478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.546574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.240946Z","time spent":"305.616433ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"warn","ts":"2026-10-27T00:16:05.546538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.415537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:16:05.546657Z","caller":"traceutil/trace.go:171","msg":"trace[1690885855] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12292; }","duration":"305.616923ms","start":"2026-10-27T00:16:05.241011Z","end":"2026-10-27T00:16:05.546628Z","steps":["trace[1690885855] 'agreement among raft nodes before linearized reading' (duration: 305.355906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:16:05.546717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:16:05.240994Z","time spent":"305.712656ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"info","ts":"2026-10-27T00:16:06.485943Z","caller":"traceutil/trace.go:171","msg":"trace[1520331621] transaction","detail":"{read_only:false; response_revision:12298; number_of_response:1; }","duration":"152.125303ms","start":"2026-10-27T00:16:06.333775Z","end":"2026-10-27T00:16:06.4859Z","steps":["trace[1520331621] 'process raft request' (duration: 104.535425ms)","trace[1520331621] 'compare' (duration: 47.452534ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:16:50.845442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10438} {"level":"info","ts":"2026-10-27T00:16:50.931297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10438,"took":"84.197062ms","hash":557280900,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":25985024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T00:16:50.931352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":557280900,"revision":10438,"compact-revision":8050} {"level":"info","ts":"2026-10-27T00:21:50.852343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12614} {"level":"info","ts":"2026-10-27T00:21:50.945126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12614,"took":"88.873739ms","hash":3840461170,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":27840512,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T00:21:50.945199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3840461170,"revision":12614,"compact-revision":10438} {"level":"info","ts":"2026-10-27T00:22:33.008332Z","caller":"traceutil/trace.go:171","msg":"trace[811043957] linearizableReadLoop","detail":"{readStateIndex:15395; appliedIndex:15394; }","duration":"274.638118ms","start":"2026-10-27T00:22:32.733654Z","end":"2026-10-27T00:22:33.008292Z","steps":["trace[811043957] 'read index received' (duration: 274.227287ms)","trace[811043957] 'applied index is now lower than readState.Index' (duration: 409.471µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:22:33.00844Z","caller":"traceutil/trace.go:171","msg":"trace[248787620] transaction","detail":"{read_only:false; response_revision:14878; number_of_response:1; }","duration":"286.499316ms","start":"2026-10-27T00:22:32.721898Z","end":"2026-10-27T00:22:33.008397Z","steps":["trace[248787620] 'process raft request' (duration: 286.118796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:22:33.008575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.856114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:22:33.008628Z","caller":"traceutil/trace.go:171","msg":"trace[1247219321] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:14878; }","duration":"274.991718ms","start":"2026-10-27T00:22:32.733622Z","end":"2026-10-27T00:22:33.008614Z","steps":["trace[1247219321] 'agreement among raft nodes before linearized reading' (duration: 274.842414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:22:50.288586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.923028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-27T00:22:50.288691Z","caller":"traceutil/trace.go:171","msg":"trace[227559642] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:14978; }","duration":"103.100012ms","start":"2026-10-27T00:22:50.185568Z","end":"2026-10-27T00:22:50.288668Z","steps":["trace[227559642] 'range keys from in-memory index tree' (duration: 102.606369ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:51.081498Z","caller":"traceutil/trace.go:171","msg":"trace[1015488664] transaction","detail":"{read_only:false; response_revision:14984; number_of_response:1; }","duration":"297.606544ms","start":"2026-10-27T00:22:50.783848Z","end":"2026-10-27T00:22:51.081455Z","steps":["trace[1015488664] 'process raft request' (duration: 297.337727ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:51.150441Z","caller":"traceutil/trace.go:171","msg":"trace[532071392] transaction","detail":"{read_only:false; response_revision:14985; number_of_response:1; }","duration":"209.048963ms","start":"2026-10-27T00:22:50.941366Z","end":"2026-10-27T00:22:51.150415Z","steps":["trace[532071392] 'process raft request' (duration: 208.825727ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:22:53.261162Z","caller":"traceutil/trace.go:171","msg":"trace[2024832458] transaction","detail":"{read_only:false; response_revision:15004; number_of_response:1; }","duration":"105.849828ms","start":"2026-10-27T00:22:53.155273Z","end":"2026-10-27T00:22:53.261122Z","steps":["trace[2024832458] 'process raft request' (duration: 75.205761ms)","trace[2024832458] 'compare' (duration: 30.491363ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:22:53.262363Z","caller":"traceutil/trace.go:171","msg":"trace[430534217] transaction","detail":"{read_only:false; response_revision:15005; number_of_response:1; }","duration":"103.564265ms","start":"2026-10-27T00:22:53.158776Z","end":"2026-10-27T00:22:53.262341Z","steps":["trace[430534217] 'process raft request' (duration: 103.456012ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:23:01.791307Z","caller":"traceutil/trace.go:171","msg":"trace[1196124081] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"120.697639ms","start":"2026-10-27T00:23:01.670575Z","end":"2026-10-27T00:23:01.791272Z","steps":["trace[1196124081] 'process raft request' (duration: 120.470043ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:23:02.155374Z","caller":"traceutil/trace.go:171","msg":"trace[206256602] linearizableReadLoop","detail":"{readStateIndex:15685; appliedIndex:15684; }","duration":"162.767973ms","start":"2026-10-27T00:23:01.992575Z","end":"2026-10-27T00:23:02.155343Z","steps":["trace[206256602] 'read index received' (duration: 162.618239ms)","trace[206256602] 'applied index is now lower than readState.Index' (duration: 148.364µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:23:02.155464Z","caller":"traceutil/trace.go:171","msg":"trace[1451598889] transaction","detail":"{read_only:false; response_revision:15159; number_of_response:1; }","duration":"184.666188ms","start":"2026-10-27T00:23:01.970729Z","end":"2026-10-27T00:23:02.155395Z","steps":["trace[1451598889] 'process raft request' (duration: 184.382901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:23:02.155651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.03449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:23:02.15576Z","caller":"traceutil/trace.go:171","msg":"trace[1792670308] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:15159; }","duration":"163.223846ms","start":"2026-10-27T00:23:01.992511Z","end":"2026-10-27T00:23:02.155735Z","steps":["trace[1792670308] 'agreement among raft nodes before linearized reading' (duration: 162.955428ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:23:02.162127Z","caller":"traceutil/trace.go:171","msg":"trace[1030029607] transaction","detail":"{read_only:false; response_revision:15160; number_of_response:1; }","duration":"162.958058ms","start":"2026-10-27T00:23:01.999149Z","end":"2026-10-27T00:23:02.162107Z","steps":["trace[1030029607] 'process raft request' (duration: 162.578698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:23:02.162188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.140706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-7mljp.18e23b19125ba4ff\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-27T00:23:02.162276Z","caller":"traceutil/trace.go:171","msg":"trace[546388560] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-7mljp.18e23b19125ba4ff; range_end:; response_count:1; response_revision:15160; }","duration":"119.27968ms","start":"2026-10-27T00:23:02.042975Z","end":"2026-10-27T00:23:02.162254Z","steps":["trace[546388560] 'agreement among raft nodes before linearized reading' (duration: 118.998632ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:26.932247Z","caller":"traceutil/trace.go:171","msg":"trace[1200097821] linearizableReadLoop","detail":"{readStateIndex:16731; appliedIndex:16730; }","duration":"232.892495ms","start":"2026-10-27T00:24:26.699327Z","end":"2026-10-27T00:24:26.932219Z","steps":["trace[1200097821] 'read index received' (duration: 232.855834ms)","trace[1200097821] 'applied index is now lower than readState.Index' (duration: 34.821µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:24:26.932316Z","caller":"traceutil/trace.go:171","msg":"trace[1968342371] transaction","detail":"{read_only:false; response_revision:16171; number_of_response:1; }","duration":"234.962021ms","start":"2026-10-27T00:24:26.697305Z","end":"2026-10-27T00:24:26.932267Z","steps":["trace[1968342371] 'process raft request' (duration: 234.749905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:26.932401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.046189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T00:24:26.932454Z","caller":"traceutil/trace.go:171","msg":"trace[117995536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16171; }","duration":"233.161142ms","start":"2026-10-27T00:24:26.699279Z","end":"2026-10-27T00:24:26.93244Z","steps":["trace[117995536] 'agreement among raft nodes before linearized reading' (duration: 233.054349ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:26.944212Z","caller":"traceutil/trace.go:171","msg":"trace[851136358] transaction","detail":"{read_only:false; response_revision:16172; number_of_response:1; }","duration":"145.055162ms","start":"2026-10-27T00:24:26.79913Z","end":"2026-10-27T00:24:26.944185Z","steps":["trace[851136358] 'process raft request' (duration: 144.890948ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:33.156024Z","caller":"traceutil/trace.go:171","msg":"trace[904049468] linearizableReadLoop","detail":"{readStateIndex:16763; appliedIndex:16762; }","duration":"144.496596ms","start":"2026-10-27T00:24:33.01148Z","end":"2026-10-27T00:24:33.155976Z","steps":["trace[904049468] 'read index received' (duration: 51.399748ms)","trace[904049468] 'applied index is now lower than readState.Index' (duration: 93.094988ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:24:33.156124Z","caller":"traceutil/trace.go:171","msg":"trace[733279298] transaction","detail":"{read_only:false; response_revision:16202; number_of_response:1; }","duration":"152.591619ms","start":"2026-10-27T00:24:33.003492Z","end":"2026-10-27T00:24:33.156084Z","steps":["trace[733279298] 'process raft request' (duration: 59.320306ms)","trace[733279298] 'compare' (duration: 92.844952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:24:33.156433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.913408ms","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-27T00:24:33.156565Z","caller":"traceutil/trace.go:171","msg":"trace[415177344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16202; }","duration":"145.081292ms","start":"2026-10-27T00:24:33.011454Z","end":"2026-10-27T00:24:33.156536Z","steps":["trace[415177344] 'agreement among raft nodes before linearized reading' (duration: 144.733802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.44426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.217953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T00:24:33.44464Z","caller":"traceutil/trace.go:171","msg":"trace[2021038429] transaction","detail":"{read_only:false; response_revision:16204; number_of_response:1; }","duration":"270.41827ms","start":"2026-10-27T00:24:33.174199Z","end":"2026-10-27T00:24:33.444617Z","steps":["trace[2021038429] 'process raft request' (duration: 270.272756ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:33.444931Z","caller":"traceutil/trace.go:171","msg":"trace[1715115623] linearizableReadLoop","detail":"{readStateIndex:16764; appliedIndex:16763; }","duration":"270.854982ms","start":"2026-10-27T00:24:33.174019Z","end":"2026-10-27T00:24:33.444874Z","steps":["trace[1715115623] 'read index received' (duration: 93.233µs)","trace[1715115623] 'applied index is now lower than readState.Index' (duration: 270.760279ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:24:33.44511Z","caller":"traceutil/trace.go:171","msg":"trace[901130233] transaction","detail":"{read_only:false; response_revision:16203; number_of_response:1; }","duration":"271.097288ms","start":"2026-10-27T00:24:33.173997Z","end":"2026-10-27T00:24:33.445094Z","steps":["trace[901130233] 'process raft request' (duration: 127.942519ms)","trace[901130233] 'compare' (duration: 141.616997ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:24:33.445448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.097279ms","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-27T00:24:33.445498Z","caller":"traceutil/trace.go:171","msg":"trace[491471793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16204; }","duration":"221.187832ms","start":"2026-10-27T00:24:33.224293Z","end":"2026-10-27T00:24:33.445481Z","steps":["trace[491471793] 'agreement among raft nodes before linearized reading' (duration: 220.992597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.44579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.772747ms","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-27T00:24:33.44586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.979543ms","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-27T00:24:33.445885Z","caller":"traceutil/trace.go:171","msg":"trace[644579140] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16204; }","duration":"242.889781ms","start":"2026-10-27T00:24:33.202978Z","end":"2026-10-27T00:24:33.445868Z","steps":["trace[644579140] 'agreement among raft nodes before linearized reading' (duration: 242.761507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.44604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.081146ms","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-27T00:24:33.446166Z","caller":"traceutil/trace.go:171","msg":"trace[408538479] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16204; }","duration":"243.257211ms","start":"2026-10-27T00:24:33.202896Z","end":"2026-10-27T00:24:33.446153Z","steps":["trace[408538479] 'agreement among raft nodes before linearized reading' (duration: 243.078366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.446127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.222377ms","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-27T00:24:33.445928Z","caller":"traceutil/trace.go:171","msg":"trace[1451999491] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16204; }","duration":"243.075116ms","start":"2026-10-27T00:24:33.202835Z","end":"2026-10-27T00:24:33.44591Z","steps":["trace[1451999491] 'agreement among raft nodes before linearized reading' (duration: 242.968013ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:33.446275Z","caller":"traceutil/trace.go:171","msg":"trace[62427439] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16204; }","duration":"203.430032ms","start":"2026-10-27T00:24:33.242812Z","end":"2026-10-27T00:24:33.446242Z","steps":["trace[62427439] 'agreement among raft nodes before linearized reading' (duration: 203.122384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.446359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.399364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-10-27T00:24:33.44638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.391688ms","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-27T00:24:33.44644Z","caller":"traceutil/trace.go:171","msg":"trace[1845402160] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16204; }","duration":"200.483641ms","start":"2026-10-27T00:24:33.245941Z","end":"2026-10-27T00:24:33.446424Z","steps":["trace[1845402160] 'agreement among raft nodes before linearized reading' (duration: 199.928296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.446464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.920451ms","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-27T00:24:33.446399Z","caller":"traceutil/trace.go:171","msg":"trace[600719190] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16204; }","duration":"202.456235ms","start":"2026-10-27T00:24:33.243931Z","end":"2026-10-27T00:24:33.446387Z","steps":["trace[600719190] 'agreement among raft nodes before linearized reading' (duration: 201.942171ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:33.446486Z","caller":"traceutil/trace.go:171","msg":"trace[1503464252] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16204; }","duration":"238.941122ms","start":"2026-10-27T00:24:33.207538Z","end":"2026-10-27T00:24:33.446479Z","steps":["trace[1503464252] 'agreement among raft nodes before linearized reading' (duration: 238.281103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.446804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.59923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-n9rjk\" ","response":"range_response_count:1 size:20799"} {"level":"info","ts":"2026-10-27T00:24:33.446889Z","caller":"traceutil/trace.go:171","msg":"trace[1529838134] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-n9rjk; range_end:; response_count:1; response_revision:16204; }","duration":"243.729294ms","start":"2026-10-27T00:24:33.203135Z","end":"2026-10-27T00:24:33.446864Z","steps":["trace[1529838134] 'agreement among raft nodes before linearized reading' (duration: 242.713216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.44745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.30717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:772875"} {"level":"info","ts":"2026-10-27T00:24:33.44751Z","caller":"traceutil/trace.go:171","msg":"trace[87999773] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16204; }","duration":"273.374622ms","start":"2026-10-27T00:24:33.174122Z","end":"2026-10-27T00:24:33.447497Z","steps":["trace[87999773] 'agreement among raft nodes before linearized reading' (duration: 271.1357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:24:33.44773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.705871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:772875"} {"level":"info","ts":"2026-10-27T00:24:33.44778Z","caller":"traceutil/trace.go:171","msg":"trace[1199803523] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16204; }","duration":"273.780293ms","start":"2026-10-27T00:24:33.17399Z","end":"2026-10-27T00:24:33.44777Z","steps":["trace[1199803523] 'agreement among raft nodes before linearized reading' (duration: 271.610323ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:24:33.566377Z","caller":"traceutil/trace.go:171","msg":"trace[689853102] transaction","detail":"{read_only:false; response_revision:16205; number_of_response:1; }","duration":"115.947259ms","start":"2026-10-27T00:24:33.450381Z","end":"2026-10-27T00:24:33.566328Z","steps":["trace[689853102] 'process raft request' (duration: 115.64761ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:26:50.865386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14616} {"level":"info","ts":"2026-10-27T00:26:50.948502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14616,"took":"79.627457ms","hash":2157210092,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":31682560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T00:26:50.948561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2157210092,"revision":14616,"compact-revision":12614} {"level":"info","ts":"2026-10-27T00:30:37.311403Z","caller":"traceutil/trace.go:171","msg":"trace[167546872] transaction","detail":"{read_only:false; response_revision:18987; number_of_response:1; }","duration":"155.803895ms","start":"2026-10-27T00:30:37.155579Z","end":"2026-10-27T00:30:37.311383Z","steps":["trace[167546872] 'process raft request' (duration: 155.392754ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.311519Z","caller":"traceutil/trace.go:171","msg":"trace[79025247] transaction","detail":"{read_only:false; response_revision:18989; number_of_response:1; }","duration":"155.720073ms","start":"2026-10-27T00:30:37.155767Z","end":"2026-10-27T00:30:37.311487Z","steps":["trace[79025247] 'process raft request' (duration: 155.353803ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.311585Z","caller":"traceutil/trace.go:171","msg":"trace[1181031112] transaction","detail":"{read_only:false; response_revision:18993; number_of_response:1; }","duration":"142.093287ms","start":"2026-10-27T00:30:37.169437Z","end":"2026-10-27T00:30:37.311531Z","steps":["trace[1181031112] 'process raft request' (duration: 141.779599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:37.311718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.109943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-27T00:30:37.311754Z","caller":"traceutil/trace.go:171","msg":"trace[650654764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18993; }","duration":"131.188635ms","start":"2026-10-27T00:30:37.180554Z","end":"2026-10-27T00:30:37.311743Z","steps":["trace[650654764] 'agreement among raft nodes before linearized reading' (duration: 131.043071ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.311758Z","caller":"traceutil/trace.go:171","msg":"trace[425867082] transaction","detail":"{read_only:false; response_revision:18990; number_of_response:1; }","duration":"155.921649ms","start":"2026-10-27T00:30:37.155797Z","end":"2026-10-27T00:30:37.311719Z","steps":["trace[425867082] 'process raft request' (duration: 155.351123ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.311836Z","caller":"traceutil/trace.go:171","msg":"trace[2097202972] transaction","detail":"{read_only:false; response_revision:18991; number_of_response:1; }","duration":"155.94888ms","start":"2026-10-27T00:30:37.155845Z","end":"2026-10-27T00:30:37.311794Z","steps":["trace[2097202972] 'process raft request' (duration: 155.327392ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.312219Z","caller":"traceutil/trace.go:171","msg":"trace[1988420737] transaction","detail":"{read_only:false; response_revision:18988; number_of_response:1; }","duration":"155.871707ms","start":"2026-10-27T00:30:37.155726Z","end":"2026-10-27T00:30:37.311598Z","steps":["trace[1988420737] 'process raft request' (duration: 155.363903ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.312307Z","caller":"traceutil/trace.go:171","msg":"trace[567853370] transaction","detail":"{read_only:false; response_revision:18992; number_of_response:1; }","duration":"155.518798ms","start":"2026-10-27T00:30:37.156693Z","end":"2026-10-27T00:30:37.312212Z","steps":["trace[567853370] 'process raft request' (duration: 154.50114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:37.325119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.306929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-10-27T00:30:37.325196Z","caller":"traceutil/trace.go:171","msg":"trace[981083277] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:18994; }","duration":"101.389491ms","start":"2026-10-27T00:30:37.223785Z","end":"2026-10-27T00:30:37.325174Z","steps":["trace[981083277] 'agreement among raft nodes before linearized reading' (duration: 101.189266ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.468224Z","caller":"traceutil/trace.go:171","msg":"trace[177775194] transaction","detail":"{read_only:false; response_revision:18998; number_of_response:1; }","duration":"136.510352ms","start":"2026-10-27T00:30:37.331672Z","end":"2026-10-27T00:30:37.468182Z","steps":["trace[177775194] 'process raft request' (duration: 117.087126ms)","trace[177775194] 'compare' (duration: 19.092867ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:30:37.468404Z","caller":"traceutil/trace.go:171","msg":"trace[421530518] transaction","detail":"{read_only:false; response_revision:19000; number_of_response:1; }","duration":"119.044459ms","start":"2026-10-27T00:30:37.349326Z","end":"2026-10-27T00:30:37.46837Z","steps":["trace[421530518] 'process raft request' (duration: 118.864375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:37.468613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.868222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-n9rjk-api-gr4bp\" ","response":"range_response_count:1 size:2533"} {"level":"info","ts":"2026-10-27T00:30:37.468694Z","caller":"traceutil/trace.go:171","msg":"trace[1405730900] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-n9rjk-api-gr4bp; range_end:; response_count:1; response_revision:19000; }","duration":"136.974526ms","start":"2026-10-27T00:30:37.331695Z","end":"2026-10-27T00:30:37.46867Z","steps":["trace[1405730900] 'agreement among raft nodes before linearized reading' (duration: 136.694618ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.468859Z","caller":"traceutil/trace.go:171","msg":"trace[995048660] transaction","detail":"{read_only:false; response_revision:18999; number_of_response:1; }","duration":"131.484523ms","start":"2026-10-27T00:30:37.337313Z","end":"2026-10-27T00:30:37.468797Z","steps":["trace[995048660] 'process raft request' (duration: 130.793684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:37.46927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.160555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-10-27T00:30:37.469322Z","caller":"traceutil/trace.go:171","msg":"trace[1626820936] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:19000; }","duration":"118.261408ms","start":"2026-10-27T00:30:37.351049Z","end":"2026-10-27T00:30:37.46931Z","steps":["trace[1626820936] 'agreement among raft nodes before linearized reading' (duration: 117.337033ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:30:37.867285Z","caller":"traceutil/trace.go:171","msg":"trace[2057105346] linearizableReadLoop","detail":"{readStateIndex:19667; appliedIndex:19666; }","duration":"207.942387ms","start":"2026-10-27T00:30:37.659313Z","end":"2026-10-27T00:30:37.867256Z","steps":["trace[2057105346] 'read index received' (duration: 57.472µs)","trace[2057105346] 'applied index is now lower than readState.Index' (duration: 207.883335ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:30:37.867422Z","caller":"traceutil/trace.go:171","msg":"trace[2108945264] transaction","detail":"{read_only:false; response_revision:19011; number_of_response:1; }","duration":"208.042189ms","start":"2026-10-27T00:30:37.659286Z","end":"2026-10-27T00:30:37.867328Z","steps":["trace[2108945264] 'process raft request' (duration: 207.744741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:37.867592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.297813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:30:37.867645Z","caller":"traceutil/trace.go:171","msg":"trace[516734562] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:19011; }","duration":"205.427457ms","start":"2026-10-27T00:30:37.662204Z","end":"2026-10-27T00:30:37.867631Z","steps":["trace[516734562] 'agreement among raft nodes before linearized reading' (duration: 205.292664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:30:37.867621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.280316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-10-27T00:30:37.867703Z","caller":"traceutil/trace.go:171","msg":"trace[1916388597] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:19011; }","duration":"208.386009ms","start":"2026-10-27T00:30:37.659295Z","end":"2026-10-27T00:30:37.867681Z","steps":["trace[1916388597] 'agreement among raft nodes before linearized reading' (duration: 208.136692ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:31:06.815886Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T00:31:06.822138Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-27T00:31:06.822491Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-27T00:31:50.876838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17120} {"level":"info","ts":"2026-10-27T00:31:50.994931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17120,"took":"109.051304ms","hash":1556003141,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":34189312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T00:31:50.995021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1556003141,"revision":17120,"compact-revision":14616} {"level":"info","ts":"2026-10-27T00:36:20.91854Z","caller":"traceutil/trace.go:171","msg":"trace[748590342] transaction","detail":"{read_only:false; response_revision:21518; number_of_response:1; }","duration":"349.183199ms","start":"2026-10-27T00:36:20.569329Z","end":"2026-10-27T00:36:20.918512Z","steps":["trace[748590342] 'process raft request' (duration: 348.976294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:20.918726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:36:20.569313Z","time spent":"349.328234ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:36:20.918826Z","caller":"traceutil/trace.go:171","msg":"trace[732586483] linearizableReadLoop","detail":"{readStateIndex:22257; appliedIndex:22255; }","duration":"158.177041ms","start":"2026-10-27T00:36:20.760601Z","end":"2026-10-27T00:36:20.918778Z","steps":["trace[732586483] 'read index received' (duration: 157.664357ms)","trace[732586483] 'applied index is now lower than readState.Index' (duration: 510.744µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:36:20.918995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.366947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:36:20.919036Z","caller":"traceutil/trace.go:171","msg":"trace[318245992] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:21519; }","duration":"158.472859ms","start":"2026-10-27T00:36:20.76055Z","end":"2026-10-27T00:36:20.919023Z","steps":["trace[318245992] 'agreement among raft nodes before linearized reading' (duration: 158.349806ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:20.919204Z","caller":"traceutil/trace.go:171","msg":"trace[1687689906] transaction","detail":"{read_only:false; response_revision:21519; number_of_response:1; }","duration":"345.381135ms","start":"2026-10-27T00:36:20.573796Z","end":"2026-10-27T00:36:20.919177Z","steps":["trace[1687689906] 'process raft request' (duration: 344.674065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:20.91938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:36:20.573766Z","time spent":"345.532179ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:36:21.308526Z","caller":"traceutil/trace.go:171","msg":"trace[1371998351] transaction","detail":"{read_only:false; response_revision:21520; number_of_response:1; }","duration":"126.676021ms","start":"2026-10-27T00:36:21.181818Z","end":"2026-10-27T00:36:21.308494Z","steps":["trace[1371998351] 'process raft request' (duration: 126.466565ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:21.6033Z","caller":"traceutil/trace.go:171","msg":"trace[554886324] linearizableReadLoop","detail":"{readStateIndex:22260; appliedIndex:22258; }","duration":"152.085173ms","start":"2026-10-27T00:36:21.451183Z","end":"2026-10-27T00:36:21.603268Z","steps":["trace[554886324] 'read index received' (duration: 69.10562ms)","trace[554886324] 'applied index is now lower than readState.Index' (duration: 82.978293ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:36:21.603454Z","caller":"traceutil/trace.go:171","msg":"trace[652864610] transaction","detail":"{read_only:false; response_revision:21522; number_of_response:1; }","duration":"218.989111ms","start":"2026-10-27T00:36:21.384447Z","end":"2026-10-27T00:36:21.603436Z","steps":["trace[652864610] 'process raft request' (duration: 218.744964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:21.603649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.584797ms","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-27T00:36:21.603719Z","caller":"traceutil/trace.go:171","msg":"trace[2096557087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21522; }","duration":"118.70866ms","start":"2026-10-27T00:36:21.484992Z","end":"2026-10-27T00:36:21.603701Z","steps":["trace[2096557087] 'agreement among raft nodes before linearized reading' (duration: 118.479284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:21.603834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.510525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:36:21.603408Z","caller":"traceutil/trace.go:171","msg":"trace[80445183] transaction","detail":"{read_only:false; response_revision:21521; number_of_response:1; }","duration":"247.319725ms","start":"2026-10-27T00:36:21.356032Z","end":"2026-10-27T00:36:21.603351Z","steps":["trace[80445183] 'process raft request' (duration: 164.128606ms)","trace[80445183] 'compare' (duration: 82.812328ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:36:21.603926Z","caller":"traceutil/trace.go:171","msg":"trace[1552895664] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:21522; }","duration":"152.769812ms","start":"2026-10-27T00:36:21.451129Z","end":"2026-10-27T00:36:21.603899Z","steps":["trace[1552895664] 'agreement among raft nodes before linearized reading' (duration: 152.460933ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:21.92209Z","caller":"traceutil/trace.go:171","msg":"trace[1182930432] transaction","detail":"{read_only:false; response_revision:21524; number_of_response:1; }","duration":"312.973559ms","start":"2026-10-27T00:36:21.609085Z","end":"2026-10-27T00:36:21.922059Z","steps":["trace[1182930432] 'process raft request' (duration: 312.802145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:21.922294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:36:21.609073Z","time spent":"313.098382ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:36:21.92246Z","caller":"traceutil/trace.go:171","msg":"trace[199308348] linearizableReadLoop","detail":"{readStateIndex:22262; appliedIndex:22262; }","duration":"253.857065ms","start":"2026-10-27T00:36:21.668568Z","end":"2026-10-27T00:36:21.922425Z","steps":["trace[199308348] 'read index received' (duration: 253.838905ms)","trace[199308348] 'applied index is now lower than readState.Index' (duration: 14.98µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:36:21.922699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.072182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T00:36:21.922876Z","caller":"traceutil/trace.go:171","msg":"trace[2074347736] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:21524; }","duration":"254.310628ms","start":"2026-10-27T00:36:21.668493Z","end":"2026-10-27T00:36:21.922804Z","steps":["trace[2074347736] 'agreement among raft nodes before linearized reading' (duration: 254.02569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:36:21.982733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.535666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:36:21.982808Z","caller":"traceutil/trace.go:171","msg":"trace[642835095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21526; }","duration":"283.661669ms","start":"2026-10-27T00:36:21.699126Z","end":"2026-10-27T00:36:21.982788Z","steps":["trace[642835095] 'agreement among raft nodes before linearized reading' (duration: 283.517335ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:21.983062Z","caller":"traceutil/trace.go:171","msg":"trace[981158101] transaction","detail":"{read_only:false; response_revision:21526; number_of_response:1; }","duration":"172.453056ms","start":"2026-10-27T00:36:21.810576Z","end":"2026-10-27T00:36:21.983029Z","steps":["trace[981158101] 'process raft request' (duration: 171.941551ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:21.983097Z","caller":"traceutil/trace.go:171","msg":"trace[391340501] transaction","detail":"{read_only:false; response_revision:21525; number_of_response:1; }","duration":"241.218836ms","start":"2026-10-27T00:36:21.741623Z","end":"2026-10-27T00:36:21.982842Z","steps":["trace[391340501] 'process raft request' (duration: 240.756634ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:36:22.197941Z","caller":"traceutil/trace.go:171","msg":"trace[1481422584] transaction","detail":"{read_only:false; response_revision:21527; number_of_response:1; }","duration":"149.346868ms","start":"2026-10-27T00:36:22.048561Z","end":"2026-10-27T00:36:22.197908Z","steps":["trace[1481422584] 'process raft request' (duration: 124.0837ms)","trace[1481422584] 'compare' (duration: 24.852327ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:36:50.885982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19690} {"level":"info","ts":"2026-10-27T00:36:51.036596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19690,"took":"143.642342ms","hash":2914971493,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":29290496,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-27T00:36:51.036667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2914971493,"revision":19690,"compact-revision":17120} {"level":"info","ts":"2026-10-27T00:40:27.878116Z","caller":"traceutil/trace.go:171","msg":"trace[1049017682] transaction","detail":"{read_only:false; response_revision:24065; number_of_response:1; }","duration":"212.138746ms","start":"2026-10-27T00:40:27.665948Z","end":"2026-10-27T00:40:27.878087Z","steps":["trace[1049017682] 'process raft request' (duration: 179.838803ms)","trace[1049017682] 'compare' (duration: 32.118358ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:40:27.878133Z","caller":"traceutil/trace.go:171","msg":"trace[2034548528] linearizableReadLoop","detail":"{readStateIndex:24878; appliedIndex:24877; }","duration":"163.580864ms","start":"2026-10-27T00:40:27.714522Z","end":"2026-10-27T00:40:27.878103Z","steps":["trace[2034548528] 'read index received' (duration: 131.221819ms)","trace[2034548528] 'applied index is now lower than readState.Index' (duration: 32.357715ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:40:27.87848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.951543ms","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-27T00:40:27.878549Z","caller":"traceutil/trace.go:171","msg":"trace[893255830] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24066; }","duration":"164.065046ms","start":"2026-10-27T00:40:27.714464Z","end":"2026-10-27T00:40:27.878529Z","steps":["trace[893255830] 'agreement among raft nodes before linearized reading' (duration: 163.724737ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:41:50.895405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21676} {"level":"info","ts":"2026-10-27T00:41:51.020934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21676,"took":"122.293121ms","hash":2892094723,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":43503616,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-27T00:41:51.020996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2892094723,"revision":21676,"compact-revision":19690} {"level":"info","ts":"2026-10-27T00:43:38.068203Z","caller":"traceutil/trace.go:171","msg":"trace[139151803] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"106.113135ms","start":"2026-10-27T00:43:37.962059Z","end":"2026-10-27T00:43:38.068172Z","steps":["trace[139151803] 'process raft request' (duration: 105.870248ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:43:43.440121Z","caller":"traceutil/trace.go:171","msg":"trace[1602850544] linearizableReadLoop","detail":"{readStateIndex:26833; appliedIndex:26832; }","duration":"110.544047ms","start":"2026-10-27T00:43:43.329546Z","end":"2026-10-27T00:43:43.44009Z","steps":["trace[1602850544] 'read index received' (duration: 110.373082ms)","trace[1602850544] 'applied index is now lower than readState.Index' (duration: 169.785µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:43:43.440184Z","caller":"traceutil/trace.go:171","msg":"trace[91213065] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"110.913487ms","start":"2026-10-27T00:43:43.32923Z","end":"2026-10-27T00:43:43.440144Z","steps":["trace[91213065] 'process raft request' (duration: 110.64853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:43.440294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.744002ms","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":"info","ts":"2026-10-27T00:43:43.440347Z","caller":"traceutil/trace.go:171","msg":"trace[56356517] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25971; }","duration":"110.891386ms","start":"2026-10-27T00:43:43.32944Z","end":"2026-10-27T00:43:43.440332Z","steps":["trace[56356517] 'agreement among raft nodes before linearized reading' (duration: 110.775743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:43.817383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.412547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T00:43:43.817603Z","caller":"traceutil/trace.go:171","msg":"trace[1540797074] transaction","detail":"{read_only:false; response_revision:25972; number_of_response:1; }","duration":"454.877518ms","start":"2026-10-27T00:43:43.362696Z","end":"2026-10-27T00:43:43.817574Z","steps":["trace[1540797074] 'process raft request' (duration: 205.184973ms)","trace[1540797074] 'compare' (duration: 249.127779ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:43:43.817777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:43:43.362664Z","time spent":"455.033612ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:43:43.817965Z","caller":"traceutil/trace.go:171","msg":"trace[826083188] transaction","detail":"{read_only:false; response_revision:25973; number_of_response:1; }","duration":"199.159297ms","start":"2026-10-27T00:43:43.618751Z","end":"2026-10-27T00:43:43.817911Z","steps":["trace[826083188] 'process raft request' (duration: 198.740396ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:43:43.818573Z","caller":"traceutil/trace.go:171","msg":"trace[1802070964] linearizableReadLoop","detail":"{readStateIndex:26834; appliedIndex:26833; }","duration":"209.847692ms","start":"2026-10-27T00:43:43.608711Z","end":"2026-10-27T00:43:43.818559Z","steps":["trace[1802070964] 'read index received' (duration: 53.271µs)","trace[1802070964] 'applied index is now lower than readState.Index' (duration: 209.792901ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:43:43.819006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.297875ms","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-27T00:43:43.819048Z","caller":"traceutil/trace.go:171","msg":"trace[65902623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25973; }","duration":"210.380297ms","start":"2026-10-27T00:43:43.608657Z","end":"2026-10-27T00:43:43.819037Z","steps":["trace[65902623] 'agreement among raft nodes before linearized reading' (duration: 210.257174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:44.16016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.481808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T00:43:44.160362Z","caller":"traceutil/trace.go:171","msg":"trace[135077781] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"337.145463ms","start":"2026-10-27T00:43:43.823197Z","end":"2026-10-27T00:43:44.160343Z","steps":["trace[135077781] 'process raft request' (duration: 337.085502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:44.160428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:43:43.823167Z","time spent":"337.229055ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:43:44.160416Z","caller":"traceutil/trace.go:171","msg":"trace[121465564] linearizableReadLoop","detail":"{readStateIndex:26836; appliedIndex:26835; }","duration":"341.768131ms","start":"2026-10-27T00:43:43.818609Z","end":"2026-10-27T00:43:44.160378Z","steps":["trace[121465564] 'read index received' (duration: 129.965914ms)","trace[121465564] 'applied index is now lower than readState.Index' (duration: 211.800167ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:43:44.160574Z","caller":"traceutil/trace.go:171","msg":"trace[209449567] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"475.755486ms","start":"2026-10-27T00:43:43.684804Z","end":"2026-10-27T00:43:44.160559Z","steps":["trace[209449567] 'process raft request' (duration: 263.772024ms)","trace[209449567] 'compare' (duration: 211.315384ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:43:44.160681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:43:43.684776Z","time spent":"475.822738ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T00:43:44.160852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.37891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T00:43:44.16089Z","caller":"traceutil/trace.go:171","msg":"trace[8898470] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25975; }","duration":"524.454852ms","start":"2026-10-27T00:43:43.636421Z","end":"2026-10-27T00:43:44.160876Z","steps":["trace[8898470] 'agreement among raft nodes before linearized reading' (duration: 524.3914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:44.160923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:43:43.636396Z","time spent":"524.519754ms","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":32,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-10-27T00:43:44.161008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.271711ms","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:6"} {"level":"warn","ts":"2026-10-27T00:43:44.161075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.636141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T00:43:44.161084Z","caller":"traceutil/trace.go:171","msg":"trace[874416606] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25975; }","duration":"246.392993ms","start":"2026-10-27T00:43:43.91467Z","end":"2026-10-27T00:43:44.161063Z","steps":["trace[874416606] 'agreement among raft nodes before linearized reading' (duration: 246.253239ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:43:44.161114Z","caller":"traceutil/trace.go:171","msg":"trace[1546852429] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25975; }","duration":"169.707533ms","start":"2026-10-27T00:43:43.991393Z","end":"2026-10-27T00:43:44.161101Z","steps":["trace[1546852429] 'agreement among raft nodes before linearized reading' (duration: 169.57041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:44.161109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.247744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:43:44.161255Z","caller":"traceutil/trace.go:171","msg":"trace[20745570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25975; }","duration":"461.386128ms","start":"2026-10-27T00:43:43.699813Z","end":"2026-10-27T00:43:44.161199Z","steps":["trace[20745570] 'agreement among raft nodes before linearized reading' (duration: 461.133821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:44.161345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:43:43.699747Z","time spent":"461.575804ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-27T00:43:44.161411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.511917ms","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:8"} {"level":"info","ts":"2026-10-27T00:43:44.161477Z","caller":"traceutil/trace.go:171","msg":"trace[783122984] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25975; }","duration":"365.6157ms","start":"2026-10-27T00:43:43.795847Z","end":"2026-10-27T00:43:44.161463Z","steps":["trace[783122984] 'agreement among raft nodes before linearized reading' (duration: 365.068945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:43:44.161503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:43:43.795836Z","time spent":"365.659871ms","remote":"127.0.0.1:38024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-27T00:44:08.263133Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069da.wal"} {"level":"info","ts":"2026-10-27T00:44:48.679631Z","caller":"traceutil/trace.go:171","msg":"trace[1303490321] linearizableReadLoop","detail":"{readStateIndex:27428; appliedIndex:27427; }","duration":"149.399641ms","start":"2026-10-27T00:44:48.530204Z","end":"2026-10-27T00:44:48.679603Z","steps":["trace[1303490321] 'read index received' (duration: 149.130904ms)","trace[1303490321] 'applied index is now lower than readState.Index' (duration: 267.917µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T00:44:48.679737Z","caller":"traceutil/trace.go:171","msg":"trace[1562593110] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"208.388002ms","start":"2026-10-27T00:44:48.47131Z","end":"2026-10-27T00:44:48.679698Z","steps":["trace[1562593110] 'process raft request' (duration: 208.095014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:44:48.680018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.689166ms","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-27T00:44:48.680089Z","caller":"traceutil/trace.go:171","msg":"trace[1528437082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26553; }","duration":"142.799829ms","start":"2026-10-27T00:44:48.537277Z","end":"2026-10-27T00:44:48.680077Z","steps":["trace[1528437082] 'agreement among raft nodes before linearized reading' (duration: 142.589843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:44:48.680047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.785782ms","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-10-27T00:44:48.680219Z","caller":"traceutil/trace.go:171","msg":"trace[1637043857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26553; }","duration":"149.974618ms","start":"2026-10-27T00:44:48.530187Z","end":"2026-10-27T00:44:48.680162Z","steps":["trace[1637043857] 'agreement among raft nodes before linearized reading' (duration: 149.540706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:44:48.894704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.88328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T00:44:48.895197Z","caller":"traceutil/trace.go:171","msg":"trace[1739251223] linearizableReadLoop","detail":"{readStateIndex:27431; appliedIndex:27428; }","duration":"195.483876ms","start":"2026-10-27T00:44:48.699683Z","end":"2026-10-27T00:44:48.895167Z","steps":["trace[1739251223] 'read index received' (duration: 16.294771ms)","trace[1739251223] 'applied index is now lower than readState.Index' (duration: 179.186835ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:44:48.895349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.672701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T00:44:48.895598Z","caller":"traceutil/trace.go:171","msg":"trace[1869921867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26557; }","duration":"195.751543ms","start":"2026-10-27T00:44:48.699624Z","end":"2026-10-27T00:44:48.895376Z","steps":["trace[1869921867] 'agreement among raft nodes before linearized reading' (duration: 195.632119ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:44:48.89589Z","caller":"traceutil/trace.go:171","msg":"trace[1950329719] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"181.128039ms","start":"2026-10-27T00:44:48.714723Z","end":"2026-10-27T00:44:48.895851Z","steps":["trace[1950329719] 'process raft request' (duration: 180.361148ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:44:48.89588Z","caller":"traceutil/trace.go:171","msg":"trace[211468103] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"211.374965ms","start":"2026-10-27T00:44:48.684465Z","end":"2026-10-27T00:44:48.89584Z","steps":["trace[211468103] 'process raft request' (duration: 210.447379ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:44:48.895901Z","caller":"traceutil/trace.go:171","msg":"trace[801634076] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"211.485378ms","start":"2026-10-27T00:44:48.684371Z","end":"2026-10-27T00:44:48.895856Z","steps":["trace[801634076] 'process raft request' (duration: 54.23425ms)","trace[801634076] 'compare' (duration: 155.539071ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:44:49.254535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.641489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T00:44:49.254906Z","caller":"traceutil/trace.go:171","msg":"trace[1758692755] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"354.988686ms","start":"2026-10-27T00:44:48.899902Z","end":"2026-10-27T00:44:49.254891Z","steps":["trace[1758692755] 'process raft request' (duration: 217.903755ms)","trace[1758692755] 'compare' (duration: 136.415343ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T00:44:49.254953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:44:48.899872Z","time spent":"355.059958ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:44:49.254982Z","caller":"traceutil/trace.go:171","msg":"trace[1625199070] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"336.120914ms","start":"2026-10-27T00:44:48.918845Z","end":"2026-10-27T00:44:49.254966Z","steps":["trace[1625199070] 'process raft request' (duration: 335.790935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T00:44:49.255102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T00:44:48.91879Z","time spent":"336.249948ms","remote":"127.0.0.1:34040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T00:46:50.907115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24960} {"level":"info","ts":"2026-10-27T00:46:51.08267Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24960,"took":"170.374837ms","hash":2887952898,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":36249600,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-27T00:46:51.082759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2887952898,"revision":24960,"compact-revision":21676} {"level":"info","ts":"2026-10-27T00:48:58.38386Z","caller":"traceutil/trace.go:171","msg":"trace[1820370985] transaction","detail":"{read_only:false; response_revision:29033; number_of_response:1; }","duration":"103.140478ms","start":"2026-10-27T00:48:58.280695Z","end":"2026-10-27T00:48:58.383836Z","steps":["trace[1820370985] 'process raft request' (duration: 102.815939ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T00:49:01.439288Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T00:49:01.44815Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-27T00:49:01.448246Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-27T00:51:50.914844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27754} {"level":"info","ts":"2026-10-27T00:51:51.059109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27754,"took":"140.191186ms","hash":426129512,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":33611776,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T00:51:51.059181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":426129512,"revision":27754,"compact-revision":24960} {"level":"info","ts":"2026-10-27T00:56:50.92441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30349} {"level":"info","ts":"2026-10-27T00:56:51.075083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30349,"took":"146.204781ms","hash":4111599956,"current-db-size-bytes":56311808,"current-db-size":"56 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T00:56:51.075168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4111599956,"revision":30349,"compact-revision":27754} {"level":"info","ts":"2026-10-27T01:01:50.93298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32604} {"level":"info","ts":"2026-10-27T01:01:51.103567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32604,"took":"162.350217ms","hash":32885074,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T01:01:51.10364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":32885074,"revision":32604,"compact-revision":30349} {"level":"info","ts":"2026-10-27T01:06:50.941129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34899} {"level":"info","ts":"2026-10-27T01:06:51.084978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34899,"took":"138.733823ms","hash":3657932506,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":33361920,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-27T01:06:51.085044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3657932506,"revision":34899,"compact-revision":32604} {"level":"info","ts":"2026-10-27T01:10:14.444022Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"85ebf4ab7bdf9269","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T01:10:14.4521Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-27T01:10:14.452328Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-27T01:11:50.952068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37225} {"level":"info","ts":"2026-10-27T01:11:51.101398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37225,"took":"141.58668ms","hash":1145744409,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":32784384,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-27T01:11:51.101521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1145744409,"revision":37225,"compact-revision":34899} {"level":"info","ts":"2026-10-27T01:16:50.96079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39500} {"level":"info","ts":"2026-10-27T01:16:51.097051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39500,"took":"132.189675ms","hash":3095610805,"current-db-size-bytes":59510784,"current-db-size":"60 MB","current-db-size-in-use-bytes":31236096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-27T01:16:51.097141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3095610805,"revision":39500,"compact-revision":37225}