{"level":"warn","ts":"2026-08-31T06:54:10.94541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T06:54:10.945552Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.148: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.148:2380","--initial-cluster=instance=https://162.253.55.148:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.148:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.148:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-31T06:54:10.945715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T06:54:10.945749Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-08-31T06:54:10.945801Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-31T06:54:10.947366Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"]} {"level":"info","ts":"2026-08-31T06:54:10.947535Z","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.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.148:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-31T06:54:10.951236Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.124056ms"} {"level":"info","ts":"2026-08-31T06:54:10.95776Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a6342599e225e01b","cluster-id":"8ad3da7aa894cc05"} {"level":"info","ts":"2026-08-31T06:54:10.957863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T06:54:10.957886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 0"} {"level":"info","ts":"2026-08-31T06:54:10.957896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a6342599e225e01b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T06:54:10.957903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 1"} {"level":"info","ts":"2026-08-31T06:54:10.957941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"warn","ts":"2026-08-31T06:54:10.963466Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T06:54:10.965211Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T06:54:10.966761Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-31T06:54:10.969206Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a6342599e225e01b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T06:54:10.969356Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a6342599e225e01b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T06:54:10.969612Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T06:54:10.969708Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T06:54:10.969717Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-31T06:54:10.971198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"info","ts":"2026-08-31T06:54:10.97136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","added-peer-id":"a6342599e225e01b","added-peer-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-08-31T06:54:10.973575Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-31T06:54:10.973667Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-08-31T06:54:10.973884Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-08-31T06:54:10.973941Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a6342599e225e01b","initial-advertise-peer-urls":["https://162.253.55.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T06:54:10.973987Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T06:54:11.158303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T06:54:11.158385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T06:54:11.158431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgPreVoteResp from a6342599e225e01b at term 1"} {"level":"info","ts":"2026-08-31T06:54:11.158456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became candidate at term 2"} {"level":"info","ts":"2026-08-31T06:54:11.158479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgVoteResp from a6342599e225e01b at term 2"} {"level":"info","ts":"2026-08-31T06:54:11.1585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became leader at term 2"} {"level":"info","ts":"2026-08-31T06:54:11.158526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a6342599e225e01b elected leader a6342599e225e01b at term 2"} {"level":"info","ts":"2026-08-31T06:54:11.159671Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:11.160688Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a6342599e225e01b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.148:2379]}","request-path":"/0/members/a6342599e225e01b/attributes","cluster-id":"8ad3da7aa894cc05","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T06:54:11.160694Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T06:54:11.160754Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T06:54:11.161003Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T06:54:11.161052Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T06:54:11.161438Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:11.161576Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:11.161631Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T06:54:11.16409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.148:2379"} {"level":"info","ts":"2026-08-31T06:54:11.164421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-31T06:54:53.963309Z","caller":"traceutil/trace.go:171","msg":"trace[54338790] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"104.615074ms","start":"2026-08-31T06:54:53.85867Z","end":"2026-08-31T06:54:53.963285Z","steps":["trace[54338790] 'process raft request' (duration: 104.464851ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:54:54.00028Z","caller":"traceutil/trace.go:171","msg":"trace[1469862107] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"121.80496ms","start":"2026-08-31T06:54:53.878424Z","end":"2026-08-31T06:54:54.000229Z","steps":["trace[1469862107] 'process raft request' (duration: 121.711068ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:54:54.486158Z","caller":"traceutil/trace.go:171","msg":"trace[774022200] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"136.134958ms","start":"2026-08-31T06:54:54.349981Z","end":"2026-08-31T06:54:54.486116Z","steps":["trace[774022200] 'read index received' (duration: 135.918474ms)","trace[774022200] 'applied index is now lower than readState.Index' (duration: 215.134µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T06:54:54.486321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.303943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T06:54:54.486392Z","caller":"traceutil/trace.go:171","msg":"trace[1288271155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"136.423715ms","start":"2026-08-31T06:54:54.349954Z","end":"2026-08-31T06:54:54.486377Z","steps":["trace[1288271155] 'agreement among raft nodes before linearized reading' (duration: 136.302032ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:54:54.48639Z","caller":"traceutil/trace.go:171","msg":"trace[1779702156] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"154.887372ms","start":"2026-08-31T06:54:54.331475Z","end":"2026-08-31T06:54:54.486362Z","steps":["trace[1779702156] 'process raft request' (duration: 154.440981ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:54:54.659456Z","caller":"traceutil/trace.go:171","msg":"trace[1965309214] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"219.298881ms","start":"2026-08-31T06:54:54.440133Z","end":"2026-08-31T06:54:54.659432Z","steps":["trace[1965309214] 'process raft request' (duration: 217.483213ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:56:25.489897Z","caller":"traceutil/trace.go:171","msg":"trace[479727908] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"147.762425ms","start":"2026-08-31T06:56:25.342098Z","end":"2026-08-31T06:56:25.489861Z","steps":["trace[479727908] 'process raft request' (duration: 147.551091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:56:25.851837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.981038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d213e70-50b5-4348-bded-9d2a48a435ea\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-31T06:56:25.851932Z","caller":"traceutil/trace.go:171","msg":"trace[734776005] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d213e70-50b5-4348-bded-9d2a48a435ea; range_end:; response_count:1; response_revision:1861; }","duration":"120.136071ms","start":"2026-08-31T06:56:25.731772Z","end":"2026-08-31T06:56:25.851908Z","steps":["trace[734776005] 'range keys from in-memory index tree' (duration: 119.855735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:57:09.367368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.543348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T06:57:09.367476Z","caller":"traceutil/trace.go:171","msg":"trace[894154478] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"116.779749ms","start":"2026-08-31T06:57:09.250679Z","end":"2026-08-31T06:57:09.367459Z","steps":["trace[894154478] 'read index received' (duration: 10.509666ms)","trace[894154478] 'applied index is now lower than readState.Index' (duration: 106.268773ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T06:57:09.367532Z","caller":"traceutil/trace.go:171","msg":"trace[1050274237] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"152.083057ms","start":"2026-08-31T06:57:09.215394Z","end":"2026-08-31T06:57:09.367477Z","steps":["trace[1050274237] 'process raft request' (duration: 45.808044ms)","trace[1050274237] 'compare' (duration: 105.432826ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T06:57:09.367643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.958894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-31T06:57:09.367681Z","caller":"traceutil/trace.go:171","msg":"trace[1215657043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"117.027794ms","start":"2026-08-31T06:57:09.250642Z","end":"2026-08-31T06:57:09.36767Z","steps":["trace[1215657043] 'agreement among raft nodes before linearized reading' (duration: 116.904072ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:57:09.817632Z","caller":"traceutil/trace.go:171","msg":"trace[209947068] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"147.483739ms","start":"2026-08-31T06:57:09.670124Z","end":"2026-08-31T06:57:09.817608Z","steps":["trace[209947068] 'read index received' (duration: 147.354876ms)","trace[209947068] 'applied index is now lower than readState.Index' (duration: 127.943µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T06:57:09.817722Z","caller":"traceutil/trace.go:171","msg":"trace[426997966] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"147.762785ms","start":"2026-08-31T06:57:09.669936Z","end":"2026-08-31T06:57:09.817699Z","steps":["trace[426997966] 'process raft request' (duration: 147.505779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:57:09.817843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.690854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T06:57:09.817918Z","caller":"traceutil/trace.go:171","msg":"trace[76106412] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2160; }","duration":"147.811736ms","start":"2026-08-31T06:57:09.670095Z","end":"2026-08-31T06:57:09.817907Z","steps":["trace[76106412] 'agreement among raft nodes before linearized reading' (duration: 147.641192ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:57:15.726267Z","caller":"traceutil/trace.go:171","msg":"trace[1681830982] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"192.719921ms","start":"2026-08-31T06:57:15.533507Z","end":"2026-08-31T06:57:15.726227Z","steps":["trace[1681830982] 'process raft request' (duration: 96.203427ms)","trace[1681830982] 'compare' (duration: 96.374541ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T06:57:15.735825Z","caller":"traceutil/trace.go:171","msg":"trace[1692486719] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"188.462008ms","start":"2026-08-31T06:57:15.547336Z","end":"2026-08-31T06:57:15.735798Z","steps":["trace[1692486719] 'process raft request' (duration: 188.128411ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:57:15.735756Z","caller":"traceutil/trace.go:171","msg":"trace[890851385] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2265; }","duration":"148.067721ms","start":"2026-08-31T06:57:15.58763Z","end":"2026-08-31T06:57:15.735697Z","steps":["trace[890851385] 'read index received' (duration: 42.292138ms)","trace[890851385] 'applied index is now lower than readState.Index' (duration: 105.773903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T06:57:15.736129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.4699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-31T06:57:15.736196Z","caller":"traceutil/trace.go:171","msg":"trace[1150180928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2188; }","duration":"148.624153ms","start":"2026-08-31T06:57:15.587557Z","end":"2026-08-31T06:57:15.736181Z","steps":["trace[1150180928] 'agreement among raft nodes before linearized reading' (duration: 148.314767ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:57:20.866228Z","caller":"traceutil/trace.go:171","msg":"trace[535853491] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"114.122161ms","start":"2026-08-31T06:57:20.75207Z","end":"2026-08-31T06:57:20.866192Z","steps":["trace[535853491] 'process raft request' (duration: 113.850165ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:58:09.843031Z","caller":"traceutil/trace.go:171","msg":"trace[1232328312] linearizableReadLoop","detail":"{readStateIndex:2748; appliedIndex:2747; }","duration":"125.498355ms","start":"2026-08-31T06:58:09.717509Z","end":"2026-08-31T06:58:09.843007Z","steps":["trace[1232328312] 'read index received' (duration: 125.451934ms)","trace[1232328312] 'applied index is now lower than readState.Index' (duration: 45.081µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T06:58:09.843121Z","caller":"traceutil/trace.go:171","msg":"trace[1696625856] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"158.966529ms","start":"2026-08-31T06:58:09.684131Z","end":"2026-08-31T06:58:09.843098Z","steps":["trace[1696625856] 'process raft request' (duration: 158.751244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T06:58:09.843218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.702629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-31T06:58:09.843289Z","caller":"traceutil/trace.go:171","msg":"trace[443266603] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2651; }","duration":"125.820972ms","start":"2026-08-31T06:58:09.717456Z","end":"2026-08-31T06:58:09.843277Z","steps":["trace[443266603] 'agreement among raft nodes before linearized reading' (duration: 125.647748ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T06:58:42.783134Z","caller":"traceutil/trace.go:171","msg":"trace[409816076] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"116.716707ms","start":"2026-08-31T06:58:42.666385Z","end":"2026-08-31T06:58:42.783101Z","steps":["trace[409816076] 'process raft request' (duration: 116.567374ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:02:05.101129Z","caller":"traceutil/trace.go:171","msg":"trace[1755075478] transaction","detail":"{read_only:false; response_revision:4118; number_of_response:1; }","duration":"168.579741ms","start":"2026-08-31T07:02:04.93252Z","end":"2026-08-31T07:02:05.1011Z","steps":["trace[1755075478] 'process raft request' (duration: 109.917824ms)","trace[1755075478] 'compare' (duration: 58.515254ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:03:31.294309Z","caller":"traceutil/trace.go:171","msg":"trace[1980616935] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"133.934452ms","start":"2026-08-31T07:03:31.160337Z","end":"2026-08-31T07:03:31.294272Z","steps":["trace[1980616935] 'process raft request' (duration: 133.470942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:03:37.633621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.433207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:03:37.633844Z","caller":"traceutil/trace.go:171","msg":"trace[145312487] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"169.258413ms","start":"2026-08-31T07:03:37.464566Z","end":"2026-08-31T07:03:37.633824Z","steps":["trace[145312487] 'process raft request' (duration: 169.210852ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:03:37.634168Z","caller":"traceutil/trace.go:171","msg":"trace[1122922218] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"190.656133ms","start":"2026-08-31T07:03:37.443499Z","end":"2026-08-31T07:03:37.634155Z","steps":["trace[1122922218] 'process raft request' (duration: 39.619273ms)","trace[1122922218] 'compare' (duration: 149.996527ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:03:37.634372Z","caller":"traceutil/trace.go:171","msg":"trace[1421028162] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"190.795536ms","start":"2026-08-31T07:03:37.443567Z","end":"2026-08-31T07:03:37.634363Z","steps":["trace[1421028162] 'process raft request' (duration: 190.147052ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:03:37.634507Z","caller":"traceutil/trace.go:171","msg":"trace[2032071921] linearizableReadLoop","detail":"{readStateIndex:5532; appliedIndex:5529; }","duration":"190.168012ms","start":"2026-08-31T07:03:37.44433Z","end":"2026-08-31T07:03:37.634498Z","steps":["trace[2032071921] 'read index received' (duration: 12.257253ms)","trace[2032071921] 'applied index is now lower than readState.Index' (duration: 177.909969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:03:37.634869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.53214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-wsktf-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-08-31T07:03:37.634931Z","caller":"traceutil/trace.go:171","msg":"trace[1802147715] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-wsktf-tls; range_end:; response_count:1; response_revision:5335; }","duration":"190.625192ms","start":"2026-08-31T07:03:37.444295Z","end":"2026-08-31T07:03:37.63492Z","steps":["trace[1802147715] 'agreement among raft nodes before linearized reading' (duration: 190.484619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:03:37.635481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.412197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787060"} {"level":"info","ts":"2026-08-31T07:03:37.635518Z","caller":"traceutil/trace.go:171","msg":"trace[1393888620] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5335; }","duration":"117.487759ms","start":"2026-08-31T07:03:37.518022Z","end":"2026-08-31T07:03:37.63551Z","steps":["trace[1393888620] 'agreement among raft nodes before linearized reading' (duration: 117.06273ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:03:37.861193Z","caller":"traceutil/trace.go:171","msg":"trace[1021500152] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"215.031618ms","start":"2026-08-31T07:03:37.646122Z","end":"2026-08-31T07:03:37.861153Z","steps":["trace[1021500152] 'process raft request' (duration: 134.054325ms)","trace[1021500152] 'compare' (duration: 80.776818ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:03:37.861247Z","caller":"traceutil/trace.go:171","msg":"trace[432486429] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"181.358743ms","start":"2026-08-31T07:03:37.679863Z","end":"2026-08-31T07:03:37.861222Z","steps":["trace[432486429] 'process raft request' (duration: 181.274171ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:03:37.861277Z","caller":"traceutil/trace.go:171","msg":"trace[1835834890] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"194.756092ms","start":"2026-08-31T07:03:37.666472Z","end":"2026-08-31T07:03:37.861228Z","steps":["trace[1835834890] 'read index received' (duration: 113.672267ms)","trace[1835834890] 'applied index is now lower than readState.Index' (duration: 81.080895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:03:37.861709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.482996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-wsktf-tls\" ","response":"range_response_count:1 size:4436"} {"level":"info","ts":"2026-08-31T07:03:37.861763Z","caller":"traceutil/trace.go:171","msg":"trace[1408191928] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-wsktf-tls; range_end:; response_count:1; response_revision:5338; }","duration":"207.590517ms","start":"2026-08-31T07:03:37.654153Z","end":"2026-08-31T07:03:37.861743Z","steps":["trace[1408191928] 'agreement among raft nodes before linearized reading' (duration: 207.301571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:03:37.862037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.649752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-31T07:03:37.862112Z","caller":"traceutil/trace.go:171","msg":"trace[481067213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5338; }","duration":"117.852217ms","start":"2026-08-31T07:03:37.744245Z","end":"2026-08-31T07:03:37.862097Z","steps":["trace[481067213] 'agreement among raft nodes before linearized reading' (duration: 117.52515ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:03:39.845286Z","caller":"traceutil/trace.go:171","msg":"trace[165825735] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"111.933219ms","start":"2026-08-31T07:03:39.733318Z","end":"2026-08-31T07:03:39.845251Z","steps":["trace[165825735] 'process raft request' (duration: 98.832907ms)","trace[165825735] 'compare' (duration: 12.863187ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:00.750522Z","caller":"traceutil/trace.go:171","msg":"trace[1960282655] transaction","detail":"{read_only:false; response_revision:5798; number_of_response:1; }","duration":"131.89205ms","start":"2026-08-31T07:04:00.61859Z","end":"2026-08-31T07:04:00.750482Z","steps":["trace[1960282655] 'process raft request' (duration: 69.077158ms)","trace[1960282655] 'compare' (duration: 62.497635ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:04:11.596Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-08-31T07:04:11.688962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"91.168462ms","hash":4008684605,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":30158848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T07:04:11.68904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4008684605,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-08-31T07:08:12.876405Z","caller":"traceutil/trace.go:171","msg":"trace[1001976398] transaction","detail":"{read_only:false; response_revision:7594; number_of_response:1; }","duration":"123.260639ms","start":"2026-08-31T07:08:12.753111Z","end":"2026-08-31T07:08:12.876372Z","steps":["trace[1001976398] 'process raft request' (duration: 122.633275ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:20.479453Z","caller":"traceutil/trace.go:171","msg":"trace[1380748272] linearizableReadLoop","detail":"{readStateIndex:7909; appliedIndex:7908; }","duration":"175.267724ms","start":"2026-08-31T07:08:20.304158Z","end":"2026-08-31T07:08:20.479425Z","steps":["trace[1380748272] 'read index received' (duration: 175.022709ms)","trace[1380748272] 'applied index is now lower than readState.Index' (duration: 244.225µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:20.479571Z","caller":"traceutil/trace.go:171","msg":"trace[931509823] transaction","detail":"{read_only:false; response_revision:7637; number_of_response:1; }","duration":"246.63908ms","start":"2026-08-31T07:08:20.232902Z","end":"2026-08-31T07:08:20.479541Z","steps":["trace[931509823] 'process raft request' (duration: 246.338763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:20.479688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.36869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vgmdr\" ","response":"range_response_count:1 size:6451"} {"level":"warn","ts":"2026-08-31T07:08:20.479705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.50479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:20.479755Z","caller":"traceutil/trace.go:171","msg":"trace[1890931412] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vgmdr; range_end:; response_count:1; response_revision:7637; }","duration":"159.480422ms","start":"2026-08-31T07:08:20.320263Z","end":"2026-08-31T07:08:20.479744Z","steps":["trace[1890931412] 'agreement among raft nodes before linearized reading' (duration: 159.307389ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:20.479778Z","caller":"traceutil/trace.go:171","msg":"trace[1644408181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7637; }","duration":"127.623353ms","start":"2026-08-31T07:08:20.352141Z","end":"2026-08-31T07:08:20.479764Z","steps":["trace[1644408181] 'agreement among raft nodes before linearized reading' (duration: 127.4987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:20.4798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.634273ms","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-08-31T07:08:20.479894Z","caller":"traceutil/trace.go:171","msg":"trace[1206510092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7637; }","duration":"175.771865ms","start":"2026-08-31T07:08:20.304099Z","end":"2026-08-31T07:08:20.479871Z","steps":["trace[1206510092] 'agreement among raft nodes before linearized reading' (duration: 175.474219ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:20.63386Z","caller":"traceutil/trace.go:171","msg":"trace[447525495] linearizableReadLoop","detail":"{readStateIndex:7910; appliedIndex:7909; }","duration":"149.308774ms","start":"2026-08-31T07:08:20.484526Z","end":"2026-08-31T07:08:20.633834Z","steps":["trace[447525495] 'read index received' (duration: 106.240601ms)","trace[447525495] 'applied index is now lower than readState.Index' (duration: 43.067263ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:20.633881Z","caller":"traceutil/trace.go:171","msg":"trace[1237480378] transaction","detail":"{read_only:false; response_revision:7638; number_of_response:1; }","duration":"149.718112ms","start":"2026-08-31T07:08:20.484117Z","end":"2026-08-31T07:08:20.633835Z","steps":["trace[1237480378] 'process raft request' (duration: 106.539706ms)","trace[1237480378] 'compare' (duration: 42.979372ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:20.634002Z","caller":"traceutil/trace.go:171","msg":"trace[786048510] transaction","detail":"{read_only:false; response_revision:7639; number_of_response:1; }","duration":"124.522377ms","start":"2026-08-31T07:08:20.509444Z","end":"2026-08-31T07:08:20.633967Z","steps":["trace[786048510] 'process raft request' (duration: 124.351883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:20.634252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.699572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:18 size:160629"} {"level":"info","ts":"2026-08-31T07:08:20.634297Z","caller":"traceutil/trace.go:171","msg":"trace[922535856] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:18; response_revision:7639; }","duration":"149.786843ms","start":"2026-08-31T07:08:20.484499Z","end":"2026-08-31T07:08:20.634286Z","steps":["trace[922535856] 'agreement among raft nodes before linearized reading' (duration: 149.448916ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:21.047809Z","caller":"traceutil/trace.go:171","msg":"trace[1821805029] linearizableReadLoop","detail":"{readStateIndex:7915; appliedIndex:7914; }","duration":"104.346639ms","start":"2026-08-31T07:08:20.943439Z","end":"2026-08-31T07:08:21.047786Z","steps":["trace[1821805029] 'read index received' (duration: 104.170565ms)","trace[1821805029] 'applied index is now lower than readState.Index' (duration: 174.954µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:21.04794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.483471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/openstack/barbican-api\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:21.048012Z","caller":"traceutil/trace.go:171","msg":"trace[948868265] range","detail":"{range_begin:/registry/poddisruptionbudgets/openstack/barbican-api; range_end:; response_count:0; response_revision:7643; }","duration":"104.610894ms","start":"2026-08-31T07:08:20.943386Z","end":"2026-08-31T07:08:21.047997Z","steps":["trace[948868265] 'agreement among raft nodes before linearized reading' (duration: 104.493162ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:21.04806Z","caller":"traceutil/trace.go:171","msg":"trace[183792835] transaction","detail":"{read_only:false; response_revision:7643; number_of_response:1; }","duration":"105.332569ms","start":"2026-08-31T07:08:20.942676Z","end":"2026-08-31T07:08:21.048009Z","steps":["trace[183792835] 'process raft request' (duration: 104.962781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.967694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.770324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:08:21.968048Z","caller":"traceutil/trace.go:171","msg":"trace[1065540900] linearizableReadLoop","detail":"{readStateIndex:7928; appliedIndex:7919; }","duration":"522.364739ms","start":"2026-08-31T07:08:21.445668Z","end":"2026-08-31T07:08:21.968033Z","steps":["trace[1065540900] 'read index received' (duration: 117.142586ms)","trace[1065540900] 'applied index is now lower than readState.Index' (duration: 405.221123ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:21.968118Z","caller":"traceutil/trace.go:171","msg":"trace[672656280] transaction","detail":"{read_only:false; response_revision:7652; number_of_response:1; }","duration":"659.261412ms","start":"2026-08-31T07:08:21.308849Z","end":"2026-08-31T07:08:21.968111Z","steps":["trace[672656280] 'process raft request' (duration: 659.031407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.96816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.308829Z","time spent":"659.310524ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:08:21.968162Z","caller":"traceutil/trace.go:171","msg":"trace[1625609386] transaction","detail":"{read_only:false; response_revision:7651; number_of_response:1; }","duration":"659.385825ms","start":"2026-08-31T07:08:21.308716Z","end":"2026-08-31T07:08:21.968102Z","steps":["trace[1625609386] 'process raft request' (duration: 659.14763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.968333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.308681Z","time spent":"659.577699ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:08:21.96834Z","caller":"traceutil/trace.go:171","msg":"trace[1202289579] transaction","detail":"{read_only:false; response_revision:7647; number_of_response:1; }","duration":"660.220444ms","start":"2026-08-31T07:08:21.308089Z","end":"2026-08-31T07:08:21.968309Z","steps":["trace[1202289579] 'process raft request' (duration: 254.773966ms)","trace[1202289579] 'compare' (duration: 404.402216ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:21.968319Z","caller":"traceutil/trace.go:171","msg":"trace[415920587] transaction","detail":"{read_only:false; response_revision:7649; number_of_response:1; }","duration":"659.59857ms","start":"2026-08-31T07:08:21.308681Z","end":"2026-08-31T07:08:21.96828Z","steps":["trace[415920587] 'process raft request' (duration: 659.14ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:21.968352Z","caller":"traceutil/trace.go:171","msg":"trace[1558789220] transaction","detail":"{read_only:false; response_revision:7648; number_of_response:1; }","duration":"659.730703ms","start":"2026-08-31T07:08:21.308585Z","end":"2026-08-31T07:08:21.968315Z","steps":["trace[1558789220] 'process raft request' (duration: 659.195302ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:21.968326Z","caller":"traceutil/trace.go:171","msg":"trace[583238487] transaction","detail":"{read_only:false; response_revision:7655; number_of_response:1; }","duration":"658.478546ms","start":"2026-08-31T07:08:21.309803Z","end":"2026-08-31T07:08:21.968281Z","steps":["trace[583238487] 'process raft request' (duration: 658.185949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.968419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.308072Z","time spent":"660.315796ms","remote":"127.0.0.1:35600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":659,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:08:21.968393Z","caller":"traceutil/trace.go:171","msg":"trace[548600808] transaction","detail":"{read_only:false; response_revision:7654; number_of_response:1; }","duration":"658.879974ms","start":"2026-08-31T07:08:21.309477Z","end":"2026-08-31T07:08:21.968357Z","steps":["trace[548600808] 'process raft request' (duration: 658.451175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.968446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.308573Z","time spent":"659.834115ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:08:21.968453Z","caller":"traceutil/trace.go:171","msg":"trace[787867480] transaction","detail":"{read_only:false; response_revision:7653; number_of_response:1; }","duration":"659.533278ms","start":"2026-08-31T07:08:21.3089Z","end":"2026-08-31T07:08:21.968434Z","steps":["trace[787867480] 'process raft request' (duration: 659.011937ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:21.96842Z","caller":"traceutil/trace.go:171","msg":"trace[174606379] transaction","detail":"{read_only:false; response_revision:7650; number_of_response:1; }","duration":"659.6459ms","start":"2026-08-31T07:08:21.308715Z","end":"2026-08-31T07:08:21.968361Z","steps":["trace[174606379] 'process raft request' (duration: 659.126149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.96851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.308862Z","time spent":"659.62329ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:08:21.96853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.309463Z","time spent":"659.003967ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:08:21.968537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.309794Z","time spent":"658.640449ms","remote":"127.0.0.1:58042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:08:21.968572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.308705Z","time spent":"659.794533ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":531,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:08:21.968777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.440846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-31T07:08:21.968894Z","caller":"traceutil/trace.go:171","msg":"trace[117005035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7655; }","duration":"386.651841ms","start":"2026-08-31T07:08:21.582211Z","end":"2026-08-31T07:08:21.968863Z","steps":["trace[117005035] 'agreement among raft nodes before linearized reading' (duration: 386.174211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.968947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.582187Z","time spent":"386.750593ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-31T07:08:21.969043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.380201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-31T07:08:21.969177Z","caller":"traceutil/trace.go:171","msg":"trace[1625581953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7655; }","duration":"523.569595ms","start":"2026-08-31T07:08:21.445594Z","end":"2026-08-31T07:08:21.969164Z","steps":["trace[1625581953] 'agreement among raft nodes before linearized reading' (duration: 522.749548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:21.969201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.44557Z","time spent":"523.623746ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-31T07:08:21.968568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:21.308661Z","time spent":"659.741712ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:08:22.123Z","caller":"traceutil/trace.go:171","msg":"trace[1334772280] transaction","detail":"{read_only:false; response_revision:7658; number_of_response:1; }","duration":"149.094517ms","start":"2026-08-31T07:08:21.973881Z","end":"2026-08-31T07:08:22.122976Z","steps":["trace[1334772280] 'process raft request' (duration: 148.979825ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.123018Z","caller":"traceutil/trace.go:171","msg":"trace[1908837522] transaction","detail":"{read_only:false; response_revision:7659; number_of_response:1; }","duration":"148.941095ms","start":"2026-08-31T07:08:21.974055Z","end":"2026-08-31T07:08:22.122996Z","steps":["trace[1908837522] 'process raft request' (duration: 148.852143ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.122999Z","caller":"traceutil/trace.go:171","msg":"trace[1027576301] transaction","detail":"{read_only:false; response_revision:7657; number_of_response:1; }","duration":"149.083948ms","start":"2026-08-31T07:08:21.973874Z","end":"2026-08-31T07:08:22.122958Z","steps":["trace[1027576301] 'process raft request' (duration: 132.616081ms)","trace[1027576301] 'compare' (duration: 16.173551ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:22.123201Z","caller":"traceutil/trace.go:171","msg":"trace[41508442] transaction","detail":"{read_only:false; response_revision:7661; number_of_response:1; }","duration":"148.923534ms","start":"2026-08-31T07:08:21.974264Z","end":"2026-08-31T07:08:22.123188Z","steps":["trace[41508442] 'process raft request' (duration: 148.7187ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.123225Z","caller":"traceutil/trace.go:171","msg":"trace[1027003498] transaction","detail":"{read_only:false; response_revision:7662; number_of_response:1; }","duration":"148.904104ms","start":"2026-08-31T07:08:21.974281Z","end":"2026-08-31T07:08:22.123185Z","steps":["trace[1027003498] 'process raft request' (duration: 148.756591ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.12328Z","caller":"traceutil/trace.go:171","msg":"trace[1495078391] transaction","detail":"{read_only:false; response_revision:7660; number_of_response:1; }","duration":"148.982675ms","start":"2026-08-31T07:08:21.974212Z","end":"2026-08-31T07:08:22.123195Z","steps":["trace[1495078391] 'process raft request' (duration: 148.7305ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.123407Z","caller":"traceutil/trace.go:171","msg":"trace[1926458858] transaction","detail":"{read_only:false; response_revision:7664; number_of_response:1; }","duration":"149.006916ms","start":"2026-08-31T07:08:21.974384Z","end":"2026-08-31T07:08:22.123391Z","steps":["trace[1926458858] 'process raft request' (duration: 148.881024ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.123498Z","caller":"traceutil/trace.go:171","msg":"trace[1256343380] transaction","detail":"{read_only:false; response_revision:7663; number_of_response:1; }","duration":"149.1989ms","start":"2026-08-31T07:08:21.974287Z","end":"2026-08-31T07:08:22.123486Z","steps":["trace[1256343380] 'process raft request' (duration: 148.783111ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.123526Z","caller":"traceutil/trace.go:171","msg":"trace[1144464444] linearizableReadLoop","detail":"{readStateIndex:7935; appliedIndex:7928; }","duration":"149.221041ms","start":"2026-08-31T07:08:21.974293Z","end":"2026-08-31T07:08:22.123514Z","steps":["trace[1144464444] 'read index received' (duration: 60.826348ms)","trace[1144464444] 'applied index is now lower than readState.Index' (duration: 88.393653ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:22.123586Z","caller":"traceutil/trace.go:171","msg":"trace[1643319030] transaction","detail":"{read_only:false; response_revision:7665; number_of_response:1; }","duration":"103.014869ms","start":"2026-08-31T07:08:22.020558Z","end":"2026-08-31T07:08:22.123573Z","steps":["trace[1643319030] 'process raft request' (duration: 102.796064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.123713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.390244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api.18d0d22357d406ae\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-31T07:08:22.123764Z","caller":"traceutil/trace.go:171","msg":"trace[2102703442] range","detail":"{range_begin:/registry/events/openstack/barbican-api.18d0d22357d406ae; range_end:; response_count:1; response_revision:7665; }","duration":"149.771662ms","start":"2026-08-31T07:08:21.973983Z","end":"2026-08-31T07:08:22.123755Z","steps":["trace[2102703442] 'agreement among raft nodes before linearized reading' (duration: 149.588698ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.279437Z","caller":"traceutil/trace.go:171","msg":"trace[943937309] transaction","detail":"{read_only:false; response_revision:7667; number_of_response:1; }","duration":"148.809211ms","start":"2026-08-31T07:08:22.130592Z","end":"2026-08-31T07:08:22.279402Z","steps":["trace[943937309] 'process raft request' (duration: 138.645681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.279682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.20158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:22.279795Z","caller":"traceutil/trace.go:171","msg":"trace[137767066] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:7667; }","duration":"137.368324ms","start":"2026-08-31T07:08:22.142405Z","end":"2026-08-31T07:08:22.279773Z","steps":["trace[137767066] 'agreement among raft nodes before linearized reading' (duration: 137.17814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.365653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.011361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-31T07:08:22.365781Z","caller":"traceutil/trace.go:171","msg":"trace[1468585626] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7668; }","duration":"140.194486ms","start":"2026-08-31T07:08:22.225557Z","end":"2026-08-31T07:08:22.365752Z","steps":["trace[1468585626] 'agreement among raft nodes before linearized reading' (duration: 139.91216ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.54764Z","caller":"traceutil/trace.go:171","msg":"trace[2115845651] transaction","detail":"{read_only:false; response_revision:7669; number_of_response:1; }","duration":"259.767484ms","start":"2026-08-31T07:08:22.287841Z","end":"2026-08-31T07:08:22.547609Z","steps":["trace[2115845651] 'process raft request' (duration: 259.433847ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.547751Z","caller":"traceutil/trace.go:171","msg":"trace[987569411] transaction","detail":"{read_only:false; response_revision:7671; number_of_response:1; }","duration":"259.124081ms","start":"2026-08-31T07:08:22.288575Z","end":"2026-08-31T07:08:22.547699Z","steps":["trace[987569411] 'process raft request' (duration: 258.979048ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.547858Z","caller":"traceutil/trace.go:171","msg":"trace[684619163] transaction","detail":"{read_only:false; response_revision:7672; number_of_response:1; }","duration":"259.170682ms","start":"2026-08-31T07:08:22.288647Z","end":"2026-08-31T07:08:22.547818Z","steps":["trace[684619163] 'process raft request' (duration: 258.947097ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.547886Z","caller":"traceutil/trace.go:171","msg":"trace[791551890] transaction","detail":"{read_only:false; response_revision:7670; number_of_response:1; }","duration":"259.874347ms","start":"2026-08-31T07:08:22.28799Z","end":"2026-08-31T07:08:22.547865Z","steps":["trace[791551890] 'process raft request' (duration: 259.510869ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.548018Z","caller":"traceutil/trace.go:171","msg":"trace[677445014] transaction","detail":"{read_only:false; response_revision:7673; number_of_response:1; }","duration":"258.459205ms","start":"2026-08-31T07:08:22.289476Z","end":"2026-08-31T07:08:22.547936Z","steps":["trace[677445014] 'process raft request' (duration: 258.159749ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.548146Z","caller":"traceutil/trace.go:171","msg":"trace[1023744250] transaction","detail":"{read_only:false; response_revision:7674; number_of_response:1; }","duration":"258.495706ms","start":"2026-08-31T07:08:22.289633Z","end":"2026-08-31T07:08:22.548128Z","steps":["trace[1023744250] 'process raft request' (duration: 258.041826ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.548137Z","caller":"traceutil/trace.go:171","msg":"trace[1680820228] linearizableReadLoop","detail":"{readStateIndex:7948; appliedIndex:7941; }","duration":"182.701876ms","start":"2026-08-31T07:08:22.365412Z","end":"2026-08-31T07:08:22.548113Z","steps":["trace[1680820228] 'read index received' (duration: 181.768646ms)","trace[1680820228] 'applied index is now lower than readState.Index' (duration: 931.9µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:22.548344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.69033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:22.548454Z","caller":"traceutil/trace.go:171","msg":"trace[910458417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7675; }","duration":"197.861184ms","start":"2026-08-31T07:08:22.350574Z","end":"2026-08-31T07:08:22.548435Z","steps":["trace[910458417] 'agreement among raft nodes before linearized reading' (duration: 197.600618ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.548539Z","caller":"traceutil/trace.go:171","msg":"trace[280110357] transaction","detail":"{read_only:false; response_revision:7675; number_of_response:1; }","duration":"258.830714ms","start":"2026-08-31T07:08:22.289693Z","end":"2026-08-31T07:08:22.548523Z","steps":["trace[280110357] 'process raft request' (duration: 258.21601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.54838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.499893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-31T07:08:22.548659Z","caller":"traceutil/trace.go:171","msg":"trace[1413665894] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7675; }","duration":"159.793239ms","start":"2026-08-31T07:08:22.388819Z","end":"2026-08-31T07:08:22.548613Z","steps":["trace[1413665894] 'agreement among raft nodes before linearized reading' (duration: 159.442542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.72756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.192452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:08:22.727684Z","caller":"traceutil/trace.go:171","msg":"trace[930635784] transaction","detail":"{read_only:false; response_revision:7676; number_of_response:1; }","duration":"173.744772ms","start":"2026-08-31T07:08:22.553917Z","end":"2026-08-31T07:08:22.727662Z","steps":["trace[930635784] 'process raft request' (duration: 29.341735ms)","trace[930635784] 'compare' (duration: 143.918276ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:22.76432Z","caller":"traceutil/trace.go:171","msg":"trace[1900012907] transaction","detail":"{read_only:false; response_revision:7677; number_of_response:1; }","duration":"210.348614ms","start":"2026-08-31T07:08:22.553955Z","end":"2026-08-31T07:08:22.764303Z","steps":["trace[1900012907] 'process raft request' (duration: 210.14213ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.764335Z","caller":"traceutil/trace.go:171","msg":"trace[500068124] transaction","detail":"{read_only:false; response_revision:7678; number_of_response:1; }","duration":"210.005567ms","start":"2026-08-31T07:08:22.554291Z","end":"2026-08-31T07:08:22.764297Z","steps":["trace[500068124] 'process raft request' (duration: 209.900875ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.764465Z","caller":"traceutil/trace.go:171","msg":"trace[1483368571] transaction","detail":"{read_only:false; response_revision:7679; number_of_response:1; }","duration":"210.118119ms","start":"2026-08-31T07:08:22.554317Z","end":"2026-08-31T07:08:22.764435Z","steps":["trace[1483368571] 'process raft request' (duration: 209.899414ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.764551Z","caller":"traceutil/trace.go:171","msg":"trace[2007439773] transaction","detail":"{read_only:false; response_revision:7683; number_of_response:1; }","duration":"209.034805ms","start":"2026-08-31T07:08:22.555489Z","end":"2026-08-31T07:08:22.764523Z","steps":["trace[2007439773] 'process raft request' (duration: 208.980693ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.764579Z","caller":"traceutil/trace.go:171","msg":"trace[91192141] linearizableReadLoop","detail":"{readStateIndex:7956; appliedIndex:7948; }","duration":"204.675522ms","start":"2026-08-31T07:08:22.559895Z","end":"2026-08-31T07:08:22.76457Z","steps":["trace[91192141] 'read index received' (duration: 23.416938ms)","trace[91192141] 'applied index is now lower than readState.Index' (duration: 181.257884ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:22.764468Z","caller":"traceutil/trace.go:171","msg":"trace[1324940020] transaction","detail":"{read_only:false; response_revision:7680; number_of_response:1; }","duration":"210.006457ms","start":"2026-08-31T07:08:22.554424Z","end":"2026-08-31T07:08:22.76443Z","steps":["trace[1324940020] 'process raft request' (duration: 209.816513ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.764511Z","caller":"traceutil/trace.go:171","msg":"trace[524024976] transaction","detail":"{read_only:false; response_revision:7682; number_of_response:1; }","duration":"209.70202ms","start":"2026-08-31T07:08:22.554801Z","end":"2026-08-31T07:08:22.764503Z","steps":["trace[524024976] 'process raft request' (duration: 209.642639ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.76454Z","caller":"traceutil/trace.go:171","msg":"trace[1799025054] transaction","detail":"{read_only:false; response_revision:7681; number_of_response:1; }","duration":"210.023737ms","start":"2026-08-31T07:08:22.55451Z","end":"2026-08-31T07:08:22.764534Z","steps":["trace[1799025054] 'process raft request' (duration: 209.748601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.76485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.949857ms","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:650"} {"level":"info","ts":"2026-08-31T07:08:22.764879Z","caller":"traceutil/trace.go:171","msg":"trace[397020125] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:7683; }","duration":"205.003918ms","start":"2026-08-31T07:08:22.559866Z","end":"2026-08-31T07:08:22.76487Z","steps":["trace[397020125] 'agreement among raft nodes before linearized reading' (duration: 204.728602ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.772644Z","caller":"traceutil/trace.go:171","msg":"trace[1471623030] transaction","detail":"{read_only:false; response_revision:7684; number_of_response:1; }","duration":"132.281323ms","start":"2026-08-31T07:08:22.640348Z","end":"2026-08-31T07:08:22.77263Z","steps":["trace[1471623030] 'process raft request' (duration: 132.069909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.772802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.831149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-31T07:08:22.772822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.587037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-08-31T07:08:22.772859Z","caller":"traceutil/trace.go:171","msg":"trace[1340381735] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:7685; }","duration":"185.67182ms","start":"2026-08-31T07:08:22.587176Z","end":"2026-08-31T07:08:22.772847Z","steps":["trace[1340381735] 'agreement among raft nodes before linearized reading' (duration: 185.590328ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:22.772891Z","caller":"traceutil/trace.go:171","msg":"trace[540405649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7685; }","duration":"134.962121ms","start":"2026-08-31T07:08:22.637913Z","end":"2026-08-31T07:08:22.772876Z","steps":["trace[540405649] 'agreement among raft nodes before linearized reading' (duration: 134.731596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:22.772993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.103839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vgmdr\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-31T07:08:22.773025Z","caller":"traceutil/trace.go:171","msg":"trace[1946657663] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vgmdr; range_end:; response_count:1; response_revision:7685; }","duration":"131.18907ms","start":"2026-08-31T07:08:22.641828Z","end":"2026-08-31T07:08:22.773017Z","steps":["trace[1946657663] 'agreement among raft nodes before linearized reading' (duration: 131.090598ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.014269Z","caller":"traceutil/trace.go:171","msg":"trace[2104661585] transaction","detail":"{read_only:false; response_revision:7691; number_of_response:1; }","duration":"151.637643ms","start":"2026-08-31T07:08:22.862599Z","end":"2026-08-31T07:08:23.014237Z","steps":["trace[2104661585] 'process raft request' (duration: 151.50186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.014412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.45621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-31T07:08:23.014403Z","caller":"traceutil/trace.go:171","msg":"trace[2083405887] transaction","detail":"{read_only:false; response_revision:7690; number_of_response:1; }","duration":"151.9504ms","start":"2026-08-31T07:08:22.862432Z","end":"2026-08-31T07:08:23.014383Z","steps":["trace[2083405887] 'process raft request' (duration: 151.553611ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.014476Z","caller":"traceutil/trace.go:171","msg":"trace[1475327927] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:7692; }","duration":"152.554932ms","start":"2026-08-31T07:08:22.861907Z","end":"2026-08-31T07:08:23.014462Z","steps":["trace[1475327927] 'agreement among raft nodes before linearized reading' (duration: 152.338748ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.014308Z","caller":"traceutil/trace.go:171","msg":"trace[1550495055] transaction","detail":"{read_only:false; response_revision:7692; number_of_response:1; }","duration":"149.752731ms","start":"2026-08-31T07:08:22.864525Z","end":"2026-08-31T07:08:23.014278Z","steps":["trace[1550495055] 'process raft request' (duration: 149.623999ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.209062Z","caller":"traceutil/trace.go:171","msg":"trace[169190041] linearizableReadLoop","detail":"{readStateIndex:7967; appliedIndex:7966; }","duration":"125.487767ms","start":"2026-08-31T07:08:23.08355Z","end":"2026-08-31T07:08:23.209037Z","steps":["trace[169190041] 'read index received' (duration: 125.374104ms)","trace[169190041] 'applied index is now lower than readState.Index' (duration: 112.512µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:23.209219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.361382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-db-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:08:23.209248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.977204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:23.209311Z","caller":"traceutil/trace.go:171","msg":"trace[2101803144] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:7694; }","duration":"185.077537ms","start":"2026-08-31T07:08:23.024217Z","end":"2026-08-31T07:08:23.209295Z","steps":["trace[2101803144] 'agreement among raft nodes before linearized reading' (duration: 184.965475ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.20931Z","caller":"traceutil/trace.go:171","msg":"trace[1745082150] range","detail":"{range_begin:/registry/jobs/openstack/barbican-db-init; range_end:; response_count:0; response_revision:7694; }","duration":"178.484294ms","start":"2026-08-31T07:08:23.030804Z","end":"2026-08-31T07:08:23.209288Z","steps":["trace[1745082150] 'agreement among raft nodes before linearized reading' (duration: 178.28266ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.209695Z","caller":"traceutil/trace.go:171","msg":"trace[1972169995] transaction","detail":"{read_only:false; response_revision:7694; number_of_response:1; }","duration":"157.327426ms","start":"2026-08-31T07:08:23.052344Z","end":"2026-08-31T07:08:23.209671Z","steps":["trace[1972169995] 'process raft request' (duration: 156.549889ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.264391Z","caller":"traceutil/trace.go:171","msg":"trace[1344109161] transaction","detail":"{read_only:false; response_revision:7696; number_of_response:1; }","duration":"172.499535ms","start":"2026-08-31T07:08:23.091858Z","end":"2026-08-31T07:08:23.264357Z","steps":["trace[1344109161] 'process raft request' (duration: 172.358502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.26478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.268692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:23.264852Z","caller":"traceutil/trace.go:171","msg":"trace[1923710374] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:7696; }","duration":"174.435506ms","start":"2026-08-31T07:08:23.090397Z","end":"2026-08-31T07:08:23.264832Z","steps":["trace[1923710374] 'agreement among raft nodes before linearized reading' (duration: 174.280303ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.264953Z","caller":"traceutil/trace.go:171","msg":"trace[1724771814] transaction","detail":"{read_only:false; response_revision:7695; number_of_response:1; }","duration":"176.543692ms","start":"2026-08-31T07:08:23.088367Z","end":"2026-08-31T07:08:23.264911Z","steps":["trace[1724771814] 'process raft request' (duration: 175.723144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.264983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.587117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-31T07:08:23.265028Z","caller":"traceutil/trace.go:171","msg":"trace[1972678917] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:7696; }","duration":"179.678819ms","start":"2026-08-31T07:08:23.085335Z","end":"2026-08-31T07:08:23.265014Z","steps":["trace[1972678917] 'agreement among raft nodes before linearized reading' (duration: 179.466675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.330229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.284999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-08-31T07:08:23.33031Z","caller":"traceutil/trace.go:171","msg":"trace[694413909] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:7697; }","duration":"117.413292ms","start":"2026-08-31T07:08:23.212877Z","end":"2026-08-31T07:08:23.33029Z","steps":["trace[694413909] 'agreement among raft nodes before linearized reading' (duration: 117.195987ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.640188Z","caller":"traceutil/trace.go:171","msg":"trace[305458259] linearizableReadLoop","detail":"{readStateIndex:7983; appliedIndex:7980; }","duration":"120.973789ms","start":"2026-08-31T07:08:23.519192Z","end":"2026-08-31T07:08:23.640166Z","steps":["trace[305458259] 'read index received' (duration: 110.681656ms)","trace[305458259] 'applied index is now lower than readState.Index' (duration: 10.291093ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:23.640218Z","caller":"traceutil/trace.go:171","msg":"trace[1153306607] transaction","detail":"{read_only:false; response_revision:7709; number_of_response:1; }","duration":"163.699455ms","start":"2026-08-31T07:08:23.476488Z","end":"2026-08-31T07:08:23.640187Z","steps":["trace[1153306607] 'process raft request' (duration: 163.558682ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.640362Z","caller":"traceutil/trace.go:171","msg":"trace[268982182] transaction","detail":"{read_only:false; response_revision:7708; number_of_response:1; }","duration":"164.298828ms","start":"2026-08-31T07:08:23.476032Z","end":"2026-08-31T07:08:23.640331Z","steps":["trace[268982182] 'process raft request' (duration: 153.648747ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.640579Z","caller":"traceutil/trace.go:171","msg":"trace[1032523640] transaction","detail":"{read_only:false; response_revision:7710; number_of_response:1; }","duration":"163.186553ms","start":"2026-08-31T07:08:23.477377Z","end":"2026-08-31T07:08:23.640564Z","steps":["trace[1032523640] 'process raft request' (duration: 162.736013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.640973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.865067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-08-31T07:08:23.64117Z","caller":"traceutil/trace.go:171","msg":"trace[1960366919] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:7710; }","duration":"164.106122ms","start":"2026-08-31T07:08:23.477052Z","end":"2026-08-31T07:08:23.641158Z","steps":["trace[1960366919] 'agreement among raft nodes before linearized reading' (duration: 163.784176ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.664193Z","caller":"traceutil/trace.go:171","msg":"trace[1163743453] transaction","detail":"{read_only:false; response_revision:7711; number_of_response:1; }","duration":"135.135585ms","start":"2026-08-31T07:08:23.52904Z","end":"2026-08-31T07:08:23.664175Z","steps":["trace[1163743453] 'process raft request' (duration: 134.90744ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.664299Z","caller":"traceutil/trace.go:171","msg":"trace[1736740878] transaction","detail":"{read_only:false; response_revision:7712; number_of_response:1; }","duration":"133.000139ms","start":"2026-08-31T07:08:23.53126Z","end":"2026-08-31T07:08:23.66426Z","steps":["trace[1736740878] 'process raft request' (duration: 132.833046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.664423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.801415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-31T07:08:23.664466Z","caller":"traceutil/trace.go:171","msg":"trace[1818450381] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7712; }","duration":"139.889157ms","start":"2026-08-31T07:08:23.524563Z","end":"2026-08-31T07:08:23.664452Z","steps":["trace[1818450381] 'agreement among raft nodes before linearized reading' (duration: 139.761544ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:23.853698Z","caller":"traceutil/trace.go:171","msg":"trace[464227742] linearizableReadLoop","detail":"{readStateIndex:7988; appliedIndex:7986; }","duration":"129.579345ms","start":"2026-08-31T07:08:23.72409Z","end":"2026-08-31T07:08:23.853669Z","steps":["trace[464227742] 'read index received' (duration: 75.886143ms)","trace[464227742] 'applied index is now lower than readState.Index' (duration: 53.692162ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:23.853896Z","caller":"traceutil/trace.go:171","msg":"trace[1171454370] transaction","detail":"{read_only:false; response_revision:7715; number_of_response:1; }","duration":"133.622653ms","start":"2026-08-31T07:08:23.72023Z","end":"2026-08-31T07:08:23.853852Z","steps":["trace[1171454370] 'process raft request' (duration: 133.274716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:23.854024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.911832ms","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:708"} {"level":"info","ts":"2026-08-31T07:08:23.854084Z","caller":"traceutil/trace.go:171","msg":"trace[2074888620] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:7715; }","duration":"129.991614ms","start":"2026-08-31T07:08:23.724076Z","end":"2026-08-31T07:08:23.854068Z","steps":["trace[2074888620] 'agreement among raft nodes before linearized reading' (duration: 129.770709ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:24.473771Z","caller":"traceutil/trace.go:171","msg":"trace[2088579921] transaction","detail":"{read_only:false; response_revision:7722; number_of_response:1; }","duration":"206.971291ms","start":"2026-08-31T07:08:24.266772Z","end":"2026-08-31T07:08:24.473743Z","steps":["trace[2088579921] 'process raft request' (duration: 121.211736ms)","trace[2088579921] 'compare' (duration: 85.597542ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:24.519042Z","caller":"traceutil/trace.go:171","msg":"trace[680574364] linearizableReadLoop","detail":"{readStateIndex:7996; appliedIndex:7994; }","duration":"167.769401ms","start":"2026-08-31T07:08:24.351248Z","end":"2026-08-31T07:08:24.519017Z","steps":["trace[680574364] 'read index received' (duration: 36.746566ms)","trace[680574364] 'applied index is now lower than readState.Index' (duration: 131.021685ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:24.519176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.917625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:24.519205Z","caller":"traceutil/trace.go:171","msg":"trace[1633419888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7723; }","duration":"167.999927ms","start":"2026-08-31T07:08:24.351195Z","end":"2026-08-31T07:08:24.519195Z","steps":["trace[1633419888] 'agreement among raft nodes before linearized reading' (duration: 167.931576ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:24.519164Z","caller":"traceutil/trace.go:171","msg":"trace[79647337] transaction","detail":"{read_only:false; response_revision:7723; number_of_response:1; }","duration":"249.84388ms","start":"2026-08-31T07:08:24.269279Z","end":"2026-08-31T07:08:24.519123Z","steps":["trace[79647337] 'process raft request' (duration: 249.600843ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:24.63489Z","caller":"traceutil/trace.go:171","msg":"trace[1185801896] linearizableReadLoop","detail":"{readStateIndex:7997; appliedIndex:7996; }","duration":"115.764516ms","start":"2026-08-31T07:08:24.519102Z","end":"2026-08-31T07:08:24.634866Z","steps":["trace[1185801896] 'read index received' (duration: 112.769731ms)","trace[1185801896] 'applied index is now lower than readState.Index' (duration: 2.993875ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:24.635043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.225184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:08:24.635028Z","caller":"traceutil/trace.go:171","msg":"trace[1813593347] transaction","detail":"{read_only:false; response_revision:7724; number_of_response:1; }","duration":"151.254225ms","start":"2026-08-31T07:08:24.483744Z","end":"2026-08-31T07:08:24.634998Z","steps":["trace[1813593347] 'process raft request' (duration: 148.145168ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:24.635082Z","caller":"traceutil/trace.go:171","msg":"trace[360721933] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:7724; }","duration":"181.328756ms","start":"2026-08-31T07:08:24.453743Z","end":"2026-08-31T07:08:24.635071Z","steps":["trace[360721933] 'agreement among raft nodes before linearized reading' (duration: 181.214504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:24.635151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.74046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-31T07:08:24.635205Z","caller":"traceutil/trace.go:171","msg":"trace[828722760] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7724; }","duration":"172.831132ms","start":"2026-08-31T07:08:24.462362Z","end":"2026-08-31T07:08:24.635193Z","steps":["trace[828722760] 'agreement among raft nodes before linearized reading' (duration: 172.656768ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:25.687421Z","caller":"traceutil/trace.go:171","msg":"trace[403370062] transaction","detail":"{read_only:false; response_revision:7733; number_of_response:1; }","duration":"168.808505ms","start":"2026-08-31T07:08:25.518585Z","end":"2026-08-31T07:08:25.687394Z","steps":["trace[403370062] 'process raft request' (duration: 168.650172ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:26.386486Z","caller":"traceutil/trace.go:171","msg":"trace[598650049] transaction","detail":"{read_only:false; response_revision:7738; number_of_response:1; }","duration":"118.506705ms","start":"2026-08-31T07:08:26.267946Z","end":"2026-08-31T07:08:26.386453Z","steps":["trace[598650049] 'process raft request' (duration: 118.335851ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:29.032964Z","caller":"traceutil/trace.go:171","msg":"trace[17714835] transaction","detail":"{read_only:false; response_revision:7755; number_of_response:1; }","duration":"138.605411ms","start":"2026-08-31T07:08:28.89433Z","end":"2026-08-31T07:08:29.032935Z","steps":["trace[17714835] 'process raft request' (duration: 94.538997ms)","trace[17714835] 'compare' (duration: 43.87916ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:29.03318Z","caller":"traceutil/trace.go:171","msg":"trace[1962365037] transaction","detail":"{read_only:false; response_revision:7756; number_of_response:1; }","duration":"138.735824ms","start":"2026-08-31T07:08:28.894415Z","end":"2026-08-31T07:08:29.033151Z","steps":["trace[1962365037] 'process raft request' (duration: 138.474298ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:31.234471Z","caller":"traceutil/trace.go:171","msg":"trace[1124037832] transaction","detail":"{read_only:false; response_revision:7772; number_of_response:1; }","duration":"103.273116ms","start":"2026-08-31T07:08:31.131171Z","end":"2026-08-31T07:08:31.234444Z","steps":["trace[1124037832] 'process raft request' (duration: 103.224865ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:31.234493Z","caller":"traceutil/trace.go:171","msg":"trace[340753216] transaction","detail":"{read_only:false; response_revision:7771; number_of_response:1; }","duration":"196.091315ms","start":"2026-08-31T07:08:31.038363Z","end":"2026-08-31T07:08:31.234455Z","steps":["trace[340753216] 'process raft request' (duration: 135.78954ms)","trace[340753216] 'compare' (duration: 60.076991ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:31.234497Z","caller":"traceutil/trace.go:171","msg":"trace[388523521] linearizableReadLoop","detail":"{readStateIndex:8046; appliedIndex:8044; }","duration":"189.856381ms","start":"2026-08-31T07:08:31.044608Z","end":"2026-08-31T07:08:31.234464Z","steps":["trace[388523521] 'read index received' (duration: 82.599518ms)","trace[388523521] 'applied index is now lower than readState.Index' (duration: 107.253793ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:31.2348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.167537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vgmdr\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-31T07:08:31.234867Z","caller":"traceutil/trace.go:171","msg":"trace[59137218] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vgmdr; range_end:; response_count:1; response_revision:7772; }","duration":"190.28452ms","start":"2026-08-31T07:08:31.044567Z","end":"2026-08-31T07:08:31.234851Z","steps":["trace[59137218] 'agreement among raft nodes before linearized reading' (duration: 190.056805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:31.234943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.822948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-31T07:08:31.234988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.671013ms","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-08-31T07:08:31.235037Z","caller":"traceutil/trace.go:171","msg":"trace[1213504118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7772; }","duration":"182.947361ms","start":"2026-08-31T07:08:31.052055Z","end":"2026-08-31T07:08:31.235002Z","steps":["trace[1213504118] 'agreement among raft nodes before linearized reading' (duration: 182.723166ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:31.23509Z","caller":"traceutil/trace.go:171","msg":"trace[709028645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7772; }","duration":"157.790646ms","start":"2026-08-31T07:08:31.077259Z","end":"2026-08-31T07:08:31.235049Z","steps":["trace[709028645] 'agreement among raft nodes before linearized reading' (duration: 157.530661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:31.807524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.42338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:08:31.80766Z","caller":"traceutil/trace.go:171","msg":"trace[2039984244] transaction","detail":"{read_only:false; response_revision:7773; number_of_response:1; }","duration":"568.069248ms","start":"2026-08-31T07:08:31.239565Z","end":"2026-08-31T07:08:31.807634Z","steps":["trace[2039984244] 'process raft request' (duration: 305.427443ms)","trace[2039984244] 'compare' (duration: 262.192785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:31.807769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:31.239542Z","time spent":"568.156789ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:08:31.885833Z","caller":"traceutil/trace.go:171","msg":"trace[1468379960] linearizableReadLoop","detail":"{readStateIndex:8049; appliedIndex:8047; }","duration":"643.858619ms","start":"2026-08-31T07:08:31.241947Z","end":"2026-08-31T07:08:31.885806Z","steps":["trace[1468379960] 'read index received' (duration: 303.066782ms)","trace[1468379960] 'applied index is now lower than readState.Index' (duration: 340.790907ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:31.885963Z","caller":"traceutil/trace.go:171","msg":"trace[249328161] transaction","detail":"{read_only:false; response_revision:7775; number_of_response:1; }","duration":"586.697621ms","start":"2026-08-31T07:08:31.299253Z","end":"2026-08-31T07:08:31.885951Z","steps":["trace[249328161] 'process raft request' (duration: 586.427876ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:31.885965Z","caller":"traceutil/trace.go:171","msg":"trace[1925451807] transaction","detail":"{read_only:false; response_revision:7774; number_of_response:1; }","duration":"645.32561ms","start":"2026-08-31T07:08:31.240609Z","end":"2026-08-31T07:08:31.885934Z","steps":["trace[1925451807] 'process raft request' (duration: 644.926502ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:31.886011Z","caller":"traceutil/trace.go:171","msg":"trace[325597203] transaction","detail":"{read_only:false; response_revision:7776; number_of_response:1; }","duration":"539.687483ms","start":"2026-08-31T07:08:31.346304Z","end":"2026-08-31T07:08:31.885991Z","steps":["trace[325597203] 'process raft request' (duration: 539.452618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:31.886072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:31.299224Z","time spent":"586.770782ms","remote":"127.0.0.1:35738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:08:31.88609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:31.240586Z","time spent":"645.441803ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:08:31.886113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:31.346277Z","time spent":"539.782406ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:08:31.88654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.590184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:20 size:176058"} {"level":"info","ts":"2026-08-31T07:08:31.88662Z","caller":"traceutil/trace.go:171","msg":"trace[459740385] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:20; response_revision:7776; }","duration":"644.709947ms","start":"2026-08-31T07:08:31.241893Z","end":"2026-08-31T07:08:31.886603Z","steps":["trace[459740385] 'agreement among raft nodes before linearized reading' (duration: 644.301509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:31.886863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:31.241881Z","time spent":"644.972414ms","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":20,"response size":176082,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-31T07:08:32.022922Z","caller":"traceutil/trace.go:171","msg":"trace[546479224] linearizableReadLoop","detail":"{readStateIndex:8052; appliedIndex:8051; }","duration":"136.973235ms","start":"2026-08-31T07:08:31.885916Z","end":"2026-08-31T07:08:32.02289Z","steps":["trace[546479224] 'read index received' (duration: 77.178451ms)","trace[546479224] 'applied index is now lower than readState.Index' (duration: 59.793624ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:32.022994Z","caller":"traceutil/trace.go:171","msg":"trace[1397044488] transaction","detail":"{read_only:false; response_revision:7777; number_of_response:1; }","duration":"286.572524ms","start":"2026-08-31T07:08:31.736394Z","end":"2026-08-31T07:08:32.022967Z","steps":["trace[1397044488] 'process raft request' (duration: 226.78676ms)","trace[1397044488] 'compare' (duration: 59.566649ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:32.023103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.56114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:32.023155Z","caller":"traceutil/trace.go:171","msg":"trace[1975574148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7777; }","duration":"672.670263ms","start":"2026-08-31T07:08:31.350468Z","end":"2026-08-31T07:08:32.023138Z","steps":["trace[1975574148] 'agreement among raft nodes before linearized reading' (duration: 672.56538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:32.023291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.403795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:08:32.023379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.624488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:415064"} {"level":"warn","ts":"2026-08-31T07:08:32.023368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:31.350448Z","time spent":"672.900858ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-31T07:08:32.023424Z","caller":"traceutil/trace.go:171","msg":"trace[116683286] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:7777; }","duration":"215.74281ms","start":"2026-08-31T07:08:31.80767Z","end":"2026-08-31T07:08:32.023413Z","steps":["trace[116683286] 'agreement among raft nodes before linearized reading' (duration: 215.490885ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:32.023486Z","caller":"traceutil/trace.go:171","msg":"trace[285402161] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:7777; }","duration":"477.578659ms","start":"2026-08-31T07:08:31.54582Z","end":"2026-08-31T07:08:32.023398Z","steps":["trace[285402161] 'agreement among raft nodes before linearized reading' (duration: 477.346084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:32.02356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:08:31.545805Z","time spent":"477.739202ms","remote":"127.0.0.1:35724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-08-31T07:08:32.339111Z","caller":"traceutil/trace.go:171","msg":"trace[1181209851] transaction","detail":"{read_only:false; response_revision:7778; number_of_response:1; }","duration":"105.097226ms","start":"2026-08-31T07:08:32.233993Z","end":"2026-08-31T07:08:32.33909Z","steps":["trace[1181209851] 'process raft request' (duration: 104.216447ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:32.654425Z","caller":"traceutil/trace.go:171","msg":"trace[1133988720] transaction","detail":"{read_only:false; response_revision:7781; number_of_response:1; }","duration":"166.780471ms","start":"2026-08-31T07:08:32.487618Z","end":"2026-08-31T07:08:32.654399Z","steps":["trace[1133988720] 'process raft request' (duration: 149.533037ms)","trace[1133988720] 'compare' (duration: 17.023278ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:32.701312Z","caller":"traceutil/trace.go:171","msg":"trace[1831622547] transaction","detail":"{read_only:false; response_revision:7782; number_of_response:1; }","duration":"203.27977ms","start":"2026-08-31T07:08:32.498005Z","end":"2026-08-31T07:08:32.701285Z","steps":["trace[1831622547] 'process raft request' (duration: 203.095596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:33.519598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.663822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:08:33.519788Z","caller":"traceutil/trace.go:171","msg":"trace[1925935808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7786; }","duration":"168.842516ms","start":"2026-08-31T07:08:33.350857Z","end":"2026-08-31T07:08:33.5197Z","steps":["trace[1925935808] 'range keys from in-memory index tree' (duration: 168.565619ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:34.008121Z","caller":"traceutil/trace.go:171","msg":"trace[787594386] linearizableReadLoop","detail":"{readStateIndex:8064; appliedIndex:8063; }","duration":"104.345728ms","start":"2026-08-31T07:08:33.903748Z","end":"2026-08-31T07:08:34.008094Z","steps":["trace[787594386] 'read index received' (duration: 40.581338ms)","trace[787594386] 'applied index is now lower than readState.Index' (duration: 63.76345ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:34.008247Z","caller":"traceutil/trace.go:171","msg":"trace[65422832] transaction","detail":"{read_only:false; response_revision:7789; number_of_response:1; }","duration":"116.657426ms","start":"2026-08-31T07:08:33.891556Z","end":"2026-08-31T07:08:34.008213Z","steps":["trace[65422832] 'process raft request' (duration: 52.764173ms)","trace[65422832] 'compare' (duration: 63.570836ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:34.008379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.441978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vgmdr\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-31T07:08:34.008419Z","caller":"traceutil/trace.go:171","msg":"trace[1214580759] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vgmdr; range_end:; response_count:1; response_revision:7789; }","duration":"114.54045ms","start":"2026-08-31T07:08:33.893868Z","end":"2026-08-31T07:08:34.008408Z","steps":["trace[1214580759] 'agreement among raft nodes before linearized reading' (duration: 114.349846ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:34.074642Z","caller":"traceutil/trace.go:171","msg":"trace[624221189] transaction","detail":"{read_only:false; response_revision:7790; number_of_response:1; }","duration":"164.217066ms","start":"2026-08-31T07:08:33.910389Z","end":"2026-08-31T07:08:34.074606Z","steps":["trace[624221189] 'process raft request' (duration: 164.034661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:08:34.5639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.296012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:08:34.56408Z","caller":"traceutil/trace.go:171","msg":"trace[614648253] transaction","detail":"{read_only:false; response_revision:7794; number_of_response:1; }","duration":"181.375226ms","start":"2026-08-31T07:08:34.382673Z","end":"2026-08-31T07:08:34.564048Z","steps":["trace[614648253] 'process raft request' (duration: 79.861739ms)","trace[614648253] 'compare' (duration: 101.210731ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:35.899885Z","caller":"traceutil/trace.go:171","msg":"trace[1123119673] transaction","detail":"{read_only:false; response_revision:7807; number_of_response:1; }","duration":"167.247471ms","start":"2026-08-31T07:08:35.732605Z","end":"2026-08-31T07:08:35.899852Z","steps":["trace[1123119673] 'process raft request' (duration: 99.803971ms)","trace[1123119673] 'compare' (duration: 67.170054ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:36.056929Z","caller":"traceutil/trace.go:171","msg":"trace[97996443] transaction","detail":"{read_only:false; response_revision:7809; number_of_response:1; }","duration":"152.201015ms","start":"2026-08-31T07:08:35.904703Z","end":"2026-08-31T07:08:36.056904Z","steps":["trace[97996443] 'process raft request' (duration: 142.314902ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:36.057205Z","caller":"traceutil/trace.go:171","msg":"trace[320638017] transaction","detail":"{read_only:false; response_revision:7811; number_of_response:1; }","duration":"148.897144ms","start":"2026-08-31T07:08:35.908284Z","end":"2026-08-31T07:08:36.057182Z","steps":["trace[320638017] 'process raft request' (duration: 148.782071ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:36.057205Z","caller":"traceutil/trace.go:171","msg":"trace[1556477483] transaction","detail":"{read_only:false; response_revision:7810; number_of_response:1; }","duration":"152.371528ms","start":"2026-08-31T07:08:35.904803Z","end":"2026-08-31T07:08:36.057175Z","steps":["trace[1556477483] 'process raft request' (duration: 152.01051ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:08:36.269786Z","caller":"traceutil/trace.go:171","msg":"trace[522232357] transaction","detail":"{read_only:false; response_revision:7815; number_of_response:1; }","duration":"151.159953ms","start":"2026-08-31T07:08:36.118524Z","end":"2026-08-31T07:08:36.269684Z","steps":["trace[522232357] 'process raft request' (duration: 69.643228ms)","trace[522232357] 'compare' (duration: 81.382702ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:08:36.934654Z","caller":"traceutil/trace.go:171","msg":"trace[626948987] transaction","detail":"{read_only:false; response_revision:7820; number_of_response:1; }","duration":"104.782698ms","start":"2026-08-31T07:08:36.82984Z","end":"2026-08-31T07:08:36.934622Z","steps":["trace[626948987] 'process raft request' (duration: 74.81989ms)","trace[626948987] 'compare' (duration: 29.817185ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:08:38.976157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.16511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:08:38.976291Z","caller":"traceutil/trace.go:171","msg":"trace[1402313910] transaction","detail":"{read_only:false; response_revision:7834; number_of_response:1; }","duration":"153.38943ms","start":"2026-08-31T07:08:38.822875Z","end":"2026-08-31T07:08:38.976265Z","steps":["trace[1402313910] 'process raft request' (duration: 15.990835ms)","trace[1402313910] 'compare' (duration: 136.905744ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:09:11.603232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5920} {"level":"info","ts":"2026-08-31T07:09:11.694648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5920,"took":"89.539667ms","hash":2714076187,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":25894912,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T07:09:11.695014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2714076187,"revision":5920,"compact-revision":3009} {"level":"warn","ts":"2026-08-31T07:10:22.120511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.451346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:10:22.120743Z","caller":"traceutil/trace.go:171","msg":"trace[1305407618] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"197.126672ms","start":"2026-08-31T07:10:21.923589Z","end":"2026-08-31T07:10:22.120715Z","steps":["trace[1305407618] 'process raft request' (duration: 60.322939ms)","trace[1305407618] 'compare' (duration: 136.154878ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:10:30.27764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.405252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:10:30.277904Z","caller":"traceutil/trace.go:171","msg":"trace[1385883143] linearizableReadLoop","detail":"{readStateIndex:9007; appliedIndex:9005; }","duration":"111.340784ms","start":"2026-08-31T07:10:30.16654Z","end":"2026-08-31T07:10:30.277881Z","steps":["trace[1385883143] 'read index received' (duration: 96.468775ms)","trace[1385883143] 'applied index is now lower than readState.Index' (duration: 14.870189ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:10:30.278109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.57178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-31T07:10:30.278142Z","caller":"traceutil/trace.go:171","msg":"trace[1668564288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8703; }","duration":"111.642863ms","start":"2026-08-31T07:10:30.166489Z","end":"2026-08-31T07:10:30.278132Z","steps":["trace[1668564288] 'agreement among raft nodes before linearized reading' (duration: 111.469788ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:10:30.278187Z","caller":"traceutil/trace.go:171","msg":"trace[994330890] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"146.133305ms","start":"2026-08-31T07:10:30.132015Z","end":"2026-08-31T07:10:30.278148Z","steps":["trace[994330890] 'process raft request' (duration: 145.783517ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:10:30.278225Z","caller":"traceutil/trace.go:171","msg":"trace[1989203868] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"407.98876ms","start":"2026-08-31T07:10:29.870185Z","end":"2026-08-31T07:10:30.278174Z","steps":["trace[1989203868] 'process raft request' (duration: 226.971644ms)","trace[1989203868] 'compare' (duration: 180.165847ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:10:30.278422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:10:29.870167Z","time spent":"408.148463ms","remote":"127.0.0.1:35768","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-08-31T07:10:32.329853Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000233d.wal"} {"level":"info","ts":"2026-08-31T07:10:42.872345Z","caller":"traceutil/trace.go:171","msg":"trace[1584600729] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"226.943354ms","start":"2026-08-31T07:10:42.645379Z","end":"2026-08-31T07:10:42.872322Z","steps":["trace[1584600729] 'process raft request' (duration: 226.799941ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:10:42.872754Z","caller":"traceutil/trace.go:171","msg":"trace[597019907] linearizableReadLoop","detail":"{readStateIndex:9074; appliedIndex:9074; }","duration":"110.215691ms","start":"2026-08-31T07:10:42.762507Z","end":"2026-08-31T07:10:42.872723Z","steps":["trace[597019907] 'read index received' (duration: 109.595058ms)","trace[597019907] 'applied index is now lower than readState.Index' (duration: 618.203µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:10:42.873009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.493768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-08-31T07:10:42.873077Z","caller":"traceutil/trace.go:171","msg":"trace[1838562147] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8767; }","duration":"110.604329ms","start":"2026-08-31T07:10:42.762454Z","end":"2026-08-31T07:10:42.873059Z","steps":["trace[1838562147] 'agreement among raft nodes before linearized reading' (duration: 110.398975ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:14.882852Z","caller":"traceutil/trace.go:171","msg":"trace[577965424] linearizableReadLoop","detail":"{readStateIndex:9308; appliedIndex:9307; }","duration":"264.291164ms","start":"2026-08-31T07:11:14.618537Z","end":"2026-08-31T07:11:14.882828Z","steps":["trace[577965424] 'read index received' (duration: 264.016568ms)","trace[577965424] 'applied index is now lower than readState.Index' (duration: 273.476µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:14.882939Z","caller":"traceutil/trace.go:171","msg":"trace[977920094] transaction","detail":"{read_only:false; response_revision:8992; number_of_response:1; }","duration":"365.667567ms","start":"2026-08-31T07:11:14.517231Z","end":"2026-08-31T07:11:14.882899Z","steps":["trace[977920094] 'process raft request' (duration: 365.392601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:14.883052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.493469ms","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-08-31T07:11:14.883094Z","caller":"traceutil/trace.go:171","msg":"trace[221345874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8992; }","duration":"264.585441ms","start":"2026-08-31T07:11:14.618495Z","end":"2026-08-31T07:11:14.883081Z","steps":["trace[221345874] 'agreement among raft nodes before linearized reading' (duration: 264.429927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:14.88312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:11:14.517201Z","time spent":"365.83108ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:11:27.953761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.291958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T07:11:27.953875Z","caller":"traceutil/trace.go:171","msg":"trace[108272855] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9072; }","duration":"191.739366ms","start":"2026-08-31T07:11:27.762119Z","end":"2026-08-31T07:11:27.953858Z","steps":["trace[108272855] 'process raft request' (duration: 50.800814ms)","trace[108272855] 'compare' (duration: 140.164305ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:27.959908Z","caller":"traceutil/trace.go:171","msg":"trace[1380065134] transaction","detail":"{read_only:false; response_revision:9073; number_of_response:1; }","duration":"195.655929ms","start":"2026-08-31T07:11:27.764238Z","end":"2026-08-31T07:11:27.959894Z","steps":["trace[1380065134] 'process raft request' (duration: 195.558707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:27.96051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.273011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-46jd6\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-31T07:11:27.961233Z","caller":"traceutil/trace.go:171","msg":"trace[1550073117] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-46jd6; range_end:; response_count:1; response_revision:9073; }","duration":"129.367753ms","start":"2026-08-31T07:11:27.831176Z","end":"2026-08-31T07:11:27.960544Z","steps":["trace[1550073117] 'agreement among raft nodes before linearized reading' (duration: 129.276001ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:27.960386Z","caller":"traceutil/trace.go:171","msg":"trace[2044270087] linearizableReadLoop","detail":"{readStateIndex:9392; appliedIndex:9392; }","duration":"129.110478ms","start":"2026-08-31T07:11:27.831227Z","end":"2026-08-31T07:11:27.960338Z","steps":["trace[2044270087] 'read index received' (duration: 129.101847ms)","trace[2044270087] 'applied index is now lower than readState.Index' (duration: 6.701µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:27.971174Z","caller":"traceutil/trace.go:171","msg":"trace[1852634405] transaction","detail":"{read_only:false; response_revision:9074; number_of_response:1; }","duration":"139.319757ms","start":"2026-08-31T07:11:27.831829Z","end":"2026-08-31T07:11:27.971149Z","steps":["trace[1852634405] 'process raft request' (duration: 139.177384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:27.972436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.470185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-31T07:11:27.972549Z","caller":"traceutil/trace.go:171","msg":"trace[181577344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9074; }","duration":"122.592118ms","start":"2026-08-31T07:11:27.849893Z","end":"2026-08-31T07:11:27.972485Z","steps":["trace[181577344] 'agreement among raft nodes before linearized reading' (duration: 121.3429ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:28.113271Z","caller":"traceutil/trace.go:171","msg":"trace[164588721] linearizableReadLoop","detail":"{readStateIndex:9394; appliedIndex:9393; }","duration":"142.118677ms","start":"2026-08-31T07:11:27.971126Z","end":"2026-08-31T07:11:28.113245Z","steps":["trace[164588721] 'read index received' (duration: 135.461143ms)","trace[164588721] 'applied index is now lower than readState.Index' (duration: 6.656294ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:28.113351Z","caller":"traceutil/trace.go:171","msg":"trace[1445131153] transaction","detail":"{read_only:false; response_revision:9075; number_of_response:1; }","duration":"143.043387ms","start":"2026-08-31T07:11:27.97029Z","end":"2026-08-31T07:11:28.113334Z","steps":["trace[1445131153] 'process raft request' (duration: 136.284651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:28.113416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.184487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-46jd6\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-08-31T07:11:28.113479Z","caller":"traceutil/trace.go:171","msg":"trace[989051896] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-46jd6; range_end:; response_count:1; response_revision:9075; }","duration":"148.30055ms","start":"2026-08-31T07:11:27.965163Z","end":"2026-08-31T07:11:28.113464Z","steps":["trace[989051896] 'agreement among raft nodes before linearized reading' (duration: 148.189868ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:28.290595Z","caller":"traceutil/trace.go:171","msg":"trace[478555084] transaction","detail":"{read_only:false; response_revision:9076; number_of_response:1; }","duration":"315.292321ms","start":"2026-08-31T07:11:27.975278Z","end":"2026-08-31T07:11:28.290571Z","steps":["trace[478555084] 'process raft request' (duration: 288.826073ms)","trace[478555084] 'compare' (duration: 25.988708ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:28.290604Z","caller":"traceutil/trace.go:171","msg":"trace[1742679509] linearizableReadLoop","detail":"{readStateIndex:9397; appliedIndex:9394; }","duration":"173.637736ms","start":"2026-08-31T07:11:28.116943Z","end":"2026-08-31T07:11:28.290581Z","steps":["trace[1742679509] 'read index received' (duration: 147.165217ms)","trace[1742679509] 'applied index is now lower than readState.Index' (duration: 26.471149ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:11:28.290647Z","caller":"traceutil/trace.go:171","msg":"trace[1799867352] transaction","detail":"{read_only:false; response_revision:9077; number_of_response:1; }","duration":"314.541686ms","start":"2026-08-31T07:11:27.976066Z","end":"2026-08-31T07:11:28.290608Z","steps":["trace[1799867352] 'process raft request' (duration: 314.391253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:28.290735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:11:27.975263Z","time spent":"315.405434ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:11:28.290766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:11:27.976049Z","time spent":"314.651298ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:11:28.290772Z","caller":"traceutil/trace.go:171","msg":"trace[1899939788] transaction","detail":"{read_only:false; response_revision:9078; number_of_response:1; }","duration":"198.229794ms","start":"2026-08-31T07:11:28.09253Z","end":"2026-08-31T07:11:28.290759Z","steps":["trace[1899939788] 'process raft request' (duration: 198.01052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:28.291103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.157217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-46jd6\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-08-31T07:11:28.291134Z","caller":"traceutil/trace.go:171","msg":"trace[438771042] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-46jd6; range_end:; response_count:1; response_revision:9078; }","duration":"174.243358ms","start":"2026-08-31T07:11:28.116883Z","end":"2026-08-31T07:11:28.291126Z","steps":["trace[438771042] 'agreement among raft nodes before linearized reading' (duration: 174.145416ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:11:28.299988Z","caller":"traceutil/trace.go:171","msg":"trace[2134714726] transaction","detail":"{read_only:false; response_revision:9079; number_of_response:1; }","duration":"161.444113ms","start":"2026-08-31T07:11:28.138495Z","end":"2026-08-31T07:11:28.299939Z","steps":["trace[2134714726] 'process raft request' (duration: 161.269589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:11:28.30014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.008975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-08-31T07:11:28.300219Z","caller":"traceutil/trace.go:171","msg":"trace[737611063] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:9079; }","duration":"175.113927ms","start":"2026-08-31T07:11:28.125086Z","end":"2026-08-31T07:11:28.3002Z","steps":["trace[737611063] 'agreement among raft nodes before linearized reading' (duration: 174.81254ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:26.19558Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:12:26.207203Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-08-31T07:12:26.207301Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-08-31T07:12:56.971654Z","caller":"traceutil/trace.go:171","msg":"trace[1298056901] linearizableReadLoop","detail":"{readStateIndex:10261; appliedIndex:10260; }","duration":"153.576363ms","start":"2026-08-31T07:12:56.81805Z","end":"2026-08-31T07:12:56.971626Z","steps":["trace[1298056901] 'read index received' (duration: 153.331908ms)","trace[1298056901] 'applied index is now lower than readState.Index' (duration: 243.525µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:56.971793Z","caller":"traceutil/trace.go:171","msg":"trace[26291878] transaction","detail":"{read_only:false; response_revision:9917; number_of_response:1; }","duration":"273.232167ms","start":"2026-08-31T07:12:56.698545Z","end":"2026-08-31T07:12:56.971777Z","steps":["trace[26291878] 'process raft request' (duration: 272.912891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:56.971988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.88034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-31T07:12:56.972283Z","caller":"traceutil/trace.go:171","msg":"trace[200714645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9917; }","duration":"154.241417ms","start":"2026-08-31T07:12:56.818014Z","end":"2026-08-31T07:12:56.972255Z","steps":["trace[200714645] 'agreement among raft nodes before linearized reading' (duration: 153.819999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:57.574809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.43614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:12:57.575038Z","caller":"traceutil/trace.go:171","msg":"trace[977004690] transaction","detail":"{read_only:false; response_revision:9918; number_of_response:1; }","duration":"598.448463ms","start":"2026-08-31T07:12:56.976564Z","end":"2026-08-31T07:12:57.575013Z","steps":["trace[977004690] 'process raft request' (duration: 101.703747ms)","trace[977004690] 'compare' (duration: 496.203055ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:12:57.575136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:56.976528Z","time spent":"598.563256ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:12:57.575268Z","caller":"traceutil/trace.go:171","msg":"trace[2113135118] linearizableReadLoop","detail":"{readStateIndex:10263; appliedIndex:10261; }","duration":"574.673902ms","start":"2026-08-31T07:12:57.00056Z","end":"2026-08-31T07:12:57.575234Z","steps":["trace[2113135118] 'read index received' (duration: 77.815774ms)","trace[2113135118] 'applied index is now lower than readState.Index' (duration: 496.856948ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:57.575348Z","caller":"traceutil/trace.go:171","msg":"trace[704936263] transaction","detail":"{read_only:false; response_revision:9919; number_of_response:1; }","duration":"597.37823ms","start":"2026-08-31T07:12:56.977925Z","end":"2026-08-31T07:12:57.575304Z","steps":["trace[704936263] 'process raft request' (duration: 597.009293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:57.575432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.834045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:12:57.575513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:56.97791Z","time spent":"597.504383ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:12:57.575556Z","caller":"traceutil/trace.go:171","msg":"trace[1495916887] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:9919; }","duration":"575.002079ms","start":"2026-08-31T07:12:57.000524Z","end":"2026-08-31T07:12:57.575526Z","steps":["trace[1495916887] 'agreement among raft nodes before linearized reading' (duration: 574.802905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:57.575648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:57.00048Z","time spent":"575.149882ms","remote":"127.0.0.1:41336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"info","ts":"2026-08-31T07:12:57.604998Z","caller":"traceutil/trace.go:171","msg":"trace[560526146] transaction","detail":"{read_only:false; response_revision:9921; number_of_response:1; }","duration":"120.343319ms","start":"2026-08-31T07:12:57.484622Z","end":"2026-08-31T07:12:57.604965Z","steps":["trace[560526146] 'process raft request' (duration: 120.288897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:57.605105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.351301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:12:57.605263Z","caller":"traceutil/trace.go:171","msg":"trace[33295565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9921; }","duration":"254.553406ms","start":"2026-08-31T07:12:57.350685Z","end":"2026-08-31T07:12:57.605238Z","steps":["trace[33295565] 'agreement among raft nodes before linearized reading' (duration: 254.28338ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:57.605414Z","caller":"traceutil/trace.go:171","msg":"trace[1314227504] transaction","detail":"{read_only:false; response_revision:9920; number_of_response:1; }","duration":"235.519386ms","start":"2026-08-31T07:12:57.36988Z","end":"2026-08-31T07:12:57.605399Z","steps":["trace[1314227504] 'process raft request' (duration: 234.928812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.009844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.567922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:12:58.009943Z","caller":"traceutil/trace.go:171","msg":"trace[2067776001] linearizableReadLoop","detail":"{readStateIndex:10266; appliedIndex:10265; }","duration":"196.893966ms","start":"2026-08-31T07:12:57.813032Z","end":"2026-08-31T07:12:58.009926Z","steps":["trace[2067776001] 'read index received' (duration: 11.075089ms)","trace[2067776001] 'applied index is now lower than readState.Index' (duration: 185.817577ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:58.010016Z","caller":"traceutil/trace.go:171","msg":"trace[95645296] transaction","detail":"{read_only:false; response_revision:9922; number_of_response:1; }","duration":"400.24195ms","start":"2026-08-31T07:12:57.609761Z","end":"2026-08-31T07:12:58.010003Z","steps":["trace[95645296] 'process raft request' (duration: 214.416483ms)","trace[95645296] 'compare' (duration: 185.131513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:12:58.010154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.11954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-31T07:12:58.010164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:57.609745Z","time spent":"400.299601ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:12:58.010233Z","caller":"traceutil/trace.go:171","msg":"trace[1853541504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9922; }","duration":"197.241613ms","start":"2026-08-31T07:12:57.812973Z","end":"2026-08-31T07:12:58.010214Z","steps":["trace[1853541504] 'agreement among raft nodes before linearized reading' (duration: 197.055989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.444111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.134663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:12:58.444235Z","caller":"traceutil/trace.go:171","msg":"trace[1165498239] linearizableReadLoop","detail":"{readStateIndex:10267; appliedIndex:10266; }","duration":"434.241922ms","start":"2026-08-31T07:12:58.009968Z","end":"2026-08-31T07:12:58.44421Z","steps":["trace[1165498239] 'read index received' (duration: 248.923875ms)","trace[1165498239] 'applied index is now lower than readState.Index' (duration: 185.315957ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:58.444268Z","caller":"traceutil/trace.go:171","msg":"trace[1548198376] transaction","detail":"{read_only:false; response_revision:9923; number_of_response:1; }","duration":"585.608397ms","start":"2026-08-31T07:12:57.85864Z","end":"2026-08-31T07:12:58.444248Z","steps":["trace[1548198376] 'process raft request' (duration: 400.25313ms)","trace[1548198376] 'compare' (duration: 184.743885ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:12:58.444359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:57.858624Z","time spent":"585.689859ms","remote":"127.0.0.1:35768","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-08-31T07:12:58.444433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.336588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vgmdr\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-31T07:12:58.444486Z","caller":"traceutil/trace.go:171","msg":"trace[648667512] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vgmdr; range_end:; response_count:1; response_revision:9923; }","duration":"563.41708ms","start":"2026-08-31T07:12:57.881054Z","end":"2026-08-31T07:12:58.444471Z","steps":["trace[648667512] 'agreement among raft nodes before linearized reading' (duration: 563.245427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.444526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:57.88104Z","time spent":"563.475291ms","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6475,"request content":"key:\"/registry/pods/openstack/keepalived-vgmdr\" "} {"level":"warn","ts":"2026-08-31T07:12:58.444703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.11895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-31T07:12:58.444986Z","caller":"traceutil/trace.go:171","msg":"trace[1989956539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9923; }","duration":"574.456337ms","start":"2026-08-31T07:12:57.870502Z","end":"2026-08-31T07:12:58.444958Z","steps":["trace[1989956539] 'agreement among raft nodes before linearized reading' (duration: 573.924376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.445055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:57.870479Z","time spent":"574.559739ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-31T07:12:58.523347Z","caller":"traceutil/trace.go:171","msg":"trace[1323873604] transaction","detail":"{read_only:false; response_revision:9924; number_of_response:1; }","duration":"508.550329ms","start":"2026-08-31T07:12:58.014764Z","end":"2026-08-31T07:12:58.523314Z","steps":["trace[1323873604] 'process raft request' (duration: 508.335134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.523497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.817062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-31T07:12:58.523544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.723004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-7786db9cbb-66p48.18d0d25c6a86427a\" ","response":"range_response_count:1 size:787"} {"level":"info","ts":"2026-08-31T07:12:58.52358Z","caller":"traceutil/trace.go:171","msg":"trace[2104445185] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:9924; }","duration":"129.975985ms","start":"2026-08-31T07:12:58.393585Z","end":"2026-08-31T07:12:58.523561Z","steps":["trace[2104445185] 'agreement among raft nodes before linearized reading' (duration: 129.834972ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:58.523648Z","caller":"traceutil/trace.go:171","msg":"trace[1630828136] range","detail":"{range_begin:/registry/events/openstack/glance-api-7786db9cbb-66p48.18d0d25c6a86427a; range_end:; response_count:1; response_revision:9924; }","duration":"430.818657ms","start":"2026-08-31T07:12:58.092766Z","end":"2026-08-31T07:12:58.523584Z","steps":["trace[1630828136] 'agreement among raft nodes before linearized reading' (duration: 430.618502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.523436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.517301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:12:58.523703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:58.092744Z","time spent":"430.94522ms","remote":"127.0.0.1:35600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":811,"request content":"key:\"/registry/events/openstack/glance-api-7786db9cbb-66p48.18d0d25c6a86427a\" "} {"level":"info","ts":"2026-08-31T07:12:58.523729Z","caller":"traceutil/trace.go:171","msg":"trace[1411968627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9924; }","duration":"172.857968ms","start":"2026-08-31T07:12:58.350846Z","end":"2026-08-31T07:12:58.523704Z","steps":["trace[1411968627] 'agreement among raft nodes before linearized reading' (duration: 172.48104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.523863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:58.014741Z","time spent":"508.811114ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:12:58.80432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.067013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:12:58.804529Z","caller":"traceutil/trace.go:171","msg":"trace[1342576002] transaction","detail":"{read_only:false; response_revision:9926; number_of_response:1; }","duration":"278.377829ms","start":"2026-08-31T07:12:58.526133Z","end":"2026-08-31T07:12:58.804511Z","steps":["trace[1342576002] 'process raft request' (duration: 278.294218ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:58.804579Z","caller":"traceutil/trace.go:171","msg":"trace[372434780] linearizableReadLoop","detail":"{readStateIndex:10269; appliedIndex:10268; }","duration":"281.296411ms","start":"2026-08-31T07:12:58.523255Z","end":"2026-08-31T07:12:58.804551Z","steps":["trace[372434780] 'read index received' (duration: 180.920322ms)","trace[372434780] 'applied index is now lower than readState.Index' (duration: 100.373369ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:12:58.804938Z","caller":"traceutil/trace.go:171","msg":"trace[1153779500] transaction","detail":"{read_only:false; response_revision:9925; number_of_response:1; }","duration":"355.411726ms","start":"2026-08-31T07:12:58.449492Z","end":"2026-08-31T07:12:58.804904Z","steps":["trace[1153779500] 'process raft request' (duration: 254.668478ms)","trace[1153779500] 'compare' (duration: 99.823128ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:12:58.8051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:58.449475Z","time spent":"355.532758ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:12:58.805114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.826934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:289845"} {"level":"info","ts":"2026-08-31T07:12:58.80517Z","caller":"traceutil/trace.go:171","msg":"trace[1897453250] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9926; }","duration":"355.888126ms","start":"2026-08-31T07:12:58.449265Z","end":"2026-08-31T07:12:58.805153Z","steps":["trace[1897453250] 'agreement among raft nodes before linearized reading' (duration: 355.411266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:58.805224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:58.449246Z","time spent":"355.963807ms","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":31,"response size":289869,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-31T07:12:59.099586Z","caller":"traceutil/trace.go:171","msg":"trace[1782155724] transaction","detail":"{read_only:false; response_revision:9927; number_of_response:1; }","duration":"120.065433ms","start":"2026-08-31T07:12:58.979499Z","end":"2026-08-31T07:12:59.099564Z","steps":["trace[1782155724] 'process raft request' (duration: 119.93313ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:59.77751Z","caller":"traceutil/trace.go:171","msg":"trace[55840707] transaction","detail":"{read_only:false; response_revision:9928; number_of_response:1; }","duration":"189.596228ms","start":"2026-08-31T07:12:59.587873Z","end":"2026-08-31T07:12:59.777469Z","steps":["trace[55840707] 'process raft request' (duration: 189.390874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:59.979795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.749095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:12:59.980038Z","caller":"traceutil/trace.go:171","msg":"trace[616144032] transaction","detail":"{read_only:false; response_revision:9929; number_of_response:1; }","duration":"391.961432ms","start":"2026-08-31T07:12:59.588041Z","end":"2026-08-31T07:12:59.980003Z","steps":["trace[616144032] 'process raft request' (duration: 258.89498ms)","trace[616144032] 'compare' (duration: 132.399938ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:12:59.980151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:59.588009Z","time spent":"392.097104ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:12:59.980178Z","caller":"traceutil/trace.go:171","msg":"trace[1433632105] transaction","detail":"{read_only:false; response_revision:9931; number_of_response:1; }","duration":"369.411786ms","start":"2026-08-31T07:12:59.610746Z","end":"2026-08-31T07:12:59.980158Z","steps":["trace[1433632105] 'process raft request' (duration: 369.338655ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:12:59.980233Z","caller":"traceutil/trace.go:171","msg":"trace[1449664503] transaction","detail":"{read_only:false; response_revision:9930; number_of_response:1; }","duration":"369.474457ms","start":"2026-08-31T07:12:59.610734Z","end":"2026-08-31T07:12:59.980209Z","steps":["trace[1449664503] 'process raft request' (duration: 369.200712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:12:59.980275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:59.610721Z","time spent":"369.500028ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:12:59.980376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:12:59.610709Z","time spent":"369.59743ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:13:00.254045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.651061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:13:00.254137Z","caller":"traceutil/trace.go:171","msg":"trace[126804515] linearizableReadLoop","detail":"{readStateIndex:10276; appliedIndex:10275; }","duration":"101.917942ms","start":"2026-08-31T07:13:00.152202Z","end":"2026-08-31T07:13:00.25412Z","steps":["trace[126804515] 'read index received' (duration: 51.971µs)","trace[126804515] 'applied index is now lower than readState.Index' (duration: 101.864691ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:13:00.254232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.031775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:13:00.254263Z","caller":"traceutil/trace.go:171","msg":"trace[499949988] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:9932; }","duration":"102.104756ms","start":"2026-08-31T07:13:00.152145Z","end":"2026-08-31T07:13:00.25425Z","steps":["trace[499949988] 'agreement among raft nodes before linearized reading' (duration: 102.018215ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:00.254237Z","caller":"traceutil/trace.go:171","msg":"trace[1376648773] transaction","detail":"{read_only:false; response_revision:9932; number_of_response:1; }","duration":"234.880732ms","start":"2026-08-31T07:13:00.019321Z","end":"2026-08-31T07:13:00.254202Z","steps":["trace[1376648773] 'process raft request' (duration: 103.992407ms)","trace[1376648773] 'compare' (duration: 130.547198ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:13:00.616927Z","caller":"traceutil/trace.go:171","msg":"trace[844363759] transaction","detail":"{read_only:false; response_revision:9933; number_of_response:1; }","duration":"422.829336ms","start":"2026-08-31T07:13:00.194076Z","end":"2026-08-31T07:13:00.616905Z","steps":["trace[844363759] 'process raft request' (duration: 422.680073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:00.617126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:13:00.194046Z","time spent":"422.946578ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:13:00.63453Z","caller":"traceutil/trace.go:171","msg":"trace[1201009431] linearizableReadLoop","detail":"{readStateIndex:10278; appliedIndex:10276; }","duration":"283.1224ms","start":"2026-08-31T07:13:00.351385Z","end":"2026-08-31T07:13:00.634508Z","steps":["trace[1201009431] 'read index received' (duration: 265.41385ms)","trace[1201009431] 'applied index is now lower than readState.Index' (duration: 17.70747ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:13:00.634581Z","caller":"traceutil/trace.go:171","msg":"trace[179980757] transaction","detail":"{read_only:false; response_revision:9934; number_of_response:1; }","duration":"184.658862ms","start":"2026-08-31T07:13:00.449891Z","end":"2026-08-31T07:13:00.63455Z","steps":["trace[179980757] 'process raft request' (duration: 184.390307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:00.634709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.302824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:13:00.634685Z","caller":"traceutil/trace.go:171","msg":"trace[1998314193] transaction","detail":"{read_only:false; response_revision:9935; number_of_response:1; }","duration":"143.144599ms","start":"2026-08-31T07:13:00.491502Z","end":"2026-08-31T07:13:00.634647Z","steps":["trace[1998314193] 'process raft request' (duration: 142.956305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:00.634849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.795915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:13:00.634892Z","caller":"traceutil/trace.go:171","msg":"trace[970503510] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9935; }","duration":"204.857106ms","start":"2026-08-31T07:13:00.430022Z","end":"2026-08-31T07:13:00.634879Z","steps":["trace[970503510] 'agreement among raft nodes before linearized reading' (duration: 204.759614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:13:00.634889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.005661ms","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-08-31T07:13:00.634943Z","caller":"traceutil/trace.go:171","msg":"trace[1356772872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9935; }","duration":"106.126593ms","start":"2026-08-31T07:13:00.528803Z","end":"2026-08-31T07:13:00.63493Z","steps":["trace[1356772872] 'agreement among raft nodes before linearized reading' (duration: 105.904419ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:00.634841Z","caller":"traceutil/trace.go:171","msg":"trace[2123607784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9935; }","duration":"283.479918ms","start":"2026-08-31T07:13:00.351334Z","end":"2026-08-31T07:13:00.634814Z","steps":["trace[2123607784] 'agreement among raft nodes before linearized reading' (duration: 283.314754ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:01.972225Z","caller":"traceutil/trace.go:171","msg":"trace[832211051] transaction","detail":"{read_only:false; response_revision:9941; number_of_response:1; }","duration":"160.428121ms","start":"2026-08-31T07:13:01.81177Z","end":"2026-08-31T07:13:01.972198Z","steps":["trace[832211051] 'process raft request' (duration: 86.665275ms)","trace[832211051] 'compare' (duration: 73.653694ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:13:19.352812Z","caller":"traceutil/trace.go:171","msg":"trace[530524519] transaction","detail":"{read_only:false; response_revision:10087; number_of_response:1; }","duration":"135.113146ms","start":"2026-08-31T07:13:19.217659Z","end":"2026-08-31T07:13:19.352772Z","steps":["trace[530524519] 'process raft request' (duration: 134.83351ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:13:49.499193Z","caller":"traceutil/trace.go:171","msg":"trace[1753120040] transaction","detail":"{read_only:false; response_revision:10278; number_of_response:1; }","duration":"106.098012ms","start":"2026-08-31T07:13:49.393067Z","end":"2026-08-31T07:13:49.499165Z","steps":["trace[1753120040] 'process raft request' (duration: 105.896288ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:14:11.609728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8060} {"level":"info","ts":"2026-08-31T07:14:11.680454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8060,"took":"68.728478ms","hash":52737410,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":27799552,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T07:14:11.680535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":52737410,"revision":8060,"compact-revision":5920} {"level":"info","ts":"2026-08-31T07:18:32.705759Z","caller":"traceutil/trace.go:171","msg":"trace[245969089] linearizableReadLoop","detail":"{readStateIndex:12746; appliedIndex:12745; }","duration":"379.426515ms","start":"2026-08-31T07:18:32.326304Z","end":"2026-08-31T07:18:32.705731Z","steps":["trace[245969089] 'read index received' (duration: 378.481275ms)","trace[245969089] 'applied index is now lower than readState.Index' (duration: 943.72µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:18:32.705839Z","caller":"traceutil/trace.go:171","msg":"trace[536958900] transaction","detail":"{read_only:false; response_revision:12324; number_of_response:1; }","duration":"429.369128ms","start":"2026-08-31T07:18:32.276433Z","end":"2026-08-31T07:18:32.705802Z","steps":["trace[536958900] 'process raft request' (duration: 428.55504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:32.705988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.752152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:18:32.706031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.276396Z","time spent":"429.534241ms","remote":"127.0.0.1:48814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:18:32.706073Z","caller":"traceutil/trace.go:171","msg":"trace[689340379] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12324; }","duration":"379.898155ms","start":"2026-08-31T07:18:32.326163Z","end":"2026-08-31T07:18:32.706061Z","steps":["trace[689340379] 'agreement among raft nodes before linearized reading' (duration: 379.700831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:32.706146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.326133Z","time spent":"380.000978ms","remote":"127.0.0.1:35738","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":"warn","ts":"2026-08-31T07:18:32.717462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.923723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:18:32.717928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.950496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T07:18:32.717979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.96247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-08-31T07:18:32.71796Z","caller":"traceutil/trace.go:171","msg":"trace[236745032] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12325; }","duration":"391.096666ms","start":"2026-08-31T07:18:32.326444Z","end":"2026-08-31T07:18:32.717541Z","steps":["trace[236745032] 'agreement among raft nodes before linearized reading' (duration: 390.945033ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:32.718005Z","caller":"traceutil/trace.go:171","msg":"trace[1015591731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12325; }","duration":"367.062429ms","start":"2026-08-31T07:18:32.350923Z","end":"2026-08-31T07:18:32.717985Z","steps":["trace[1015591731] 'agreement among raft nodes before linearized reading' (duration: 366.928446ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:32.718028Z","caller":"traceutil/trace.go:171","msg":"trace[669400622] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12325; }","duration":"389.044332ms","start":"2026-08-31T07:18:32.328964Z","end":"2026-08-31T07:18:32.718008Z","steps":["trace[669400622] 'agreement among raft nodes before linearized reading' (duration: 388.913669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:32.718063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.350907Z","time spent":"367.148111ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-31T07:18:32.718067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.326423Z","time spent":"391.613906ms","remote":"127.0.0.1:35738","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-08-31T07:18:32.718139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.129545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-31T07:18:32.718174Z","caller":"traceutil/trace.go:171","msg":"trace[1570619412] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12325; }","duration":"391.178947ms","start":"2026-08-31T07:18:32.326987Z","end":"2026-08-31T07:18:32.718166Z","steps":["trace[1570619412] 'agreement among raft nodes before linearized reading' (duration: 391.096435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:32.718197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.326978Z","time spent":"391.213327ms","remote":"127.0.0.1:35688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/cinder-api\" "} {"level":"warn","ts":"2026-08-31T07:18:32.718192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.545834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:18:32.718234Z","caller":"traceutil/trace.go:171","msg":"trace[1715940345] transaction","detail":"{read_only:false; response_revision:12325; number_of_response:1; }","duration":"346.801923ms","start":"2026-08-31T07:18:32.371393Z","end":"2026-08-31T07:18:32.718195Z","steps":["trace[1715940345] 'process raft request' (duration: 345.838482ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:18:32.718278Z","caller":"traceutil/trace.go:171","msg":"trace[1600699803] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12325; }","duration":"391.679407ms","start":"2026-08-31T07:18:32.326572Z","end":"2026-08-31T07:18:32.718252Z","steps":["trace[1600699803] 'agreement among raft nodes before linearized reading' (duration: 391.555814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:32.718324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.326537Z","time spent":"391.7792ms","remote":"127.0.0.1:35738","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":"warn","ts":"2026-08-31T07:18:32.718398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.833501ms","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-08-31T07:18:32.718396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.371367Z","time spent":"346.949767ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:18:32.718421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.743994ms","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-08-31T07:18:32.718435Z","caller":"traceutil/trace.go:171","msg":"trace[81386316] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12325; }","duration":"391.891392ms","start":"2026-08-31T07:18:32.326535Z","end":"2026-08-31T07:18:32.718427Z","steps":["trace[81386316] 'agreement among raft nodes before linearized reading' (duration: 391.839761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:32.718494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.326523Z","time spent":"391.942513ms","remote":"127.0.0.1:35738","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":"info","ts":"2026-08-31T07:18:32.718499Z","caller":"traceutil/trace.go:171","msg":"trace[328541471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12325; }","duration":"106.836456ms","start":"2026-08-31T07:18:32.611623Z","end":"2026-08-31T07:18:32.718459Z","steps":["trace[328541471] 'agreement among raft nodes before linearized reading' (duration: 106.670973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:18:32.718066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:18:32.328943Z","time spent":"389.115383ms","remote":"127.0.0.1:35738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5028,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"info","ts":"2026-08-31T07:19:11.616634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10416} {"level":"info","ts":"2026-08-31T07:19:11.713642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10416,"took":"94.754676ms","hash":2761008065,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":25968640,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T07:19:11.713734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2761008065,"revision":10416,"compact-revision":8060} {"level":"info","ts":"2026-08-31T07:24:11.628251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12603} {"level":"info","ts":"2026-08-31T07:24:11.710372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12603,"took":"79.078607ms","hash":2785052567,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":27779072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T07:24:11.710464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2785052567,"revision":12603,"compact-revision":10416} {"level":"info","ts":"2026-08-31T07:25:37.140155Z","caller":"traceutil/trace.go:171","msg":"trace[425128138] transaction","detail":"{read_only:false; response_revision:15102; number_of_response:1; }","duration":"130.258748ms","start":"2026-08-31T07:25:37.00986Z","end":"2026-08-31T07:25:37.140119Z","steps":["trace[425128138] 'process raft request' (duration: 124.471324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:26:02.605174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.351383ms","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-08-31T07:26:02.605839Z","caller":"traceutil/trace.go:171","msg":"trace[1378326276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15229; }","duration":"193.027777ms","start":"2026-08-31T07:26:02.41279Z","end":"2026-08-31T07:26:02.605818Z","steps":["trace[1378326276] 'range keys from in-memory index tree' (duration: 192.175379ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:02.64085Z","caller":"traceutil/trace.go:171","msg":"trace[1375165422] transaction","detail":"{read_only:false; response_revision:15230; number_of_response:1; }","duration":"136.800219ms","start":"2026-08-31T07:26:02.504025Z","end":"2026-08-31T07:26:02.640825Z","steps":["trace[1375165422] 'process raft request' (duration: 136.644795ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:04.967141Z","caller":"traceutil/trace.go:171","msg":"trace[1794523977] transaction","detail":"{read_only:false; response_revision:15242; number_of_response:1; }","duration":"110.666504ms","start":"2026-08-31T07:26:04.856448Z","end":"2026-08-31T07:26:04.967115Z","steps":["trace[1794523977] 'process raft request' (duration: 110.214255ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:26:20.292263Z","caller":"traceutil/trace.go:171","msg":"trace[314680146] transaction","detail":"{read_only:false; response_revision:15334; number_of_response:1; }","duration":"400.449752ms","start":"2026-08-31T07:26:19.891786Z","end":"2026-08-31T07:26:20.292236Z","steps":["trace[314680146] 'process raft request' (duration: 400.281779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:26:20.292427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:26:19.891766Z","time spent":"400.580665ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:26:20.312012Z","caller":"traceutil/trace.go:171","msg":"trace[1419734796] linearizableReadLoop","detail":"{readStateIndex:15866; appliedIndex:15864; }","duration":"166.992425ms","start":"2026-08-31T07:26:20.14496Z","end":"2026-08-31T07:26:20.311953Z","steps":["trace[1419734796] 'read index received' (duration: 147.124161ms)","trace[1419734796] 'applied index is now lower than readState.Index' (duration: 19.867344ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:26:20.312051Z","caller":"traceutil/trace.go:171","msg":"trace[589506582] transaction","detail":"{read_only:false; response_revision:15335; number_of_response:1; }","duration":"314.591368ms","start":"2026-08-31T07:26:19.997436Z","end":"2026-08-31T07:26:20.312027Z","steps":["trace[589506582] 'process raft request' (duration: 314.382974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:26:20.312185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:26:19.997417Z","time spent":"314.682102ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:26:20.312276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.661665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-31T07:26:20.312334Z","caller":"traceutil/trace.go:171","msg":"trace[1874962267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15335; }","duration":"133.766347ms","start":"2026-08-31T07:26:20.178551Z","end":"2026-08-31T07:26:20.312318Z","steps":["trace[1874962267] 'agreement among raft nodes before linearized reading' (duration: 133.595704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:26:20.31234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.338245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-9sx6z\" ","response":"range_response_count:1 size:11628"} {"level":"info","ts":"2026-08-31T07:26:20.312418Z","caller":"traceutil/trace.go:171","msg":"trace[2072963255] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-9sx6z; range_end:; response_count:1; response_revision:15335; }","duration":"167.480647ms","start":"2026-08-31T07:26:20.14492Z","end":"2026-08-31T07:26:20.312401Z","steps":["trace[2072963255] 'agreement among raft nodes before linearized reading' (duration: 167.202891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:26:43.144634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.133493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T07:26:43.144743Z","caller":"traceutil/trace.go:171","msg":"trace[412664664] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:15647; }","duration":"118.309606ms","start":"2026-08-31T07:26:43.026408Z","end":"2026-08-31T07:26:43.144717Z","steps":["trace[412664664] 'count revisions from in-memory index tree' (duration: 117.999239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:26:43.14466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.786287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ed8c919-d9ed-456c-a923-4bfb37c9eeff\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-31T07:26:43.144908Z","caller":"traceutil/trace.go:171","msg":"trace[1216347537] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ed8c919-d9ed-456c-a923-4bfb37c9eeff; range_end:; response_count:1; response_revision:15647; }","duration":"111.075843ms","start":"2026-08-31T07:26:43.033804Z","end":"2026-08-31T07:26:43.14488Z","steps":["trace[1216347537] 'range keys from in-memory index tree' (duration: 110.569962ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:27:36.678759Z","caller":"traceutil/trace.go:171","msg":"trace[689497396] transaction","detail":"{read_only:false; response_revision:16300; number_of_response:1; }","duration":"106.567456ms","start":"2026-08-31T07:27:36.572169Z","end":"2026-08-31T07:27:36.678736Z","steps":["trace[689497396] 'process raft request' (duration: 106.365362ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:05.471187Z","caller":"traceutil/trace.go:171","msg":"trace[1621177488] transaction","detail":"{read_only:false; response_revision:16453; number_of_response:1; }","duration":"109.880206ms","start":"2026-08-31T07:28:05.36126Z","end":"2026-08-31T07:28:05.47114Z","steps":["trace[1621177488] 'process raft request' (duration: 86.016876ms)","trace[1621177488] 'compare' (duration: 23.673956ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:28:05.74112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.378218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:28:05.741198Z","caller":"traceutil/trace.go:171","msg":"trace[18158774] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16453; }","duration":"140.518451ms","start":"2026-08-31T07:28:05.600659Z","end":"2026-08-31T07:28:05.741177Z","steps":["trace[18158774] 'range keys from in-memory index tree' (duration: 140.279426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:05.741411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.534441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:28:05.741455Z","caller":"traceutil/trace.go:171","msg":"trace[2054282623] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16453; }","duration":"140.601093ms","start":"2026-08-31T07:28:05.600844Z","end":"2026-08-31T07:28:05.741445Z","steps":["trace[2054282623] 'range keys from in-memory index tree' (duration: 140.49338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:05.741759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.119532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:28:05.741846Z","caller":"traceutil/trace.go:171","msg":"trace[2087873567] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16453; }","duration":"140.217074ms","start":"2026-08-31T07:28:05.601611Z","end":"2026-08-31T07:28:05.741828Z","steps":["trace[2087873567] 'range keys from in-memory index tree' (duration: 139.961369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:05.742052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.96925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"info","ts":"2026-08-31T07:28:05.742097Z","caller":"traceutil/trace.go:171","msg":"trace[134814502] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16453; }","duration":"140.042051ms","start":"2026-08-31T07:28:05.602044Z","end":"2026-08-31T07:28:05.742087Z","steps":["trace[134814502] 'range keys from in-memory index tree' (duration: 139.804296ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:06.972796Z","caller":"traceutil/trace.go:171","msg":"trace[1572308034] transaction","detail":"{read_only:false; response_revision:16459; number_of_response:1; }","duration":"107.021675ms","start":"2026-08-31T07:28:06.865751Z","end":"2026-08-31T07:28:06.972773Z","steps":["trace[1572308034] 'process raft request' (duration: 105.921772ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:11.636058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14606} {"level":"info","ts":"2026-08-31T07:29:11.720322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14606,"took":"81.555822ms","hash":3155545172,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":29962240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T07:29:11.720378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3155545172,"revision":14606,"compact-revision":12603} {"level":"info","ts":"2026-08-31T07:34:11.642957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16856} {"level":"info","ts":"2026-08-31T07:34:11.748896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16856,"took":"102.375015ms","hash":1816490213,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":31498240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T07:34:11.749283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1816490213,"revision":16856,"compact-revision":14606} {"level":"warn","ts":"2026-08-31T07:34:31.330692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.130483ms","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-08-31T07:34:31.330782Z","caller":"traceutil/trace.go:171","msg":"trace[716575705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19219; }","duration":"118.265326ms","start":"2026-08-31T07:34:31.212498Z","end":"2026-08-31T07:34:31.330764Z","steps":["trace[716575705] 'range keys from in-memory index tree' (duration: 117.901498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:34:31.330804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.217385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-31T07:34:31.330901Z","caller":"traceutil/trace.go:171","msg":"trace[509935982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19219; }","duration":"104.349607ms","start":"2026-08-31T07:34:31.226528Z","end":"2026-08-31T07:34:31.330878Z","steps":["trace[509935982] 'range keys from in-memory index tree' (duration: 104.0053ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:34:37.594342Z","caller":"traceutil/trace.go:171","msg":"trace[642690984] linearizableReadLoop","detail":"{readStateIndex:19919; appliedIndex:19918; }","duration":"170.771511ms","start":"2026-08-31T07:34:37.423548Z","end":"2026-08-31T07:34:37.594319Z","steps":["trace[642690984] 'read index received' (duration: 97.360677ms)","trace[642690984] 'applied index is now lower than readState.Index' (duration: 73.409514ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:34:37.594374Z","caller":"traceutil/trace.go:171","msg":"trace[9899044] transaction","detail":"{read_only:false; response_revision:19260; number_of_response:1; }","duration":"225.855791ms","start":"2026-08-31T07:34:37.36848Z","end":"2026-08-31T07:34:37.594336Z","steps":["trace[9899044] 'process raft request' (duration: 152.412436ms)","trace[9899044] 'compare' (duration: 73.306551ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:34:37.594555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.990445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-31T07:34:37.594627Z","caller":"traceutil/trace.go:171","msg":"trace[311621517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19260; }","duration":"171.110148ms","start":"2026-08-31T07:34:37.423507Z","end":"2026-08-31T07:34:37.594617Z","steps":["trace[311621517] 'agreement among raft nodes before linearized reading' (duration: 170.923334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:34:37.605267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.114499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:34:37.605367Z","caller":"traceutil/trace.go:171","msg":"trace[1054389954] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:19261; }","duration":"158.265462ms","start":"2026-08-31T07:34:37.447079Z","end":"2026-08-31T07:34:37.605344Z","steps":["trace[1054389954] 'agreement among raft nodes before linearized reading' (duration: 158.094609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:34:37.606131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.499594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-31T07:34:37.606238Z","caller":"traceutil/trace.go:171","msg":"trace[1987759905] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:19261; }","duration":"101.386144ms","start":"2026-08-31T07:34:37.504829Z","end":"2026-08-31T07:34:37.606215Z","steps":["trace[1987759905] 'agreement among raft nodes before linearized reading' (duration: 100.498954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:34:37.928104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.583305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-31T07:34:37.928187Z","caller":"traceutil/trace.go:171","msg":"trace[1106553651] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:19263; }","duration":"155.701648ms","start":"2026-08-31T07:34:37.772461Z","end":"2026-08-31T07:34:37.928163Z","steps":["trace[1106553651] 'range keys from in-memory index tree' (duration: 155.291099ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:34:38.321214Z","caller":"traceutil/trace.go:171","msg":"trace[1890251679] linearizableReadLoop","detail":"{readStateIndex:19923; appliedIndex:19922; }","duration":"354.509369ms","start":"2026-08-31T07:34:37.966682Z","end":"2026-08-31T07:34:38.321191Z","steps":["trace[1890251679] 'read index received' (duration: 354.340966ms)","trace[1890251679] 'applied index is now lower than readState.Index' (duration: 167.283µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:34:38.321323Z","caller":"traceutil/trace.go:171","msg":"trace[404847894] transaction","detail":"{read_only:false; response_revision:19264; number_of_response:1; }","duration":"371.089774ms","start":"2026-08-31T07:34:37.950206Z","end":"2026-08-31T07:34:38.321295Z","steps":["trace[404847894] 'process raft request' (duration: 370.827089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:34:38.321441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.757005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-31T07:34:38.321462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:34:37.950169Z","time spent":"371.211966ms","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20785,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:34:38.321499Z","caller":"traceutil/trace.go:171","msg":"trace[1135117784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19264; }","duration":"354.852746ms","start":"2026-08-31T07:34:37.966631Z","end":"2026-08-31T07:34:38.321484Z","steps":["trace[1135117784] 'agreement among raft nodes before linearized reading' (duration: 354.685103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:34:38.321545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:34:37.966611Z","time spent":"354.922468ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-31T07:34:38.48203Z","caller":"traceutil/trace.go:171","msg":"trace[881277812] transaction","detail":"{read_only:false; response_revision:19265; number_of_response:1; }","duration":"154.823648ms","start":"2026-08-31T07:34:38.327179Z","end":"2026-08-31T07:34:38.482003Z","steps":["trace[881277812] 'process raft request' (duration: 100.839951ms)","trace[881277812] 'compare' (duration: 53.776303ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:34:38.482241Z","caller":"traceutil/trace.go:171","msg":"trace[11794601] transaction","detail":"{read_only:false; response_revision:19266; number_of_response:1; }","duration":"152.09155ms","start":"2026-08-31T07:34:38.330132Z","end":"2026-08-31T07:34:38.482224Z","steps":["trace[11794601] 'process raft request' (duration: 151.825034ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:34:38.482596Z","caller":"traceutil/trace.go:171","msg":"trace[1465883020] transaction","detail":"{read_only:false; response_revision:19267; number_of_response:1; }","duration":"104.45604ms","start":"2026-08-31T07:34:38.378097Z","end":"2026-08-31T07:34:38.482553Z","steps":["trace[1465883020] 'process raft request' (duration: 104.06257ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:34:41.422123Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:34:41.427752Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-08-31T07:34:41.427846Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-08-31T07:35:47.606265Z","caller":"traceutil/trace.go:171","msg":"trace[380062200] linearizableReadLoop","detail":"{readStateIndex:20733; appliedIndex:20732; }","duration":"150.245341ms","start":"2026-08-31T07:35:47.455997Z","end":"2026-08-31T07:35:47.606242Z","steps":["trace[380062200] 'read index received' (duration: 150.131088ms)","trace[380062200] 'applied index is now lower than readState.Index' (duration: 112.963µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:35:47.606419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.395164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:35:47.606522Z","caller":"traceutil/trace.go:171","msg":"trace[587402330] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:20053; }","duration":"150.551368ms","start":"2026-08-31T07:35:47.455949Z","end":"2026-08-31T07:35:47.6065Z","steps":["trace[587402330] 'agreement among raft nodes before linearized reading' (duration: 150.386324ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:35:47.606335Z","caller":"traceutil/trace.go:171","msg":"trace[1869639037] transaction","detail":"{read_only:false; response_revision:20053; number_of_response:1; }","duration":"201.737924ms","start":"2026-08-31T07:35:47.404561Z","end":"2026-08-31T07:35:47.606299Z","steps":["trace[1869639037] 'process raft request' (duration: 201.535619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:35:47.606755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.639619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T07:35:47.606801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.293651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:35:47.606869Z","caller":"traceutil/trace.go:171","msg":"trace[155936127] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:20053; }","duration":"150.381614ms","start":"2026-08-31T07:35:47.456471Z","end":"2026-08-31T07:35:47.606853Z","steps":["trace[155936127] 'agreement among raft nodes before linearized reading' (duration: 150.247061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:35:47.606929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.515906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:1 size:4939"} {"level":"info","ts":"2026-08-31T07:35:47.606841Z","caller":"traceutil/trace.go:171","msg":"trace[492361873] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:20053; }","duration":"150.756842ms","start":"2026-08-31T07:35:47.456072Z","end":"2026-08-31T07:35:47.606829Z","steps":["trace[492361873] 'agreement among raft nodes before linearized reading' (duration: 150.612949ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:35:47.60701Z","caller":"traceutil/trace.go:171","msg":"trace[1567761759] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:1; response_revision:20053; }","duration":"150.592948ms","start":"2026-08-31T07:35:47.456384Z","end":"2026-08-31T07:35:47.606977Z","steps":["trace[1567761759] 'agreement among raft nodes before linearized reading' (duration: 150.402594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:35:47.607206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.108129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:35:47.607255Z","caller":"traceutil/trace.go:171","msg":"trace[1562809629] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:20053; }","duration":"151.17951ms","start":"2026-08-31T07:35:47.456063Z","end":"2026-08-31T07:35:47.607243Z","steps":["trace[1562809629] 'agreement among raft nodes before linearized reading' (duration: 151.104169ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:39:11.651318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19093} {"level":"info","ts":"2026-08-31T07:39:11.764863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19093,"took":"110.355974ms","hash":1584917816,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":34353152,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-31T07:39:11.764942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1584917816,"revision":19093,"compact-revision":16856} {"level":"warn","ts":"2026-08-31T07:40:34.224504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.355188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:40:34.224601Z","caller":"traceutil/trace.go:171","msg":"trace[2141892663] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:22020; }","duration":"219.506121ms","start":"2026-08-31T07:40:34.005077Z","end":"2026-08-31T07:40:34.224583Z","steps":["trace[2141892663] 'count revisions from in-memory index tree' (duration: 219.269146ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:34.431815Z","caller":"traceutil/trace.go:171","msg":"trace[1544254587] linearizableReadLoop","detail":"{readStateIndex:22770; appliedIndex:22769; }","duration":"199.135457ms","start":"2026-08-31T07:40:34.232657Z","end":"2026-08-31T07:40:34.431793Z","steps":["trace[1544254587] 'read index received' (duration: 199.041945ms)","trace[1544254587] 'applied index is now lower than readState.Index' (duration: 92.572µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:40:34.432069Z","caller":"traceutil/trace.go:171","msg":"trace[319385276] transaction","detail":"{read_only:false; response_revision:22021; number_of_response:1; }","duration":"220.83453ms","start":"2026-08-31T07:40:34.211189Z","end":"2026-08-31T07:40:34.432023Z","steps":["trace[319385276] 'process raft request' (duration: 220.472242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:34.43224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.562406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-31T07:40:34.43229Z","caller":"traceutil/trace.go:171","msg":"trace[66128327] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:22021; }","duration":"199.694288ms","start":"2026-08-31T07:40:34.232587Z","end":"2026-08-31T07:40:34.432282Z","steps":["trace[66128327] 'agreement among raft nodes before linearized reading' (duration: 199.431813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:34.786479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.712131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T07:40:34.786578Z","caller":"traceutil/trace.go:171","msg":"trace[734050180] linearizableReadLoop","detail":"{readStateIndex:22771; appliedIndex:22770; }","duration":"281.483903ms","start":"2026-08-31T07:40:34.505081Z","end":"2026-08-31T07:40:34.786565Z","steps":["trace[734050180] 'read index received' (duration: 80.536237ms)","trace[734050180] 'applied index is now lower than readState.Index' (duration: 200.946696ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:40:34.786749Z","caller":"traceutil/trace.go:171","msg":"trace[2032521199] transaction","detail":"{read_only:false; response_revision:22022; number_of_response:1; }","duration":"335.790552ms","start":"2026-08-31T07:40:34.450942Z","end":"2026-08-31T07:40:34.786732Z","steps":["trace[2032521199] 'process raft request' (duration: 134.753204ms)","trace[2032521199] 'compare' (duration: 200.557577ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:40:34.786811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.719318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-31T07:40:34.786828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:40:34.450917Z","time spent":"335.882633ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:40:34.786848Z","caller":"traceutil/trace.go:171","msg":"trace[1679160364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22022; }","duration":"281.79488ms","start":"2026-08-31T07:40:34.505043Z","end":"2026-08-31T07:40:34.786838Z","steps":["trace[1679160364] 'agreement among raft nodes before linearized reading' (duration: 281.641287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:35.108848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.415875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:40:35.108912Z","caller":"traceutil/trace.go:171","msg":"trace[1375137105] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:22023; }","duration":"133.517657ms","start":"2026-08-31T07:40:34.975378Z","end":"2026-08-31T07:40:35.108895Z","steps":["trace[1375137105] 'count revisions from in-memory index tree' (duration: 133.352114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:35.108945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.920734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-31T07:40:35.108974Z","caller":"traceutil/trace.go:171","msg":"trace[1216354234] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:1; response_revision:22023; }","duration":"109.979365ms","start":"2026-08-31T07:40:34.998985Z","end":"2026-08-31T07:40:35.108965Z","steps":["trace[1216354234] 'range keys from in-memory index tree' (duration: 109.759561ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:35.275813Z","caller":"traceutil/trace.go:171","msg":"trace[1752320411] transaction","detail":"{read_only:false; response_revision:22025; number_of_response:1; }","duration":"171.65735ms","start":"2026-08-31T07:40:35.104132Z","end":"2026-08-31T07:40:35.275789Z","steps":["trace[1752320411] 'process raft request' (duration: 169.133668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:35.547395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.800741ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:40:35.547507Z","caller":"traceutil/trace.go:171","msg":"trace[1406141703] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:22026; }","duration":"163.118958ms","start":"2026-08-31T07:40:35.384371Z","end":"2026-08-31T07:40:35.547489Z","steps":["trace[1406141703] 'range keys from in-memory index tree' (duration: 162.784941ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:35.557766Z","caller":"traceutil/trace.go:171","msg":"trace[5294986] transaction","detail":"{read_only:false; response_revision:22027; number_of_response:1; }","duration":"192.799492ms","start":"2026-08-31T07:40:35.364926Z","end":"2026-08-31T07:40:35.557726Z","steps":["trace[5294986] 'process raft request' (duration: 192.438324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:35.870968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.389056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-31T07:40:35.871046Z","caller":"traceutil/trace.go:171","msg":"trace[2136449789] linearizableReadLoop","detail":"{readStateIndex:22778; appliedIndex:22777; }","duration":"300.276643ms","start":"2026-08-31T07:40:35.570758Z","end":"2026-08-31T07:40:35.871035Z","steps":["trace[2136449789] 'read index received' (duration: 135.735124ms)","trace[2136449789] 'applied index is now lower than readState.Index' (duration: 164.540629ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:40:35.871115Z","caller":"traceutil/trace.go:171","msg":"trace[10761092] transaction","detail":"{read_only:false; response_revision:22028; number_of_response:1; }","duration":"309.287665ms","start":"2026-08-31T07:40:35.561792Z","end":"2026-08-31T07:40:35.87108Z","steps":["trace[10761092] 'process raft request' (duration: 144.727236ms)","trace[10761092] 'compare' (duration: 163.916346ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:40:35.871174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.424397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-31T07:40:35.871515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:40:35.561771Z","time spent":"309.449848ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:40:35.87157Z","caller":"traceutil/trace.go:171","msg":"trace[1275224805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22028; }","duration":"300.846535ms","start":"2026-08-31T07:40:35.570714Z","end":"2026-08-31T07:40:35.87156Z","steps":["trace[1275224805] 'agreement among raft nodes before linearized reading' (duration: 300.375015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:40:35.871608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:40:35.570697Z","time spent":"300.892647ms","remote":"127.0.0.1:35768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-31T07:40:35.871732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.977724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:40:35.871882Z","caller":"traceutil/trace.go:171","msg":"trace[227391770] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:22028; }","duration":"290.220419ms","start":"2026-08-31T07:40:35.581569Z","end":"2026-08-31T07:40:35.87179Z","steps":["trace[227391770] 'agreement among raft nodes before linearized reading' (duration: 289.542405ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:36.116116Z","caller":"traceutil/trace.go:171","msg":"trace[39608873] transaction","detail":"{read_only:false; response_revision:22029; number_of_response:1; }","duration":"240.815455ms","start":"2026-08-31T07:40:35.875263Z","end":"2026-08-31T07:40:36.116079Z","steps":["trace[39608873] 'process raft request' (duration: 149.738514ms)","trace[39608873] 'compare' (duration: 90.679343ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:40:36.116471Z","caller":"traceutil/trace.go:171","msg":"trace[1838684976] transaction","detail":"{read_only:false; response_revision:22031; number_of_response:1; }","duration":"194.840065ms","start":"2026-08-31T07:40:35.921595Z","end":"2026-08-31T07:40:36.116435Z","steps":["trace[1838684976] 'process raft request' (duration: 194.753623ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:40:36.116646Z","caller":"traceutil/trace.go:171","msg":"trace[24225728] transaction","detail":"{read_only:false; response_revision:22030; number_of_response:1; }","duration":"223.810963ms","start":"2026-08-31T07:40:35.892812Z","end":"2026-08-31T07:40:36.116623Z","steps":["trace[24225728] 'process raft request' (duration: 223.194259ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:44:11.657862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21494} {"level":"info","ts":"2026-08-31T07:44:11.775253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21494,"took":"114.489301ms","hash":2707196927,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":30670848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T07:44:11.775321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2707196927,"revision":21494,"compact-revision":19093} {"level":"info","ts":"2026-08-31T07:44:48.133126Z","caller":"traceutil/trace.go:171","msg":"trace[234867731] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"179.247342ms","start":"2026-08-31T07:44:47.953851Z","end":"2026-08-31T07:44:48.133098Z","steps":["trace[234867731] 'process raft request' (duration: 110.21917ms)","trace[234867731] 'compare' (duration: 68.664204ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:45:25.457235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.609564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T07:45:25.457359Z","caller":"traceutil/trace.go:171","msg":"trace[1476140239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24954; }","duration":"107.764148ms","start":"2026-08-31T07:45:25.349568Z","end":"2026-08-31T07:45:25.457332Z","steps":["trace[1476140239] 'range keys from in-memory index tree' (duration: 107.549623ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:48:51.276479Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b9f.wal"} {"level":"info","ts":"2026-08-31T07:49:11.664489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23729} {"level":"info","ts":"2026-08-31T07:49:11.794185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23729,"took":"125.919908ms","hash":2213388507,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":41951232,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-31T07:49:11.794242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2213388507,"revision":23729,"compact-revision":21494} {"level":"info","ts":"2026-08-31T07:54:11.672188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26815} {"level":"info","ts":"2026-08-31T07:54:11.82143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26815,"took":"145.941278ms","hash":875195900,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":29626368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T07:54:11.821544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":875195900,"revision":26815,"compact-revision":23729} {"level":"info","ts":"2026-08-31T07:54:12.836968Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T07:54:12.843109Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-08-31T07:54:12.843374Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004}