{"level":"warn","ts":"2026-03-06T21:58:01.568634Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T21:58:01.571225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-03-06T21:58:01.571307Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T21:58:01.571319Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-06T21:58:01.571344Z","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-03-06T21:58:01.572203Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-03-06T21:58:01.572406Z","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.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-03-06T21:58:01.576486Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.789918ms"} {"level":"info","ts":"2026-03-06T21:58:01.583367Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-03-06T21:58:01.583534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T21:58:01.583579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-03-06T21:58:01.58361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T21:58:01.583625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-03-06T21:58:01.583697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-03-06T21:58:01.59004Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T21:58:01.592255Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T21:58:01.593441Z","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-03-06T21:58:01.595922Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T21:58:01.596196Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T21:58:01.596293Z","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-03-06T21:58:01.596482Z","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-03-06T21:58:01.596512Z","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-03-06T21:58:01.598001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-03-06T21:58:01.598401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-06T21:58:01.601203Z","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-03-06T21:58:01.601384Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-06T21:58:01.601444Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-06T21:58:01.60167Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T21:58:01.601727Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T21:58:02.1841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T21:58:02.184178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T21:58:02.184203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-03-06T21:58:02.184222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-03-06T21:58:02.184234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-06T21:58:02.18425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-03-06T21:58:02.184266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-06T21:58:02.185369Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:58:02.186244Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T21:58:02.186474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T21:58:02.186543Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T21:58:02.186776Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T21:58:02.18682Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T21:58:02.186811Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:58:02.186961Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:58:02.187013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:58:02.189202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-03-06T21:58:02.191274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T21:58:13.425232Z","caller":"traceutil/trace.go:171","msg":"trace[1937522173] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"196.680829ms","start":"2026-03-06T21:58:13.228514Z","end":"2026-03-06T21:58:13.425195Z","steps":["trace[1937522173] 'process raft request' (duration: 138.964371ms)","trace[1937522173] 'compare' (duration: 57.511103ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:58:13.502561Z","caller":"traceutil/trace.go:171","msg":"trace[820210054] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:406; }","duration":"154.683436ms","start":"2026-03-06T21:58:13.347844Z","end":"2026-03-06T21:58:13.502527Z","steps":["trace[820210054] 'read index received' (duration: 19.649666ms)","trace[820210054] 'applied index is now lower than readState.Index' (duration: 135.03222ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:58:13.503069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.135867ms","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-03-06T21:58:13.503361Z","caller":"traceutil/trace.go:171","msg":"trace[80680174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"155.406873ms","start":"2026-03-06T21:58:13.347779Z","end":"2026-03-06T21:58:13.503185Z","steps":["trace[80680174] 'agreement among raft nodes before linearized reading' (duration: 154.972332ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:13.664505Z","caller":"traceutil/trace.go:171","msg":"trace[245164358] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"152.411622ms","start":"2026-03-06T21:58:13.512065Z","end":"2026-03-06T21:58:13.664477Z","steps":["trace[245164358] 'process raft request' (duration: 152.33837ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:13.665031Z","caller":"traceutil/trace.go:171","msg":"trace[1478937515] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"157.389598ms","start":"2026-03-06T21:58:13.507628Z","end":"2026-03-06T21:58:13.665018Z","steps":["trace[1478937515] 'process raft request' (duration: 156.647311ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:13.933233Z","caller":"traceutil/trace.go:171","msg":"trace[1560933180] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"254.444708ms","start":"2026-03-06T21:58:13.678755Z","end":"2026-03-06T21:58:13.9332Z","steps":["trace[1560933180] 'process raft request' (duration: 254.251883ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:39.4476Z","caller":"traceutil/trace.go:171","msg":"trace[78316945] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"158.101548ms","start":"2026-03-06T21:58:39.289465Z","end":"2026-03-06T21:58:39.447566Z","steps":["trace[78316945] 'process raft request' (duration: 157.887993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:58:39.652184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.675594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kqd59\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-06T21:58:39.652316Z","caller":"traceutil/trace.go:171","msg":"trace[1948958003] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kqd59; range_end:; response_count:1; response_revision:552; }","duration":"141.881929ms","start":"2026-03-06T21:58:39.510405Z","end":"2026-03-06T21:58:39.652286Z","steps":["trace[1948958003] 'range keys from in-memory index tree' (duration: 141.323196ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:39.864196Z","caller":"traceutil/trace.go:171","msg":"trace[1537801352] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"195.070402ms","start":"2026-03-06T21:58:39.669083Z","end":"2026-03-06T21:58:39.864154Z","steps":["trace[1537801352] 'process raft request' (duration: 194.821466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:58:40.240616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.90396ms","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-03-06T21:58:40.240728Z","caller":"traceutil/trace.go:171","msg":"trace[74372408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"110.064174ms","start":"2026-03-06T21:58:40.130634Z","end":"2026-03-06T21:58:40.240698Z","steps":["trace[74372408] 'range keys from in-memory index tree' (duration: 109.673245ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:41.775084Z","caller":"traceutil/trace.go:171","msg":"trace[1847219984] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"105.415316ms","start":"2026-03-06T21:58:41.669636Z","end":"2026-03-06T21:58:41.775052Z","steps":["trace[1847219984] 'process raft request' (duration: 28.740152ms)","trace[1847219984] 'compare' (duration: 76.349576ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:58:58.909303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.587524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T21:58:58.909581Z","caller":"traceutil/trace.go:171","msg":"trace[758353929] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"167.17792ms","start":"2026-03-06T21:58:58.742386Z","end":"2026-03-06T21:58:58.909564Z","steps":["trace[758353929] 'process raft request' (duration: 167.122949ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:58.909649Z","caller":"traceutil/trace.go:171","msg":"trace[422757976] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"168.632154ms","start":"2026-03-06T21:58:58.740988Z","end":"2026-03-06T21:58:58.90962Z","steps":["trace[422757976] 'process raft request' (duration: 168.43383ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:58:58.909816Z","caller":"traceutil/trace.go:171","msg":"trace[1955149441] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"168.791678ms","start":"2026-03-06T21:58:58.740918Z","end":"2026-03-06T21:58:58.90971Z","steps":["trace[1955149441] 'process raft request' (duration: 55.15051ms)","trace[1955149441] 'compare' (duration: 112.458641ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:58:59.05303Z","caller":"traceutil/trace.go:171","msg":"trace[440924127] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"133.290137ms","start":"2026-03-06T21:58:58.919701Z","end":"2026-03-06T21:58:59.052991Z","steps":["trace[440924127] 'process raft request' (duration: 118.245175ms)","trace[440924127] 'compare' (duration: 14.872948ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:59:51.335271Z","caller":"traceutil/trace.go:171","msg":"trace[140377856] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"150.459775ms","start":"2026-03-06T21:59:51.184777Z","end":"2026-03-06T21:59:51.335237Z","steps":["trace[140377856] 'process raft request' (duration: 150.213249ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:59:51.793866Z","caller":"traceutil/trace.go:171","msg":"trace[430410062] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"297.495991ms","start":"2026-03-06T21:59:51.496339Z","end":"2026-03-06T21:59:51.793835Z","steps":["trace[430410062] 'process raft request' (duration: 296.441886ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:59:51.793868Z","caller":"traceutil/trace.go:171","msg":"trace[1617529420] linearizableReadLoop","detail":"{readStateIndex:1672; appliedIndex:1671; }","duration":"231.159178ms","start":"2026-03-06T21:59:51.562674Z","end":"2026-03-06T21:59:51.793833Z","steps":["trace[1617529420] 'read index received' (duration: 230.039042ms)","trace[1617529420] 'applied index is now lower than readState.Index' (duration: 1.118316ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:59:51.794095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.388643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:59:51.794262Z","caller":"traceutil/trace.go:171","msg":"trace[1049333107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1608; }","duration":"231.621358ms","start":"2026-03-06T21:59:51.562613Z","end":"2026-03-06T21:59:51.794234Z","steps":["trace[1049333107] 'agreement among raft nodes before linearized reading' (duration: 231.363812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:59:51.794342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.77019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57a8bea9-975f-43cf-b66a-c7b530d9c57f\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-03-06T21:59:51.794353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.787869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T21:59:51.794422Z","caller":"traceutil/trace.go:171","msg":"trace[459291284] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57a8bea9-975f-43cf-b66a-c7b530d9c57f; range_end:; response_count:1; response_revision:1608; }","duration":"197.879203ms","start":"2026-03-06T21:59:51.596525Z","end":"2026-03-06T21:59:51.794404Z","steps":["trace[459291284] 'agreement among raft nodes before linearized reading' (duration: 197.534795ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:59:51.794432Z","caller":"traceutil/trace.go:171","msg":"trace[1872564184] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1608; }","duration":"104.940392ms","start":"2026-03-06T21:59:51.689475Z","end":"2026-03-06T21:59:51.794415Z","steps":["trace[1872564184] 'agreement among raft nodes before linearized reading' (duration: 104.604894ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:35.654585Z","caller":"traceutil/trace.go:171","msg":"trace[2054257067] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"138.926894ms","start":"2026-03-06T22:00:35.515603Z","end":"2026-03-06T22:00:35.654529Z","steps":["trace[2054257067] 'process raft request' (duration: 138.668688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:41.099346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.655638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-06T22:00:41.099453Z","caller":"traceutil/trace.go:171","msg":"trace[1010322657] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1910; }","duration":"149.844102ms","start":"2026-03-06T22:00:40.949584Z","end":"2026-03-06T22:00:41.099429Z","steps":["trace[1010322657] 'range keys from in-memory index tree' (duration: 149.378151ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:41.163734Z","caller":"traceutil/trace.go:171","msg":"trace[449752828] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"134.688664ms","start":"2026-03-06T22:00:41.029009Z","end":"2026-03-06T22:00:41.163698Z","steps":["trace[449752828] 'read index received' (duration: 134.53069ms)","trace[449752828] 'applied index is now lower than readState.Index' (duration: 156.804µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:41.163788Z","caller":"traceutil/trace.go:171","msg":"trace[1643812358] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"174.928383ms","start":"2026-03-06T22:00:40.988833Z","end":"2026-03-06T22:00:41.163762Z","steps":["trace[1643812358] 'process raft request' (duration: 174.645977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:41.163918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.890439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:00:41.163996Z","caller":"traceutil/trace.go:171","msg":"trace[2145292760] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1911; }","duration":"135.036722ms","start":"2026-03-06T22:00:41.028938Z","end":"2026-03-06T22:00:41.163975Z","steps":["trace[2145292760] 'agreement among raft nodes before linearized reading' (duration: 134.880918ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:46.641646Z","caller":"traceutil/trace.go:171","msg":"trace[147715008] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2012; }","duration":"109.683364ms","start":"2026-03-06T22:00:46.531932Z","end":"2026-03-06T22:00:46.641616Z","steps":["trace[147715008] 'read index received' (duration: 109.456899ms)","trace[147715008] 'applied index is now lower than readState.Index' (duration: 225.315µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:00:46.641753Z","caller":"traceutil/trace.go:171","msg":"trace[45968641] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"115.760057ms","start":"2026-03-06T22:00:46.52595Z","end":"2026-03-06T22:00:46.64171Z","steps":["trace[45968641] 'process raft request' (duration: 115.505001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:46.641907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.94354ms","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-03-06T22:00:46.641992Z","caller":"traceutil/trace.go:171","msg":"trace[1842311396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1936; }","duration":"110.068163ms","start":"2026-03-06T22:00:46.531906Z","end":"2026-03-06T22:00:46.641974Z","steps":["trace[1842311396] 'agreement among raft nodes before linearized reading' (duration: 109.821957ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:47.087366Z","caller":"traceutil/trace.go:171","msg":"trace[62074247] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"203.858512ms","start":"2026-03-06T22:00:46.883482Z","end":"2026-03-06T22:00:47.087341Z","steps":["trace[62074247] 'process raft request' (duration: 203.698059ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:47.087725Z","caller":"traceutil/trace.go:171","msg":"trace[407577498] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2016; }","duration":"146.000773ms","start":"2026-03-06T22:00:46.941697Z","end":"2026-03-06T22:00:47.087697Z","steps":["trace[407577498] 'read index received' (duration: 145.992882ms)","trace[407577498] 'applied index is now lower than readState.Index' (duration: 5.601µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:47.08798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.255139ms","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-03-06T22:00:47.088022Z","caller":"traceutil/trace.go:171","msg":"trace[1750054661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1939; }","duration":"146.371082ms","start":"2026-03-06T22:00:46.94164Z","end":"2026-03-06T22:00:47.088011Z","steps":["trace[1750054661] 'agreement among raft nodes before linearized reading' (duration: 146.161497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:00:47.345309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.760419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:00:47.345615Z","caller":"traceutil/trace.go:171","msg":"trace[36745394] linearizableReadLoop","detail":"{readStateIndex:2018; appliedIndex:2016; }","duration":"114.902957ms","start":"2026-03-06T22:00:47.230694Z","end":"2026-03-06T22:00:47.345597Z","steps":["trace[36745394] 'read index received' (duration: 29.068398ms)","trace[36745394] 'applied index is now lower than readState.Index' (duration: 85.833089ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:00:47.345816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.127373ms","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-03-06T22:00:47.34582Z","caller":"traceutil/trace.go:171","msg":"trace[1930923955] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"161.96342ms","start":"2026-03-06T22:00:47.183824Z","end":"2026-03-06T22:00:47.345787Z","steps":["trace[1930923955] 'process raft request' (duration: 161.595411ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:47.345859Z","caller":"traceutil/trace.go:171","msg":"trace[298328390] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1941; }","duration":"115.225625ms","start":"2026-03-06T22:00:47.230622Z","end":"2026-03-06T22:00:47.345847Z","steps":["trace[298328390] 'agreement among raft nodes before linearized reading' (duration: 115.037111ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:00:47.345894Z","caller":"traceutil/trace.go:171","msg":"trace[1203718751] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"253.457534ms","start":"2026-03-06T22:00:47.0924Z","end":"2026-03-06T22:00:47.345857Z","steps":["trace[1203718751] 'process raft request' (duration: 79.05155ms)","trace[1203718751] 'compare' (duration: 173.496533ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:01:38.613141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.34333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-06T22:01:38.613241Z","caller":"traceutil/trace.go:171","msg":"trace[1877759520] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2359; }","duration":"102.505844ms","start":"2026-03-06T22:01:38.51071Z","end":"2026-03-06T22:01:38.613216Z","steps":["trace[1877759520] 'range keys from in-memory index tree' (duration: 102.140396ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:09.670145Z","caller":"traceutil/trace.go:171","msg":"trace[712723211] transaction","detail":"{read_only:false; response_revision:4444; number_of_response:1; }","duration":"103.810617ms","start":"2026-03-06T22:06:09.566301Z","end":"2026-03-06T22:06:09.670111Z","steps":["trace[712723211] 'process raft request' (duration: 103.297645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:06:09.670581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.142193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62888"} {"level":"info","ts":"2026-03-06T22:06:09.670623Z","caller":"traceutil/trace.go:171","msg":"trace[1943473654] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4444; }","duration":"100.205675ms","start":"2026-03-06T22:06:09.570407Z","end":"2026-03-06T22:06:09.670612Z","steps":["trace[1943473654] 'agreement among raft nodes before linearized reading' (duration: 99.953069ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:14.608052Z","caller":"traceutil/trace.go:171","msg":"trace[237636429] linearizableReadLoop","detail":"{readStateIndex:4816; appliedIndex:4815; }","duration":"253.806885ms","start":"2026-03-06T22:06:14.354213Z","end":"2026-03-06T22:06:14.60802Z","steps":["trace[237636429] 'read index received' (duration: 253.627521ms)","trace[237636429] 'applied index is now lower than readState.Index' (duration: 178.224µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:06:14.608121Z","caller":"traceutil/trace.go:171","msg":"trace[404462374] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"259.59468ms","start":"2026-03-06T22:06:14.348483Z","end":"2026-03-06T22:06:14.608078Z","steps":["trace[404462374] 'process raft request' (duration: 259.364095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:06:14.608449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.243426ms","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-03-06T22:06:14.608577Z","caller":"traceutil/trace.go:171","msg":"trace[270333981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4635; }","duration":"254.441011ms","start":"2026-03-06T22:06:14.354108Z","end":"2026-03-06T22:06:14.608549Z","steps":["trace[270333981] 'agreement among raft nodes before linearized reading' (duration: 254.04173ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:14.636809Z","caller":"traceutil/trace.go:171","msg":"trace[2032033311] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"173.809861ms","start":"2026-03-06T22:06:14.46297Z","end":"2026-03-06T22:06:14.63678Z","steps":["trace[2032033311] 'process raft request' (duration: 173.629557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:06:14.637086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.600768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-06T22:06:14.637143Z","caller":"traceutil/trace.go:171","msg":"trace[1731698197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4636; }","duration":"280.70686ms","start":"2026-03-06T22:06:14.356418Z","end":"2026-03-06T22:06:14.637125Z","steps":["trace[1731698197] 'agreement among raft nodes before linearized reading' (duration: 280.564097ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:16.282788Z","caller":"traceutil/trace.go:171","msg":"trace[1111296967] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"112.675803ms","start":"2026-03-06T22:06:16.170083Z","end":"2026-03-06T22:06:16.282759Z","steps":["trace[1111296967] 'process raft request' (duration: 112.492719ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:16.397069Z","caller":"traceutil/trace.go:171","msg":"trace[215129786] linearizableReadLoop","detail":"{readStateIndex:4856; appliedIndex:4855; }","duration":"105.091776ms","start":"2026-03-06T22:06:16.291942Z","end":"2026-03-06T22:06:16.397034Z","steps":["trace[215129786] 'read index received' (duration: 81.735435ms)","trace[215129786] 'applied index is now lower than readState.Index' (duration: 23.354201ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:06:16.397106Z","caller":"traceutil/trace.go:171","msg":"trace[1144718037] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"114.502976ms","start":"2026-03-06T22:06:16.28255Z","end":"2026-03-06T22:06:16.397053Z","steps":["trace[1144718037] 'process raft request' (duration: 91.181925ms)","trace[1144718037] 'compare' (duration: 23.070285ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:06:16.397335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.351593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T22:06:16.397426Z","caller":"traceutil/trace.go:171","msg":"trace[2141497326] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4673; }","duration":"105.511038ms","start":"2026-03-06T22:06:16.291893Z","end":"2026-03-06T22:06:16.397404Z","steps":["trace[2141497326] 'agreement among raft nodes before linearized reading' (duration: 105.261552ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:35.222033Z","caller":"traceutil/trace.go:171","msg":"trace[1619859531] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"150.408419ms","start":"2026-03-06T22:06:35.071594Z","end":"2026-03-06T22:06:35.222002Z","steps":["trace[1619859531] 'process raft request' (duration: 150.138442ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:35.232764Z","caller":"traceutil/trace.go:171","msg":"trace[139314236] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"161.021675ms","start":"2026-03-06T22:06:35.071711Z","end":"2026-03-06T22:06:35.232732Z","steps":["trace[139314236] 'process raft request' (duration: 160.845411ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:35.478357Z","caller":"traceutil/trace.go:171","msg":"trace[1568731632] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5215; }","duration":"178.425157ms","start":"2026-03-06T22:06:35.299895Z","end":"2026-03-06T22:06:35.47832Z","steps":["trace[1568731632] 'read index received' (duration: 178.180722ms)","trace[1568731632] 'applied index is now lower than readState.Index' (duration: 243.315µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:06:35.478441Z","caller":"traceutil/trace.go:171","msg":"trace[235960543] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"189.647017ms","start":"2026-03-06T22:06:35.288751Z","end":"2026-03-06T22:06:35.478398Z","steps":["trace[235960543] 'process raft request' (duration: 189.311299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:06:35.478682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.431203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-06T22:06:35.478729Z","caller":"traceutil/trace.go:171","msg":"trace[1853361875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5026; }","duration":"154.530314ms","start":"2026-03-06T22:06:35.324187Z","end":"2026-03-06T22:06:35.478717Z","steps":["trace[1853361875] 'agreement among raft nodes before linearized reading' (duration: 154.328549ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:06:35.478846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.910969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-06T22:06:35.478969Z","caller":"traceutil/trace.go:171","msg":"trace[1221081368] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5026; }","duration":"179.114464ms","start":"2026-03-06T22:06:35.299829Z","end":"2026-03-06T22:06:35.478943Z","steps":["trace[1221081368] 'agreement among raft nodes before linearized reading' (duration: 178.674073ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:47.408264Z","caller":"traceutil/trace.go:171","msg":"trace[727107894] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"136.159228ms","start":"2026-03-06T22:06:47.272074Z","end":"2026-03-06T22:06:47.408234Z","steps":["trace[727107894] 'process raft request' (duration: 136.106367ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:06:47.408315Z","caller":"traceutil/trace.go:171","msg":"trace[83463006] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"140.886207ms","start":"2026-03-06T22:06:47.267404Z","end":"2026-03-06T22:06:47.40829Z","steps":["trace[83463006] 'process raft request' (duration: 140.454847ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:07:25.705407Z","caller":"traceutil/trace.go:171","msg":"trace[1873037821] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"150.758834ms","start":"2026-03-06T22:07:25.554621Z","end":"2026-03-06T22:07:25.70538Z","steps":["trace[1873037821] 'process raft request' (duration: 150.58017ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:07:25.711481Z","caller":"traceutil/trace.go:171","msg":"trace[841995367] linearizableReadLoop","detail":"{readStateIndex:5722; appliedIndex:5720; }","duration":"147.760363ms","start":"2026-03-06T22:07:25.56368Z","end":"2026-03-06T22:07:25.71144Z","steps":["trace[841995367] 'read index received' (duration: 141.566798ms)","trace[841995367] 'applied index is now lower than readState.Index' (duration: 6.191605ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:07:25.71176Z","caller":"traceutil/trace.go:171","msg":"trace[7660074] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"153.936409ms","start":"2026-03-06T22:07:25.5578Z","end":"2026-03-06T22:07:25.711736Z","steps":["trace[7660074] 'process raft request' (duration: 153.459198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:07:25.713578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.885894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-03-06T22:07:25.713664Z","caller":"traceutil/trace.go:171","msg":"trace[28686264] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5515; }","duration":"150.044747ms","start":"2026-03-06T22:07:25.563598Z","end":"2026-03-06T22:07:25.713643Z","steps":["trace[28686264] 'agreement among raft nodes before linearized reading' (duration: 148.003729ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:08:02.32861Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2798} {"level":"info","ts":"2026-03-06T22:08:02.389983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2798,"took":"59.717327ms","hash":334101175,"current-db-size-bytes":41754624,"current-db-size":"42 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-06T22:08:02.390063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":334101175,"revision":2798,"compact-revision":-1} {"level":"info","ts":"2026-03-06T22:12:44.80329Z","caller":"traceutil/trace.go:171","msg":"trace[1577162897] transaction","detail":"{read_only:false; response_revision:7709; number_of_response:1; }","duration":"153.115811ms","start":"2026-03-06T22:12:44.650125Z","end":"2026-03-06T22:12:44.80324Z","steps":["trace[1577162897] 'process raft request' (duration: 152.872916ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:12:56.170374Z","caller":"traceutil/trace.go:171","msg":"trace[1627349957] transaction","detail":"{read_only:false; response_revision:7762; number_of_response:1; }","duration":"124.289599ms","start":"2026-03-06T22:12:56.046036Z","end":"2026-03-06T22:12:56.170326Z","steps":["trace[1627349957] 'process raft request' (duration: 124.042333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:12:56.17057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.00583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zlnv9\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-03-06T22:12:56.170615Z","caller":"traceutil/trace.go:171","msg":"trace[764609964] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zlnv9; range_end:; response_count:1; response_revision:7762; }","duration":"117.149353ms","start":"2026-03-06T22:12:56.053457Z","end":"2026-03-06T22:12:56.170607Z","steps":["trace[764609964] 'agreement among raft nodes before linearized reading' (duration: 116.927898ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:12:56.170292Z","caller":"traceutil/trace.go:171","msg":"trace[1915734141] linearizableReadLoop","detail":"{readStateIndex:8047; appliedIndex:8046; }","duration":"116.735784ms","start":"2026-03-06T22:12:56.053528Z","end":"2026-03-06T22:12:56.170263Z","steps":["trace[1915734141] 'read index received' (duration: 116.54496ms)","trace[1915734141] 'applied index is now lower than readState.Index' (duration: 189.754µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:12:56.409013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.06046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:12:56.409132Z","caller":"traceutil/trace.go:171","msg":"trace[1787893943] linearizableReadLoop","detail":"{readStateIndex:8048; appliedIndex:8047; }","duration":"233.121969ms","start":"2026-03-06T22:12:56.175992Z","end":"2026-03-06T22:12:56.409114Z","steps":["trace[1787893943] 'read index received' (duration: 118.838553ms)","trace[1787893943] 'applied index is now lower than readState.Index' (duration: 114.281936ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:12:56.409291Z","caller":"traceutil/trace.go:171","msg":"trace[821516387] transaction","detail":"{read_only:false; response_revision:7763; number_of_response:1; }","duration":"325.358181ms","start":"2026-03-06T22:12:56.083879Z","end":"2026-03-06T22:12:56.409237Z","steps":["trace[821516387] 'process raft request' (duration: 210.989853ms)","trace[821516387] 'compare' (duration: 113.907557ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:12:56.40946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:12:56.083852Z","time spent":"325.520455ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:12:56.409898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.902838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:222758"} {"level":"info","ts":"2026-03-06T22:12:56.409955Z","caller":"traceutil/trace.go:171","msg":"trace[1218853230] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7763; }","duration":"233.975449ms","start":"2026-03-06T22:12:56.175968Z","end":"2026-03-06T22:12:56.409943Z","steps":["trace[1218853230] 'agreement among raft nodes before linearized reading' (duration: 233.265692ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:13:02.336747Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5778} {"level":"info","ts":"2026-03-06T22:13:02.421776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5778,"took":"82.543167ms","hash":1168862259,"current-db-size-bytes":43040768,"current-db-size":"43 MB","current-db-size-in-use-bytes":23285760,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-06T22:13:02.42184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1168862259,"revision":5778,"compact-revision":2798} {"level":"info","ts":"2026-03-06T22:13:25.209101Z","caller":"traceutil/trace.go:171","msg":"trace[1865322333] linearizableReadLoop","detail":"{readStateIndex:8250; appliedIndex:8249; }","duration":"167.590861ms","start":"2026-03-06T22:13:25.041476Z","end":"2026-03-06T22:13:25.209067Z","steps":["trace[1865322333] 'read index received' (duration: 167.455298ms)","trace[1865322333] 'applied index is now lower than readState.Index' (duration: 131.263µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:13:25.209423Z","caller":"traceutil/trace.go:171","msg":"trace[1065028255] transaction","detail":"{read_only:false; response_revision:7957; number_of_response:1; }","duration":"238.592677ms","start":"2026-03-06T22:13:24.970817Z","end":"2026-03-06T22:13:25.209409Z","steps":["trace[1065028255] 'process raft request' (duration: 238.070485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:13:25.209781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.279546ms","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-03-06T22:13:25.209826Z","caller":"traceutil/trace.go:171","msg":"trace[303310503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7957; }","duration":"168.389069ms","start":"2026-03-06T22:13:25.041425Z","end":"2026-03-06T22:13:25.209814Z","steps":["trace[303310503] 'agreement among raft nodes before linearized reading' (duration: 168.218065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:13:25.21144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.439477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T22:13:25.211499Z","caller":"traceutil/trace.go:171","msg":"trace[1155638731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7960; }","duration":"158.529689ms","start":"2026-03-06T22:13:25.052955Z","end":"2026-03-06T22:13:25.211485Z","steps":["trace[1155638731] 'agreement among raft nodes before linearized reading' (duration: 158.376416ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:13:25.211764Z","caller":"traceutil/trace.go:171","msg":"trace[1809059296] transaction","detail":"{read_only:false; response_revision:7958; number_of_response:1; }","duration":"141.243515ms","start":"2026-03-06T22:13:25.070506Z","end":"2026-03-06T22:13:25.21175Z","steps":["trace[1809059296] 'process raft request' (duration: 140.618431ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:13:25.211917Z","caller":"traceutil/trace.go:171","msg":"trace[47568625] transaction","detail":"{read_only:false; response_revision:7959; number_of_response:1; }","duration":"138.01678ms","start":"2026-03-06T22:13:25.073888Z","end":"2026-03-06T22:13:25.211905Z","steps":["trace[47568625] 'process raft request' (duration: 137.348945ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:13:39.196968Z","caller":"traceutil/trace.go:171","msg":"trace[1469065900] transaction","detail":"{read_only:false; response_revision:8070; number_of_response:1; }","duration":"211.856742ms","start":"2026-03-06T22:13:38.985083Z","end":"2026-03-06T22:13:39.19694Z","steps":["trace[1469065900] 'process raft request' (duration: 211.664968ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:17:09.74687Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30aa8136fb61a722","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T22:17:09.752768Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-06T22:17:09.752921Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-06T22:18:02.346868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7798} {"level":"info","ts":"2026-03-06T22:18:02.415696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7798,"took":"66.719275ms","hash":4150683865,"current-db-size-bytes":43040768,"current-db-size":"43 MB","current-db-size-in-use-bytes":26824704,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-06T22:18:02.415802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4150683865,"revision":7798,"compact-revision":5778} {"level":"warn","ts":"2026-03-06T22:18:21.610459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.376564ms","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-03-06T22:18:21.610599Z","caller":"traceutil/trace.go:171","msg":"trace[1267022463] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10422; }","duration":"160.540997ms","start":"2026-03-06T22:18:21.450032Z","end":"2026-03-06T22:18:21.610573Z","steps":["trace[1267022463] 'range keys from in-memory index tree' (duration: 160.292381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:18:21.610661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.344373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"warn","ts":"2026-03-06T22:18:21.610786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.243034ms","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-03-06T22:18:21.610786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.726021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:18:21.61084Z","caller":"traceutil/trace.go:171","msg":"trace[689529832] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10422; }","duration":"161.357826ms","start":"2026-03-06T22:18:21.449467Z","end":"2026-03-06T22:18:21.610825Z","steps":["trace[689529832] 'range keys from in-memory index tree' (duration: 161.102481ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:18:21.610873Z","caller":"traceutil/trace.go:171","msg":"trace[1315356017] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10422; }","duration":"160.865385ms","start":"2026-03-06T22:18:21.449985Z","end":"2026-03-06T22:18:21.61085Z","steps":["trace[1315356017] 'range keys from in-memory index tree' (duration: 160.618889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:18:21.610873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.789363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:18:21.610936Z","caller":"traceutil/trace.go:171","msg":"trace[1150336261] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10422; }","duration":"160.863904ms","start":"2026-03-06T22:18:21.450058Z","end":"2026-03-06T22:18:21.610922Z","steps":["trace[1150336261] 'range keys from in-memory index tree' (duration: 160.726121ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:18:21.610776Z","caller":"traceutil/trace.go:171","msg":"trace[1942888627] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10422; }","duration":"155.482466ms","start":"2026-03-06T22:18:21.455264Z","end":"2026-03-06T22:18:21.610747Z","steps":["trace[1942888627] 'range keys from in-memory index tree' (duration: 155.133349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:18:44.7314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.716642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T22:18:44.731553Z","caller":"traceutil/trace.go:171","msg":"trace[1653231316] transaction","detail":"{read_only:false; response_revision:10540; number_of_response:1; }","duration":"149.40575ms","start":"2026-03-06T22:18:44.582119Z","end":"2026-03-06T22:18:44.731524Z","steps":["trace[1653231316] 'process raft request' (duration: 44.440442ms)","trace[1653231316] 'compare' (duration: 104.548039ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:23:02.354017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10142} {"level":"info","ts":"2026-03-06T22:23:02.43339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10142,"took":"76.667929ms","hash":59893047,"current-db-size-bytes":43040768,"current-db-size":"43 MB","current-db-size-in-use-bytes":24047616,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-06T22:23:02.433464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":59893047,"revision":10142,"compact-revision":7798} {"level":"info","ts":"2026-03-06T22:24:29.767695Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000336f.wal"} {"level":"info","ts":"2026-03-06T22:25:18.41066Z","caller":"traceutil/trace.go:171","msg":"trace[1202198480] transaction","detail":"{read_only:false; response_revision:12978; number_of_response:1; }","duration":"111.58265ms","start":"2026-03-06T22:25:18.299035Z","end":"2026-03-06T22:25:18.410617Z","steps":["trace[1202198480] 'process raft request' (duration: 90.744264ms)","trace[1202198480] 'compare' (duration: 20.563899ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:25:19.183314Z","caller":"traceutil/trace.go:171","msg":"trace[781441902] transaction","detail":"{read_only:false; response_revision:12982; number_of_response:1; }","duration":"123.890693ms","start":"2026-03-06T22:25:19.059374Z","end":"2026-03-06T22:25:19.183265Z","steps":["trace[781441902] 'process raft request' (duration: 123.578335ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:26:36.51989Z","caller":"traceutil/trace.go:171","msg":"trace[1970352049] transaction","detail":"{read_only:false; response_revision:14031; number_of_response:1; }","duration":"127.064245ms","start":"2026-03-06T22:26:36.392783Z","end":"2026-03-06T22:26:36.519848Z","steps":["trace[1970352049] 'process raft request' (duration: 126.733227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:26:56.253158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.994314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bf2lz\" ","response":"range_response_count:1 size:22009"} {"level":"info","ts":"2026-03-06T22:26:56.253251Z","caller":"traceutil/trace.go:171","msg":"trace[696873197] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bf2lz; range_end:; response_count:1; response_revision:14125; }","duration":"110.104937ms","start":"2026-03-06T22:26:56.143127Z","end":"2026-03-06T22:26:56.253232Z","steps":["trace[696873197] 'range keys from in-memory index tree' (duration: 109.8147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:26:56.253549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.722458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-03-06T22:26:56.253591Z","caller":"traceutil/trace.go:171","msg":"trace[1002506403] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14125; }","duration":"126.80043ms","start":"2026-03-06T22:26:56.126781Z","end":"2026-03-06T22:26:56.253581Z","steps":["trace[1002506403] 'range keys from in-memory index tree' (duration: 126.619135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:26:56.253766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.970614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:26:56.253801Z","caller":"traceutil/trace.go:171","msg":"trace[1111628782] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14125; }","duration":"127.041475ms","start":"2026-03-06T22:26:56.126751Z","end":"2026-03-06T22:26:56.253792Z","steps":["trace[1111628782] 'range keys from in-memory index tree' (duration: 126.869332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:26:56.254007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.013044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-03-06T22:26:56.254042Z","caller":"traceutil/trace.go:171","msg":"trace[1513069755] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14125; }","duration":"127.058466ms","start":"2026-03-06T22:26:56.126975Z","end":"2026-03-06T22:26:56.254034Z","steps":["trace[1513069755] 'range keys from in-memory index tree' (duration: 126.860281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:26:56.254874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.920756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bf2lz\" ","response":"range_response_count:1 size:22009"} {"level":"info","ts":"2026-03-06T22:26:56.254918Z","caller":"traceutil/trace.go:171","msg":"trace[1541136912] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bf2lz; range_end:; response_count:1; response_revision:14125; }","duration":"110.985718ms","start":"2026-03-06T22:26:56.14392Z","end":"2026-03-06T22:26:56.254906Z","steps":["trace[1541136912] 'range keys from in-memory index tree' (duration: 110.832764ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:26:56.353203Z","caller":"traceutil/trace.go:171","msg":"trace[234146581] transaction","detail":"{read_only:false; response_revision:14126; number_of_response:1; }","duration":"204.788491ms","start":"2026-03-06T22:26:56.148366Z","end":"2026-03-06T22:26:56.353155Z","steps":["trace[234146581] 'process raft request' (duration: 203.775428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:26:56.537939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.641004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:26:56.538054Z","caller":"traceutil/trace.go:171","msg":"trace[675542801] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14126; }","duration":"226.791898ms","start":"2026-03-06T22:26:56.311239Z","end":"2026-03-06T22:26:56.53803Z","steps":["trace[675542801] 'agreement among raft nodes before linearized reading' (duration: 42.245733ms)","trace[675542801] 'range keys from in-memory index tree' (duration: 184.382832ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:26:56.539397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.462062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764260"} {"level":"info","ts":"2026-03-06T22:26:56.539472Z","caller":"traceutil/trace.go:171","msg":"trace[2088838951] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:14126; }","duration":"278.553794ms","start":"2026-03-06T22:26:56.260898Z","end":"2026-03-06T22:26:56.539452Z","steps":["trace[2088838951] 'agreement among raft nodes before linearized reading' (duration: 92.415401ms)","trace[2088838951] 'range keys from in-memory index tree' (duration: 185.203671ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:26:56.539983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.147033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-03-06T22:26:56.540032Z","caller":"traceutil/trace.go:171","msg":"trace[1676374305] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14126; }","duration":"206.217495ms","start":"2026-03-06T22:26:56.333799Z","end":"2026-03-06T22:26:56.540016Z","steps":["trace[1676374305] 'agreement among raft nodes before linearized reading' (duration: 19.631822ms)","trace[1676374305] 'range keys from in-memory index tree' (duration: 186.46673ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:26:56.540882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.213188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764260"} {"level":"info","ts":"2026-03-06T22:26:56.540926Z","caller":"traceutil/trace.go:171","msg":"trace[629008543] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:14126; }","duration":"276.274791ms","start":"2026-03-06T22:26:56.264639Z","end":"2026-03-06T22:26:56.540913Z","steps":["trace[629008543] 'agreement among raft nodes before linearized reading' (duration: 88.826087ms)","trace[629008543] 'range keys from in-memory index tree' (duration: 186.792278ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:26:56.541327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.769797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:26:56.541383Z","caller":"traceutil/trace.go:171","msg":"trace[1599732876] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14126; }","duration":"229.843009ms","start":"2026-03-06T22:26:56.311526Z","end":"2026-03-06T22:26:56.541369Z","steps":["trace[1599732876] 'agreement among raft nodes before linearized reading' (duration: 41.950116ms)","trace[1599732876] 'range keys from in-memory index tree' (duration: 187.825202ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:26:56.542384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.14442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T22:26:56.542532Z","caller":"traceutil/trace.go:171","msg":"trace[500929165] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14126; }","duration":"231.329995ms","start":"2026-03-06T22:26:56.311181Z","end":"2026-03-06T22:26:56.542511Z","steps":["trace[500929165] 'agreement among raft nodes before linearized reading' (duration: 42.312034ms)","trace[500929165] 'range keys from in-memory index tree' (duration: 188.799346ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:28:02.359803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12162} {"level":"info","ts":"2026-03-06T22:28:02.437223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12162,"took":"73.816847ms","hash":1481213954,"current-db-size-bytes":43040768,"current-db-size":"43 MB","current-db-size-in-use-bytes":27119616,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-06T22:28:02.437292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1481213954,"revision":12162,"compact-revision":10142} {"level":"info","ts":"2026-03-06T22:32:45.33168Z","caller":"traceutil/trace.go:171","msg":"trace[1919258842] transaction","detail":"{read_only:false; response_revision:16476; number_of_response:1; }","duration":"142.593288ms","start":"2026-03-06T22:32:45.189049Z","end":"2026-03-06T22:32:45.331642Z","steps":["trace[1919258842] 'process raft request' (duration: 142.272921ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:32:51.329194Z","caller":"traceutil/trace.go:171","msg":"trace[1199095550] transaction","detail":"{read_only:false; response_revision:16571; number_of_response:1; }","duration":"204.234338ms","start":"2026-03-06T22:32:51.124932Z","end":"2026-03-06T22:32:51.329166Z","steps":["trace[1199095550] 'process raft request' (duration: 204.075775ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:32:51.351185Z","caller":"traceutil/trace.go:171","msg":"trace[2016332330] transaction","detail":"{read_only:false; response_revision:16572; number_of_response:1; }","duration":"217.069873ms","start":"2026-03-06T22:32:51.134091Z","end":"2026-03-06T22:32:51.351161Z","steps":["trace[2016332330] 'process raft request' (duration: 216.93964ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:33:02.367389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14498} {"level":"info","ts":"2026-03-06T22:33:02.465748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14498,"took":"94.625731ms","hash":2427428021,"current-db-size-bytes":43880448,"current-db-size":"44 MB","current-db-size-in-use-bytes":29007872,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-06T22:33:02.46581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2427428021,"revision":14498,"compact-revision":12162} {"level":"info","ts":"2026-03-06T22:38:02.373826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16729} {"level":"info","ts":"2026-03-06T22:38:02.477849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16729,"took":"99.959118ms","hash":3109859311,"current-db-size-bytes":45600768,"current-db-size":"46 MB","current-db-size-in-use-bytes":30199808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-06T22:38:02.477916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3109859311,"revision":16729,"compact-revision":14498} {"level":"warn","ts":"2026-03-06T22:38:31.729126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.969123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-03-06T22:38:31.729223Z","caller":"traceutil/trace.go:171","msg":"trace[1783246218] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:18997; }","duration":"103.117387ms","start":"2026-03-06T22:38:31.626087Z","end":"2026-03-06T22:38:31.729204Z","steps":["trace[1783246218] 'range keys from in-memory index tree' (duration: 102.565934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:38:37.291328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.285935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:38:37.291453Z","caller":"traceutil/trace.go:171","msg":"trace[442482396] transaction","detail":"{read_only:false; response_revision:19025; number_of_response:1; }","duration":"347.71532ms","start":"2026-03-06T22:38:36.943718Z","end":"2026-03-06T22:38:37.291434Z","steps":["trace[442482396] 'process raft request' (duration: 197.2317ms)","trace[442482396] 'compare' (duration: 149.869236ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:38:37.29151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:38:36.943685Z","time spent":"347.799362ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:38:37.645537Z","caller":"traceutil/trace.go:171","msg":"trace[1333462305] transaction","detail":"{read_only:false; response_revision:19026; number_of_response:1; }","duration":"110.715128ms","start":"2026-03-06T22:38:37.534791Z","end":"2026-03-06T22:38:37.645506Z","steps":["trace[1333462305] 'process raft request' (duration: 110.494163ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:38:37.828438Z","caller":"traceutil/trace.go:171","msg":"trace[1197579976] linearizableReadLoop","detail":"{readStateIndex:19711; appliedIndex:19709; }","duration":"266.38727ms","start":"2026-03-06T22:38:37.562025Z","end":"2026-03-06T22:38:37.828412Z","steps":["trace[1197579976] 'read index received' (duration: 83.232185ms)","trace[1197579976] 'applied index is now lower than readState.Index' (duration: 183.153785ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:38:37.828485Z","caller":"traceutil/trace.go:171","msg":"trace[882959190] transaction","detail":"{read_only:false; response_revision:19027; number_of_response:1; }","duration":"289.518999ms","start":"2026-03-06T22:38:37.538931Z","end":"2026-03-06T22:38:37.82845Z","steps":["trace[882959190] 'process raft request' (duration: 289.311874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:38:37.828604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.563924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:38:37.828642Z","caller":"traceutil/trace.go:171","msg":"trace[834229012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19027; }","duration":"266.658986ms","start":"2026-03-06T22:38:37.561972Z","end":"2026-03-06T22:38:37.828631Z","steps":["trace[834229012] 'agreement among raft nodes before linearized reading' (duration: 266.574154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:38:37.828777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.865546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:38:37.82891Z","caller":"traceutil/trace.go:171","msg":"trace[511671139] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:19027; }","duration":"201.075761ms","start":"2026-03-06T22:38:37.627808Z","end":"2026-03-06T22:38:37.828884Z","steps":["trace[511671139] 'agreement among raft nodes before linearized reading' (duration: 200.841186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:38:37.828949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.886151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:38:37.829073Z","caller":"traceutil/trace.go:171","msg":"trace[1289456647] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:19027; }","duration":"189.049635ms","start":"2026-03-06T22:38:37.639999Z","end":"2026-03-06T22:38:37.829049Z","steps":["trace[1289456647] 'agreement among raft nodes before linearized reading' (duration: 188.802559ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:38:38.048816Z","caller":"traceutil/trace.go:171","msg":"trace[1404863564] transaction","detail":"{read_only:false; response_revision:19028; number_of_response:1; }","duration":"214.216783ms","start":"2026-03-06T22:38:37.834566Z","end":"2026-03-06T22:38:38.048783Z","steps":["trace[1404863564] 'process raft request' (duration: 124.409332ms)","trace[1404863564] 'compare' (duration: 89.548305ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:38:38.049153Z","caller":"traceutil/trace.go:171","msg":"trace[1585463444] transaction","detail":"{read_only:false; response_revision:19029; number_of_response:1; }","duration":"214.52954ms","start":"2026-03-06T22:38:37.834596Z","end":"2026-03-06T22:38:38.049126Z","steps":["trace[1585463444] 'process raft request' (duration: 214.141731ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:38:38.049356Z","caller":"traceutil/trace.go:171","msg":"trace[1176187387] transaction","detail":"{read_only:false; response_revision:19030; number_of_response:1; }","duration":"214.725525ms","start":"2026-03-06T22:38:37.834618Z","end":"2026-03-06T22:38:38.049344Z","steps":["trace[1176187387] 'process raft request' (duration: 214.420808ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:38:38.049365Z","caller":"traceutil/trace.go:171","msg":"trace[702066334] linearizableReadLoop","detail":"{readStateIndex:19714; appliedIndex:19711; }","duration":"177.290566ms","start":"2026-03-06T22:38:37.872051Z","end":"2026-03-06T22:38:38.049342Z","steps":["trace[702066334] 'read index received' (duration: 86.871301ms)","trace[702066334] 'applied index is now lower than readState.Index' (duration: 90.417505ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:38:38.049535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.467951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:38:38.049594Z","caller":"traceutil/trace.go:171","msg":"trace[1082008550] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:19030; }","duration":"177.538472ms","start":"2026-03-06T22:38:37.87203Z","end":"2026-03-06T22:38:38.049568Z","steps":["trace[1082008550] 'agreement among raft nodes before linearized reading' (duration: 177.41424ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:39:12.084827Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30aa8136fb61a722","local-member-applied-index":20010,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T22:39:12.091051Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20010} {"level":"info","ts":"2026-03-06T22:39:12.091275Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15010} {"level":"warn","ts":"2026-03-06T22:42:36.631576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.374552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:623"} {"level":"info","ts":"2026-03-06T22:42:36.631741Z","caller":"traceutil/trace.go:171","msg":"trace[634361085] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:21337; }","duration":"136.591208ms","start":"2026-03-06T22:42:36.495127Z","end":"2026-03-06T22:42:36.631718Z","steps":["trace[634361085] 'range keys from in-memory index tree' (duration: 136.210909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:42:36.632638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.667385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-06T22:42:36.633095Z","caller":"traceutil/trace.go:171","msg":"trace[736430942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21337; }","duration":"285.28835ms","start":"2026-03-06T22:42:36.347784Z","end":"2026-03-06T22:42:36.633073Z","steps":["trace[736430942] 'range keys from in-memory index tree' (duration: 283.644381ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:42:36.777491Z","caller":"traceutil/trace.go:171","msg":"trace[531983388] transaction","detail":"{read_only:false; response_revision:21340; number_of_response:1; }","duration":"117.707063ms","start":"2026-03-06T22:42:36.659725Z","end":"2026-03-06T22:42:36.777432Z","steps":["trace[531983388] 'process raft request' (duration: 75.368066ms)","trace[531983388] 'compare' (duration: 42.137143ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:43:02.385351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18862} {"level":"info","ts":"2026-03-06T22:43:02.502614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18862,"took":"110.95436ms","hash":4106566030,"current-db-size-bytes":47923200,"current-db-size":"48 MB","current-db-size-in-use-bytes":36696064,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-03-06T22:43:02.502707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4106566030,"revision":18862,"compact-revision":16729} {"level":"info","ts":"2026-03-06T22:46:23.213284Z","caller":"traceutil/trace.go:171","msg":"trace[1463091571] linearizableReadLoop","detail":"{readStateIndex:24301; appliedIndex:24300; }","duration":"126.60814ms","start":"2026-03-06T22:46:23.086648Z","end":"2026-03-06T22:46:23.213256Z","steps":["trace[1463091571] 'read index received' (duration: 126.557629ms)","trace[1463091571] 'applied index is now lower than readState.Index' (duration: 48.961µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:23.213346Z","caller":"traceutil/trace.go:171","msg":"trace[942821814] transaction","detail":"{read_only:false; response_revision:23481; number_of_response:1; }","duration":"133.297629ms","start":"2026-03-06T22:46:23.080006Z","end":"2026-03-06T22:46:23.213303Z","steps":["trace[942821814] 'process raft request' (duration: 133.094904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:23.213424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.818176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:23.213454Z","caller":"traceutil/trace.go:171","msg":"trace[880594160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23481; }","duration":"126.893208ms","start":"2026-03-06T22:46:23.086553Z","end":"2026-03-06T22:46:23.213446Z","steps":["trace[880594160] 'agreement among raft nodes before linearized reading' (duration: 126.807006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:23.428264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.883685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:46:23.428417Z","caller":"traceutil/trace.go:171","msg":"trace[1142975938] linearizableReadLoop","detail":"{readStateIndex:24302; appliedIndex:24301; }","duration":"205.241439ms","start":"2026-03-06T22:46:23.223155Z","end":"2026-03-06T22:46:23.428396Z","steps":["trace[1142975938] 'read index received' (duration: 79.091848ms)","trace[1142975938] 'applied index is now lower than readState.Index' (duration: 126.147541ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:23.428538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.368812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:23.428572Z","caller":"traceutil/trace.go:171","msg":"trace[304852353] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:23482; }","duration":"205.443474ms","start":"2026-03-06T22:46:23.223115Z","end":"2026-03-06T22:46:23.428558Z","steps":["trace[304852353] 'agreement among raft nodes before linearized reading' (duration: 205.340291ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:23.428546Z","caller":"traceutil/trace.go:171","msg":"trace[803755286] transaction","detail":"{read_only:false; response_revision:23482; number_of_response:1; }","duration":"290.284809ms","start":"2026-03-06T22:46:23.138198Z","end":"2026-03-06T22:46:23.428483Z","steps":["trace[803755286] 'process raft request' (duration: 164.060157ms)","trace[803755286] 'compare' (duration: 125.611158ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:23.741693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.864624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:23.741787Z","caller":"traceutil/trace.go:171","msg":"trace[144210790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23482; }","duration":"181.012827ms","start":"2026-03-06T22:46:23.560752Z","end":"2026-03-06T22:46:23.741765Z","steps":["trace[144210790] 'range keys from in-memory index tree' (duration: 180.694239ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:23.761377Z","caller":"traceutil/trace.go:171","msg":"trace[1286504815] transaction","detail":"{read_only:false; response_revision:23483; number_of_response:1; }","duration":"165.760906ms","start":"2026-03-06T22:46:23.595584Z","end":"2026-03-06T22:46:23.761345Z","steps":["trace[1286504815] 'process raft request' (duration: 165.586092ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:24.259273Z","caller":"traceutil/trace.go:171","msg":"trace[1653475793] linearizableReadLoop","detail":"{readStateIndex:24305; appliedIndex:24304; }","duration":"173.377266ms","start":"2026-03-06T22:46:24.085868Z","end":"2026-03-06T22:46:24.259246Z","steps":["trace[1653475793] 'read index received' (duration: 173.151711ms)","trace[1653475793] 'applied index is now lower than readState.Index' (duration: 224.405µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:24.259407Z","caller":"traceutil/trace.go:171","msg":"trace[402000077] transaction","detail":"{read_only:false; response_revision:23485; number_of_response:1; }","duration":"269.17882ms","start":"2026-03-06T22:46:23.990218Z","end":"2026-03-06T22:46:24.259397Z","steps":["trace[402000077] 'process raft request' (duration: 268.822762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:24.259676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.822543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:24.259756Z","caller":"traceutil/trace.go:171","msg":"trace[478682818] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:23485; }","duration":"169.925245ms","start":"2026-03-06T22:46:24.089813Z","end":"2026-03-06T22:46:24.259738Z","steps":["trace[478682818] 'agreement among raft nodes before linearized reading' (duration: 169.778642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:24.259936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.051862ms","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-03-06T22:46:24.26127Z","caller":"traceutil/trace.go:171","msg":"trace[1042320053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23485; }","duration":"174.142934ms","start":"2026-03-06T22:46:24.085817Z","end":"2026-03-06T22:46:24.25996Z","steps":["trace[1042320053] 'agreement among raft nodes before linearized reading' (duration: 174.009051ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:24.435655Z","caller":"traceutil/trace.go:171","msg":"trace[477441711] transaction","detail":"{read_only:false; response_revision:23487; number_of_response:1; }","duration":"168.414161ms","start":"2026-03-06T22:46:24.267205Z","end":"2026-03-06T22:46:24.435619Z","steps":["trace[477441711] 'process raft request' (duration: 154.289317ms)","trace[477441711] 'compare' (duration: 13.391506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:24.744901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.208962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:24.745033Z","caller":"traceutil/trace.go:171","msg":"trace[1313046769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23488; }","duration":"181.389326ms","start":"2026-03-06T22:46:24.563613Z","end":"2026-03-06T22:46:24.745002Z","steps":["trace[1313046769] 'range keys from in-memory index tree' (duration: 181.103149ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:24.859096Z","caller":"traceutil/trace.go:171","msg":"trace[913742975] linearizableReadLoop","detail":"{readStateIndex:24309; appliedIndex:24308; }","duration":"112.750055ms","start":"2026-03-06T22:46:24.746315Z","end":"2026-03-06T22:46:24.859065Z","steps":["trace[913742975] 'read index received' (duration: 112.572951ms)","trace[913742975] 'applied index is now lower than readState.Index' (duration: 175.684µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:24.859132Z","caller":"traceutil/trace.go:171","msg":"trace[1356743892] transaction","detail":"{read_only:false; response_revision:23489; number_of_response:1; }","duration":"116.656197ms","start":"2026-03-06T22:46:24.742443Z","end":"2026-03-06T22:46:24.859099Z","steps":["trace[1356743892] 'process raft request' (duration: 116.415101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:24.85929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.956259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:24.859348Z","caller":"traceutil/trace.go:171","msg":"trace[1911428410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23489; }","duration":"113.065922ms","start":"2026-03-06T22:46:24.74626Z","end":"2026-03-06T22:46:24.859326Z","steps":["trace[1911428410] 'agreement among raft nodes before linearized reading' (duration: 112.950619ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:25.143539Z","caller":"traceutil/trace.go:171","msg":"trace[1700305859] linearizableReadLoop","detail":"{readStateIndex:24310; appliedIndex:24309; }","duration":"284.328807ms","start":"2026-03-06T22:46:24.85918Z","end":"2026-03-06T22:46:25.143509Z","steps":["trace[1700305859] 'read index received' (duration: 284.238995ms)","trace[1700305859] 'applied index is now lower than readState.Index' (duration: 88.482µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:25.143722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.843626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:46:25.143782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-06T22:46:25.143829Z","caller":"traceutil/trace.go:171","msg":"trace[1348091450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23490; }","duration":"386.982133ms","start":"2026-03-06T22:46:24.756834Z","end":"2026-03-06T22:46:25.143816Z","steps":["trace[1348091450] 'agreement among raft nodes before linearized reading' (duration: 386.808929ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:25.143838Z","caller":"traceutil/trace.go:171","msg":"trace[1077914701] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:23490; }","duration":"306.01805ms","start":"2026-03-06T22:46:24.837795Z","end":"2026-03-06T22:46:25.143813Z","steps":["trace[1077914701] 'agreement among raft nodes before linearized reading' (duration: 305.783864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:25.143866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:24.756816Z","time spent":"387.042354ms","remote":"127.0.0.1:37398","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":"info","ts":"2026-03-06T22:46:25.143816Z","caller":"traceutil/trace.go:171","msg":"trace[1374228889] transaction","detail":"{read_only:false; response_revision:23490; number_of_response:1; }","duration":"334.81563ms","start":"2026-03-06T22:46:24.808945Z","end":"2026-03-06T22:46:25.143761Z","steps":["trace[1374228889] 'process raft request' (duration: 334.40012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:25.143893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:24.83777Z","time spent":"306.112613ms","remote":"127.0.0.1:49308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:46:25.143864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.30485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-06T22:46:25.143974Z","caller":"traceutil/trace.go:171","msg":"trace[1336849181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23490; }","duration":"329.471084ms","start":"2026-03-06T22:46:24.814475Z","end":"2026-03-06T22:46:25.143946Z","steps":["trace[1336849181] 'agreement among raft nodes before linearized reading' (duration: 329.134476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:25.143966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.015644ms","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-03-06T22:46:25.144053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:24.808913Z","time spent":"335.012575ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:25.144056Z","caller":"traceutil/trace.go:171","msg":"trace[1269933488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23490; }","duration":"387.127337ms","start":"2026-03-06T22:46:24.7569Z","end":"2026-03-06T22:46:25.144027Z","steps":["trace[1269933488] 'agreement among raft nodes before linearized reading' (duration: 386.749208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:25.144036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:24.814459Z","time spent":"329.561246ms","remote":"127.0.0.1:37398","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":"warn","ts":"2026-03-06T22:46:25.144129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:24.756894Z","time spent":"387.223249ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T22:46:25.166229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.373485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:25.166286Z","caller":"traceutil/trace.go:171","msg":"trace[1250091699] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:23491; }","duration":"126.472127ms","start":"2026-03-06T22:46:25.039799Z","end":"2026-03-06T22:46:25.166271Z","steps":["trace[1250091699] 'agreement among raft nodes before linearized reading' (duration: 126.331204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:25.735849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.596967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-03-06T22:46:25.735989Z","caller":"traceutil/trace.go:171","msg":"trace[2125354987] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:23495; }","duration":"191.803772ms","start":"2026-03-06T22:46:25.544148Z","end":"2026-03-06T22:46:25.735952Z","steps":["trace[2125354987] 'range keys from in-memory index tree' (duration: 191.317451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:25.735885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.916591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-06T22:46:25.736289Z","caller":"traceutil/trace.go:171","msg":"trace[289002556] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23495; }","duration":"144.190227ms","start":"2026-03-06T22:46:25.591896Z","end":"2026-03-06T22:46:25.736087Z","steps":["trace[289002556] 'range keys from in-memory index tree' (duration: 143.741807ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:26.042284Z","caller":"traceutil/trace.go:171","msg":"trace[791771661] transaction","detail":"{read_only:false; response_revision:23497; number_of_response:1; }","duration":"275.1223ms","start":"2026-03-06T22:46:25.767127Z","end":"2026-03-06T22:46:26.042249Z","steps":["trace[791771661] 'process raft request' (duration: 266.65527ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:26.054106Z","caller":"traceutil/trace.go:171","msg":"trace[221708634] linearizableReadLoop","detail":"{readStateIndex:24319; appliedIndex:24317; }","duration":"184.851587ms","start":"2026-03-06T22:46:25.869215Z","end":"2026-03-06T22:46:26.054067Z","steps":["trace[221708634] 'read index received' (duration: 164.519017ms)","trace[221708634] 'applied index is now lower than readState.Index' (duration: 20.3313ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:26.054499Z","caller":"traceutil/trace.go:171","msg":"trace[204188913] transaction","detail":"{read_only:false; response_revision:23498; number_of_response:1; }","duration":"272.449317ms","start":"2026-03-06T22:46:25.78202Z","end":"2026-03-06T22:46:26.05447Z","steps":["trace[204188913] 'process raft request' (duration: 271.868073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:26.054594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.35191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:26.054666Z","caller":"traceutil/trace.go:171","msg":"trace[1876311099] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23498; }","duration":"185.492143ms","start":"2026-03-06T22:46:25.869153Z","end":"2026-03-06T22:46:26.054645Z","steps":["trace[1876311099] 'agreement among raft nodes before linearized reading' (duration: 185.239817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:26.054663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.529032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:26.054754Z","caller":"traceutil/trace.go:171","msg":"trace[1772704105] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:23498; }","duration":"168.689607ms","start":"2026-03-06T22:46:25.886039Z","end":"2026-03-06T22:46:26.054729Z","steps":["trace[1772704105] 'agreement among raft nodes before linearized reading' (duration: 168.496751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:26.7137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.910769ms","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-03-06T22:46:26.713821Z","caller":"traceutil/trace.go:171","msg":"trace[1593989666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23500; }","duration":"273.084573ms","start":"2026-03-06T22:46:26.440704Z","end":"2026-03-06T22:46:26.713788Z","steps":["trace[1593989666] 'range keys from in-memory index tree' (duration: 272.707354ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:26.713875Z","caller":"traceutil/trace.go:171","msg":"trace[2143390326] linearizableReadLoop","detail":"{readStateIndex:24322; appliedIndex:24321; }","duration":"151.417467ms","start":"2026-03-06T22:46:26.562428Z","end":"2026-03-06T22:46:26.713845Z","steps":["trace[2143390326] 'read index received' (duration: 67.563546ms)","trace[2143390326] 'applied index is now lower than readState.Index' (duration: 83.852531ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:26.714082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.643503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:26.714115Z","caller":"traceutil/trace.go:171","msg":"trace[2139060086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23501; }","duration":"151.738725ms","start":"2026-03-06T22:46:26.562365Z","end":"2026-03-06T22:46:26.714103Z","steps":["trace[2139060086] 'agreement among raft nodes before linearized reading' (duration: 151.586821ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:26.714455Z","caller":"traceutil/trace.go:171","msg":"trace[1612550819] transaction","detail":"{read_only:false; response_revision:23501; number_of_response:1; }","duration":"271.982596ms","start":"2026-03-06T22:46:26.442451Z","end":"2026-03-06T22:46:26.714433Z","steps":["trace[1612550819] 'process raft request' (duration: 187.562831ms)","trace[1612550819] 'compare' (duration: 83.677337ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:27.018825Z","caller":"traceutil/trace.go:171","msg":"trace[170025369] transaction","detail":"{read_only:false; response_revision:23502; number_of_response:1; }","duration":"301.226438ms","start":"2026-03-06T22:46:26.71757Z","end":"2026-03-06T22:46:27.018796Z","steps":["trace[170025369] 'process raft request' (duration: 301.023003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:26.717556Z","time spent":"301.354351ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:27.327625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.785676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:46:27.327879Z","caller":"traceutil/trace.go:171","msg":"trace[33696643] transaction","detail":"{read_only:false; response_revision:23504; number_of_response:1; }","duration":"176.533271ms","start":"2026-03-06T22:46:27.151326Z","end":"2026-03-06T22:46:27.327859Z","steps":["trace[33696643] 'process raft request' (duration: 176.456249ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:27.327854Z","caller":"traceutil/trace.go:171","msg":"trace[1010646831] transaction","detail":"{read_only:false; response_revision:23503; number_of_response:1; }","duration":"460.88131ms","start":"2026-03-06T22:46:26.866932Z","end":"2026-03-06T22:46:27.327814Z","steps":["trace[1010646831] 'process raft request' (duration: 264.775397ms)","trace[1010646831] 'compare' (duration: 195.578101ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:27.32794Z","caller":"traceutil/trace.go:171","msg":"trace[554968634] linearizableReadLoop","detail":"{readStateIndex:24324; appliedIndex:24322; }","duration":"438.11087ms","start":"2026-03-06T22:46:26.889766Z","end":"2026-03-06T22:46:27.327877Z","steps":["trace[554968634] 'read index received' (duration: 128.960287ms)","trace[554968634] 'applied index is now lower than readState.Index' (duration: 309.144583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:27.328077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:26.866897Z","time spent":"461.069995ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:27.328186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.403948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:27.328246Z","caller":"traceutil/trace.go:171","msg":"trace[142365220] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:23504; }","duration":"438.4949ms","start":"2026-03-06T22:46:26.889733Z","end":"2026-03-06T22:46:27.328227Z","steps":["trace[142365220] 'agreement among raft nodes before linearized reading' (duration: 438.337176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.328306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:26.889669Z","time spent":"438.620963ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:46:27.338821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.243606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:27.338917Z","caller":"traceutil/trace.go:171","msg":"trace[1647501248] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:23505; }","duration":"378.39154ms","start":"2026-03-06T22:46:26.960502Z","end":"2026-03-06T22:46:27.338893Z","steps":["trace[1647501248] 'agreement among raft nodes before linearized reading' (duration: 378.210135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.339083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:26.960478Z","time spent":"378.588785ms","remote":"127.0.0.1:40904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:46:27.339349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.737416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-06T22:46:27.339412Z","caller":"traceutil/trace.go:171","msg":"trace[665230133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23505; }","duration":"154.828018ms","start":"2026-03-06T22:46:27.184564Z","end":"2026-03-06T22:46:27.339392Z","steps":["trace[665230133] 'agreement among raft nodes before linearized reading' (duration: 154.694885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.339657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.632397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-06T22:46:27.339709Z","caller":"traceutil/trace.go:171","msg":"trace[1323900454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23505; }","duration":"155.727119ms","start":"2026-03-06T22:46:27.183967Z","end":"2026-03-06T22:46:27.339694Z","steps":["trace[1323900454] 'agreement among raft nodes before linearized reading' (duration: 155.502104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.339811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.426651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:46:27.338933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.418609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-06T22:46:27.339862Z","caller":"traceutil/trace.go:171","msg":"trace[157270869] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:23505; }","duration":"298.516983ms","start":"2026-03-06T22:46:27.04133Z","end":"2026-03-06T22:46:27.339847Z","steps":["trace[157270869] 'agreement among raft nodes before linearized reading' (duration: 298.439451ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:27.340147Z","caller":"traceutil/trace.go:171","msg":"trace[1888223583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23505; }","duration":"155.747039ms","start":"2026-03-06T22:46:27.184375Z","end":"2026-03-06T22:46:27.340122Z","steps":["trace[1888223583] 'agreement among raft nodes before linearized reading' (duration: 154.359837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.340263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.297323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:46:27.34031Z","caller":"traceutil/trace.go:171","msg":"trace[2033448986] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:23505; }","duration":"294.367665ms","start":"2026-03-06T22:46:27.04593Z","end":"2026-03-06T22:46:27.340298Z","steps":["trace[2033448986] 'agreement among raft nodes before linearized reading' (duration: 294.002627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.339913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.451474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:27.340688Z","caller":"traceutil/trace.go:171","msg":"trace[1055877584] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:23505; }","duration":"250.282774ms","start":"2026-03-06T22:46:27.09039Z","end":"2026-03-06T22:46:27.340673Z","steps":["trace[1055877584] 'agreement among raft nodes before linearized reading' (duration: 249.494875ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:27.625829Z","caller":"traceutil/trace.go:171","msg":"trace[1459394776] linearizableReadLoop","detail":"{readStateIndex:24329; appliedIndex:24327; }","duration":"194.463804ms","start":"2026-03-06T22:46:27.431335Z","end":"2026-03-06T22:46:27.625799Z","steps":["trace[1459394776] 'read index received' (duration: 193.997613ms)","trace[1459394776] 'applied index is now lower than readState.Index' (duration: 464.631µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:27.625895Z","caller":"traceutil/trace.go:171","msg":"trace[2119961710] transaction","detail":"{read_only:false; response_revision:23508; number_of_response:1; }","duration":"265.929513ms","start":"2026-03-06T22:46:27.359924Z","end":"2026-03-06T22:46:27.625854Z","steps":["trace[2119961710] 'process raft request' (duration: 265.77454ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:27.625905Z","caller":"traceutil/trace.go:171","msg":"trace[716370948] transaction","detail":"{read_only:false; response_revision:23507; number_of_response:1; }","duration":"275.157761ms","start":"2026-03-06T22:46:27.350684Z","end":"2026-03-06T22:46:27.625841Z","steps":["trace[716370948] 'process raft request' (duration: 274.640299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.626161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.793601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:27.626246Z","caller":"traceutil/trace.go:171","msg":"trace[1228869506] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:23508; }","duration":"194.941985ms","start":"2026-03-06T22:46:27.431287Z","end":"2026-03-06T22:46:27.626229Z","steps":["trace[1228869506] 'agreement among raft nodes before linearized reading' (duration: 194.74131ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:27.71336Z","caller":"traceutil/trace.go:171","msg":"trace[562451466] transaction","detail":"{read_only:false; response_revision:23509; number_of_response:1; }","duration":"267.568392ms","start":"2026-03-06T22:46:27.445757Z","end":"2026-03-06T22:46:27.713325Z","steps":["trace[562451466] 'process raft request' (duration: 264.504509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:27.713548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.923063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:27.713621Z","caller":"traceutil/trace.go:171","msg":"trace[937330451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23509; }","duration":"150.094866ms","start":"2026-03-06T22:46:27.563509Z","end":"2026-03-06T22:46:27.713604Z","steps":["trace[937330451] 'agreement among raft nodes before linearized reading' (duration: 149.959213ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:28.420925Z","caller":"traceutil/trace.go:171","msg":"trace[741491901] transaction","detail":"{read_only:false; response_revision:23512; number_of_response:1; }","duration":"359.884762ms","start":"2026-03-06T22:46:28.061008Z","end":"2026-03-06T22:46:28.420892Z","steps":["trace[741491901] 'process raft request' (duration: 332.543246ms)","trace[741491901] 'compare' (duration: 27.140232ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:28.421089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:28.06097Z","time spent":"360.031816ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:28.42092Z","caller":"traceutil/trace.go:171","msg":"trace[1416745992] linearizableReadLoop","detail":"{readStateIndex:24333; appliedIndex:24332; }","duration":"242.447078ms","start":"2026-03-06T22:46:28.178428Z","end":"2026-03-06T22:46:28.420875Z","steps":["trace[1416745992] 'read index received' (duration: 215.135212ms)","trace[1416745992] 'applied index is now lower than readState.Index' (duration: 27.310386ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:28.421156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.712984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-766884665f-chrw8.189a620549b4e687\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-03-06T22:46:28.421221Z","caller":"traceutil/trace.go:171","msg":"trace[848241125] range","detail":"{range_begin:/registry/events/openstack/magnum-api-766884665f-chrw8.189a620549b4e687; range_end:; response_count:1; response_revision:23512; }","duration":"242.834957ms","start":"2026-03-06T22:46:28.178369Z","end":"2026-03-06T22:46:28.421203Z","steps":["trace[848241125] 'agreement among raft nodes before linearized reading' (duration: 242.617722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:28.424238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.194473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-03-06T22:46:28.424308Z","caller":"traceutil/trace.go:171","msg":"trace[2044088595] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23513; }","duration":"110.304136ms","start":"2026-03-06T22:46:28.313986Z","end":"2026-03-06T22:46:28.42429Z","steps":["trace[2044088595] 'agreement among raft nodes before linearized reading' (duration: 110.0562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:28.819087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.151457ms","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-03-06T22:46:28.819229Z","caller":"traceutil/trace.go:171","msg":"trace[2005848115] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23513; }","duration":"216.322351ms","start":"2026-03-06T22:46:28.602879Z","end":"2026-03-06T22:46:28.819202Z","steps":["trace[2005848115] 'range keys from in-memory index tree' (duration: 216.122126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:28.819449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.940065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:46:28.819773Z","caller":"traceutil/trace.go:171","msg":"trace[798731845] transaction","detail":"{read_only:false; response_revision:23514; number_of_response:1; }","duration":"395.935335ms","start":"2026-03-06T22:46:28.423806Z","end":"2026-03-06T22:46:28.819742Z","steps":["trace[798731845] 'process raft request' (duration: 134.5899ms)","trace[798731845] 'compare' (duration: 260.620088ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:28.81982Z","caller":"traceutil/trace.go:171","msg":"trace[1336097214] transaction","detail":"{read_only:false; response_revision:23515; number_of_response:1; }","duration":"390.522038ms","start":"2026-03-06T22:46:28.429276Z","end":"2026-03-06T22:46:28.819798Z","steps":["trace[1336097214] 'process raft request' (duration: 390.300652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:28.819903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:28.429257Z","time spent":"390.605919ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:28.81994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:28.423784Z","time spent":"396.058858ms","remote":"127.0.0.1:37176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:28.819978Z","caller":"traceutil/trace.go:171","msg":"trace[2064791231] transaction","detail":"{read_only:false; response_revision:23516; number_of_response:1; }","duration":"389.865592ms","start":"2026-03-06T22:46:28.43009Z","end":"2026-03-06T22:46:28.819956Z","steps":["trace[2064791231] 'process raft request' (duration: 389.629177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:28.82011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:28.43008Z","time spent":"389.947764ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:28.820104Z","caller":"traceutil/trace.go:171","msg":"trace[209269439] linearizableReadLoop","detail":"{readStateIndex:24337; appliedIndex:24334; }","duration":"257.620026ms","start":"2026-03-06T22:46:28.562451Z","end":"2026-03-06T22:46:28.820071Z","steps":["trace[209269439] 'read index received' (duration: 113.305836ms)","trace[209269439] 'applied index is now lower than readState.Index' (duration: 144.31162ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:28.820286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.825762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:28.820321Z","caller":"traceutil/trace.go:171","msg":"trace[336970850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23516; }","duration":"257.922514ms","start":"2026-03-06T22:46:28.562387Z","end":"2026-03-06T22:46:28.820309Z","steps":["trace[336970850] 'agreement among raft nodes before linearized reading' (duration: 257.818121ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:28.824209Z","caller":"traceutil/trace.go:171","msg":"trace[933482234] transaction","detail":"{read_only:false; response_revision:23517; number_of_response:1; }","duration":"103.984867ms","start":"2026-03-06T22:46:28.720209Z","end":"2026-03-06T22:46:28.824194Z","steps":["trace[933482234] 'process raft request' (duration: 103.838164ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:29.372189Z","caller":"traceutil/trace.go:171","msg":"trace[81505231] transaction","detail":"{read_only:false; response_revision:23518; number_of_response:1; }","duration":"342.652816ms","start":"2026-03-06T22:46:29.029511Z","end":"2026-03-06T22:46:29.372164Z","steps":["trace[81505231] 'process raft request' (duration: 342.464262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:29.372349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:29.029487Z","time spent":"342.781189ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:29.729331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.920742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:46:29.729463Z","caller":"traceutil/trace.go:171","msg":"trace[1052887071] linearizableReadLoop","detail":"{readStateIndex:24342; appliedIndex:24341; }","duration":"251.41308ms","start":"2026-03-06T22:46:29.47803Z","end":"2026-03-06T22:46:29.729443Z","steps":["trace[1052887071] 'read index received' (duration: 101.185091ms)","trace[1052887071] 'applied index is now lower than readState.Index' (duration: 150.226349ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:29.729555Z","caller":"traceutil/trace.go:171","msg":"trace[826611223] transaction","detail":"{read_only:false; response_revision:23521; number_of_response:1; }","duration":"295.536872ms","start":"2026-03-06T22:46:29.434001Z","end":"2026-03-06T22:46:29.729538Z","steps":["trace[826611223] 'process raft request' (duration: 145.310544ms)","trace[826611223] 'compare' (duration: 149.780848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:29.729746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.680556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:29.729839Z","caller":"traceutil/trace.go:171","msg":"trace[75442295] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:23521; }","duration":"251.823348ms","start":"2026-03-06T22:46:29.477996Z","end":"2026-03-06T22:46:29.729819Z","steps":["trace[75442295] 'agreement among raft nodes before linearized reading' (duration: 251.632744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.019431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.526955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:46:30.01956Z","caller":"traceutil/trace.go:171","msg":"trace[1538626211] linearizableReadLoop","detail":"{readStateIndex:24343; appliedIndex:24342; }","duration":"290.048253ms","start":"2026-03-06T22:46:29.729495Z","end":"2026-03-06T22:46:30.019543Z","steps":["trace[1538626211] 'read index received' (duration: 123.282163ms)","trace[1538626211] 'applied index is now lower than readState.Index' (duration: 166.76449ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:30.019619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.643693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:30.019646Z","caller":"traceutil/trace.go:171","msg":"trace[1550441010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23522; }","duration":"457.716654ms","start":"2026-03-06T22:46:29.56192Z","end":"2026-03-06T22:46:30.019636Z","steps":["trace[1550441010] 'agreement among raft nodes before linearized reading' (duration: 457.662233ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:30.01963Z","caller":"traceutil/trace.go:171","msg":"trace[1724843678] transaction","detail":"{read_only:false; response_revision:23522; number_of_response:1; }","duration":"299.722662ms","start":"2026-03-06T22:46:29.719878Z","end":"2026-03-06T22:46:30.0196Z","steps":["trace[1724843678] 'process raft request' (duration: 132.89882ms)","trace[1724843678] 'compare' (duration: 166.32848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:30.01967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:29.561891Z","time spent":"457.775296ms","remote":"127.0.0.1:37110","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-03-06T22:46:30.019703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.486909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-06T22:46:30.019742Z","caller":"traceutil/trace.go:171","msg":"trace[1851983368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23522; }","duration":"388.583382ms","start":"2026-03-06T22:46:29.63115Z","end":"2026-03-06T22:46:30.019733Z","steps":["trace[1851983368] 'agreement among raft nodes before linearized reading' (duration: 388.411088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.019767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:29.631118Z","time spent":"388.643132ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T22:46:30.019769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.711055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-06T22:46:30.019875Z","caller":"traceutil/trace.go:171","msg":"trace[1575551991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23522; }","duration":"388.861148ms","start":"2026-03-06T22:46:29.630997Z","end":"2026-03-06T22:46:30.019859Z","steps":["trace[1575551991] 'agreement among raft nodes before linearized reading' (duration: 388.598822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.019913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:29.630984Z","time spent":"388.919469ms","remote":"127.0.0.1:37398","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-03-06T22:46:30.55683Z","caller":"traceutil/trace.go:171","msg":"trace[1546668294] transaction","detail":"{read_only:false; response_revision:23523; number_of_response:1; }","duration":"531.033737ms","start":"2026-03-06T22:46:30.025767Z","end":"2026-03-06T22:46:30.556801Z","steps":["trace[1546668294] 'process raft request' (duration: 530.811312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.557029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:30.025744Z","time spent":"531.180931ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:30.769414Z","caller":"traceutil/trace.go:171","msg":"trace[440369716] transaction","detail":"{read_only:false; response_revision:23525; number_of_response:1; }","duration":"659.142323ms","start":"2026-03-06T22:46:30.110253Z","end":"2026-03-06T22:46:30.769395Z","steps":["trace[440369716] 'process raft request' (duration: 659.051271ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:30.769414Z","caller":"traceutil/trace.go:171","msg":"trace[1521453655] transaction","detail":"{read_only:false; response_revision:23524; number_of_response:1; }","duration":"743.124637ms","start":"2026-03-06T22:46:30.026263Z","end":"2026-03-06T22:46:30.769387Z","steps":["trace[1521453655] 'process raft request' (duration: 674.198019ms)","trace[1521453655] 'compare' (duration: 68.721933ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:30.769578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:30.026234Z","time spent":"743.260231ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:30.769576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:30.110223Z","time spent":"659.268726ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:30.769741Z","caller":"traceutil/trace.go:171","msg":"trace[1860443752] transaction","detail":"{read_only:false; response_revision:23526; number_of_response:1; }","duration":"343.586277ms","start":"2026-03-06T22:46:30.426129Z","end":"2026-03-06T22:46:30.769715Z","steps":["trace[1860443752] 'process raft request' (duration: 343.227088ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:30.769896Z","caller":"traceutil/trace.go:171","msg":"trace[603703298] linearizableReadLoop","detail":"{readStateIndex:24348; appliedIndex:24344; }","duration":"285.656589ms","start":"2026-03-06T22:46:30.484224Z","end":"2026-03-06T22:46:30.76988Z","steps":["trace[603703298] 'read index received' (duration: 72.939454ms)","trace[603703298] 'applied index is now lower than readState.Index' (duration: 212.715715ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:30.769932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:30.426097Z","time spent":"343.719891ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:30.769984Z","caller":"traceutil/trace.go:171","msg":"trace[1814191843] transaction","detail":"{read_only:false; response_revision:23527; number_of_response:1; }","duration":"340.043954ms","start":"2026-03-06T22:46:30.42993Z","end":"2026-03-06T22:46:30.769974Z","steps":["trace[1814191843] 'process raft request' (duration: 339.691706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.770056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:30.429917Z","time spent":"340.095515ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:30.770305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.060218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-06T22:46:30.770436Z","caller":"traceutil/trace.go:171","msg":"trace[618828869] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23527; }","duration":"286.232303ms","start":"2026-03-06T22:46:30.484179Z","end":"2026-03-06T22:46:30.770411Z","steps":["trace[618828869] 'agreement among raft nodes before linearized reading' (duration: 285.889035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.888043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.09181ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-06T22:46:30.888195Z","caller":"traceutil/trace.go:171","msg":"trace[145788829] linearizableReadLoop","detail":"{readStateIndex:24349; appliedIndex:24348; }","duration":"118.235904ms","start":"2026-03-06T22:46:30.769931Z","end":"2026-03-06T22:46:30.888167Z","steps":["trace[145788829] 'read index received' (duration: 11.029091ms)","trace[145788829] 'applied index is now lower than readState.Index' (duration: 107.204513ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:30.888275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.297005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:30.888327Z","caller":"traceutil/trace.go:171","msg":"trace[1832849922] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23527; }","duration":"173.416907ms","start":"2026-03-06T22:46:30.714896Z","end":"2026-03-06T22:46:30.888313Z","steps":["trace[1832849922] 'agreement among raft nodes before linearized reading' (duration: 173.287614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.888335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.684239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-03-06T22:46:30.888304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.94498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:30.888372Z","caller":"traceutil/trace.go:171","msg":"trace[1055136989] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:23527; }","duration":"348.796901ms","start":"2026-03-06T22:46:30.539566Z","end":"2026-03-06T22:46:30.888363Z","steps":["trace[1055136989] 'agreement among raft nodes before linearized reading' (duration: 348.673309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.888398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:30.539537Z","time spent":"348.852372ms","remote":"127.0.0.1:37444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":22,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:46:30.888477Z","caller":"traceutil/trace.go:171","msg":"trace[341705727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23527; }","duration":"323.095293ms","start":"2026-03-06T22:46:30.565287Z","end":"2026-03-06T22:46:30.888383Z","steps":["trace[341705727] 'agreement among raft nodes before linearized reading' (duration: 322.905749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:30.888799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:30.565268Z","time spent":"323.515033ms","remote":"127.0.0.1:37110","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-03-06T22:46:30.891083Z","caller":"traceutil/trace.go:171","msg":"trace[2070716970] transaction","detail":"{read_only:false; response_revision:23528; number_of_response:1; }","duration":"116.141155ms","start":"2026-03-06T22:46:30.774926Z","end":"2026-03-06T22:46:30.891067Z","steps":["trace[2070716970] 'process raft request' (duration: 115.986001ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:35.331283Z","caller":"traceutil/trace.go:171","msg":"trace[785446746] transaction","detail":"{read_only:false; response_revision:23561; number_of_response:1; }","duration":"111.2824ms","start":"2026-03-06T22:46:35.21997Z","end":"2026-03-06T22:46:35.331252Z","steps":["trace[785446746] 'process raft request' (duration: 110.87509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:35.642509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.625343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-06T22:46:35.642598Z","caller":"traceutil/trace.go:171","msg":"trace[289878592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23561; }","duration":"231.794966ms","start":"2026-03-06T22:46:35.410784Z","end":"2026-03-06T22:46:35.642579Z","steps":["trace[289878592] 'range keys from in-memory index tree' (duration: 231.380646ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:35.642562Z","caller":"traceutil/trace.go:171","msg":"trace[1035940020] transaction","detail":"{read_only:false; response_revision:23562; number_of_response:1; }","duration":"218.176975ms","start":"2026-03-06T22:46:35.424338Z","end":"2026-03-06T22:46:35.642515Z","steps":["trace[1035940020] 'process raft request' (duration: 215.893511ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:36.2149Z","caller":"traceutil/trace.go:171","msg":"trace[688998899] linearizableReadLoop","detail":"{readStateIndex:24386; appliedIndex:24383; }","duration":"670.411479ms","start":"2026-03-06T22:46:35.544455Z","end":"2026-03-06T22:46:36.214867Z","steps":["trace[688998899] 'read index received' (duration: 95.791353ms)","trace[688998899] 'applied index is now lower than readState.Index' (duration: 574.619356ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:36.215002Z","caller":"traceutil/trace.go:171","msg":"trace[80253512] transaction","detail":"{read_only:false; response_revision:23563; number_of_response:1; }","duration":"789.963894ms","start":"2026-03-06T22:46:35.424985Z","end":"2026-03-06T22:46:36.214949Z","steps":["trace[80253512] 'process raft request' (duration: 722.020329ms)","trace[80253512] 'compare' (duration: 67.7011ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:36.215071Z","caller":"traceutil/trace.go:171","msg":"trace[1227351861] transaction","detail":"{read_only:false; response_revision:23564; number_of_response:1; }","duration":"710.450805ms","start":"2026-03-06T22:46:35.504568Z","end":"2026-03-06T22:46:36.215019Z","steps":["trace[1227351861] 'process raft request' (duration: 710.25649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:36.215144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.676075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-03-06T22:46:36.215171Z","caller":"traceutil/trace.go:171","msg":"trace[1267699906] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:23564; }","duration":"670.761877ms","start":"2026-03-06T22:46:35.544401Z","end":"2026-03-06T22:46:36.215163Z","steps":["trace[1267699906] 'agreement among raft nodes before linearized reading' (duration: 670.589403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:36.215198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:35.544381Z","time spent":"670.811249ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"warn","ts":"2026-03-06T22:46:36.215251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:35.424932Z","time spent":"790.184879ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:36.2153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:35.504533Z","time spent":"710.633569ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:36.217784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.997022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:36.217879Z","caller":"traceutil/trace.go:171","msg":"trace[1710982779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23566; }","duration":"654.149415ms","start":"2026-03-06T22:46:35.563706Z","end":"2026-03-06T22:46:36.217855Z","steps":["trace[1710982779] 'agreement among raft nodes before linearized reading' (duration: 653.970841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:36.217963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:35.563678Z","time spent":"654.272798ms","remote":"127.0.0.1:37110","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-03-06T22:46:36.218437Z","caller":"traceutil/trace.go:171","msg":"trace[1694971825] transaction","detail":"{read_only:false; response_revision:23565; number_of_response:1; }","duration":"570.54127ms","start":"2026-03-06T22:46:35.647875Z","end":"2026-03-06T22:46:36.218416Z","steps":["trace[1694971825] 'process raft request' (duration: 569.539256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:36.218582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:35.647855Z","time spent":"570.642783ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:36.21881Z","caller":"traceutil/trace.go:171","msg":"trace[697339754] transaction","detail":"{read_only:false; response_revision:23566; number_of_response:1; }","duration":"175.17223ms","start":"2026-03-06T22:46:36.043619Z","end":"2026-03-06T22:46:36.218791Z","steps":["trace[697339754] 'process raft request' (duration: 173.94892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:36.219031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.130554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-06T22:46:36.219114Z","caller":"traceutil/trace.go:171","msg":"trace[1566152808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23566; }","duration":"466.265507ms","start":"2026-03-06T22:46:35.752832Z","end":"2026-03-06T22:46:36.219097Z","steps":["trace[1566152808] 'agreement among raft nodes before linearized reading' (duration: 466.032181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:36.219181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:35.752802Z","time spent":"466.360039ms","remote":"127.0.0.1:37398","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-03-06T22:46:36.21939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.884504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:36.219471Z","caller":"traceutil/trace.go:171","msg":"trace[1959846502] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:23566; }","duration":"115.008627ms","start":"2026-03-06T22:46:36.104441Z","end":"2026-03-06T22:46:36.219449Z","steps":["trace[1959846502] 'agreement among raft nodes before linearized reading' (duration: 114.891474ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:36.396598Z","caller":"traceutil/trace.go:171","msg":"trace[1910091684] linearizableReadLoop","detail":"{readStateIndex:24391; appliedIndex:24390; }","duration":"164.059275ms","start":"2026-03-06T22:46:36.232504Z","end":"2026-03-06T22:46:36.396564Z","steps":["trace[1910091684] 'read index received' (duration: 99.785597ms)","trace[1910091684] 'applied index is now lower than readState.Index' (duration: 64.272078ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:36.397086Z","caller":"traceutil/trace.go:171","msg":"trace[1170192350] transaction","detail":"{read_only:false; response_revision:23568; number_of_response:1; }","duration":"171.988323ms","start":"2026-03-06T22:46:36.225074Z","end":"2026-03-06T22:46:36.397062Z","steps":["trace[1170192350] 'process raft request' (duration: 107.225243ms)","trace[1170192350] 'compare' (duration: 64.109805ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:36.397292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.343724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-06T22:46:36.397365Z","caller":"traceutil/trace.go:171","msg":"trace[56330110] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23568; }","duration":"173.470558ms","start":"2026-03-06T22:46:36.223881Z","end":"2026-03-06T22:46:36.397352Z","steps":["trace[56330110] 'agreement among raft nodes before linearized reading' (duration: 173.217652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:36.397404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.14693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-06T22:46:36.397514Z","caller":"traceutil/trace.go:171","msg":"trace[1635953408] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:23568; }","duration":"162.297905ms","start":"2026-03-06T22:46:36.23519Z","end":"2026-03-06T22:46:36.397488Z","steps":["trace[1635953408] 'agreement among raft nodes before linearized reading' (duration: 161.912095ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.027833Z","caller":"traceutil/trace.go:171","msg":"trace[1646800072] transaction","detail":"{read_only:false; response_revision:23698; number_of_response:1; }","duration":"195.437758ms","start":"2026-03-06T22:46:46.832361Z","end":"2026-03-06T22:46:47.027799Z","steps":["trace[1646800072] 'process raft request' (duration: 195.232713ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.167694Z","caller":"traceutil/trace.go:171","msg":"trace[1177444204] transaction","detail":"{read_only:false; response_revision:23699; number_of_response:1; }","duration":"314.635624ms","start":"2026-03-06T22:46:46.853019Z","end":"2026-03-06T22:46:47.167655Z","steps":["trace[1177444204] 'process raft request' (duration: 303.404409ms)","trace[1177444204] 'compare' (duration: 10.912937ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:47.167849Z","caller":"traceutil/trace.go:171","msg":"trace[2023784481] linearizableReadLoop","detail":"{readStateIndex:24526; appliedIndex:24524; }","duration":"297.55174ms","start":"2026-03-06T22:46:46.870261Z","end":"2026-03-06T22:46:47.167813Z","steps":["trace[2023784481] 'read index received' (duration: 157.620014ms)","trace[2023784481] 'applied index is now lower than readState.Index' (duration: 139.929216ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:47.167914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:46.852985Z","time spent":"314.810758ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:47.167988Z","caller":"traceutil/trace.go:171","msg":"trace[855976098] transaction","detail":"{read_only:false; response_revision:23701; number_of_response:1; }","duration":"213.456363ms","start":"2026-03-06T22:46:46.954518Z","end":"2026-03-06T22:46:47.167974Z","steps":["trace[855976098] 'process raft request' (duration: 213.072984ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.16769Z","caller":"traceutil/trace.go:171","msg":"trace[133790821] transaction","detail":"{read_only:false; response_revision:23700; number_of_response:1; }","duration":"312.74603ms","start":"2026-03-06T22:46:46.854905Z","end":"2026-03-06T22:46:47.167651Z","steps":["trace[133790821] 'process raft request' (duration: 312.610846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:47.168177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.89481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-03-06T22:46:47.168182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:46.854863Z","time spent":"313.18577ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:47.168243Z","caller":"traceutil/trace.go:171","msg":"trace[415434284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23701; }","duration":"180.012813ms","start":"2026-03-06T22:46:46.988214Z","end":"2026-03-06T22:46:47.168227Z","steps":["trace[415434284] 'agreement among raft nodes before linearized reading' (duration: 179.88741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:47.168783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.526583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-06T22:46:47.168899Z","caller":"traceutil/trace.go:171","msg":"trace[2042700409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23701; }","duration":"298.642656ms","start":"2026-03-06T22:46:46.870233Z","end":"2026-03-06T22:46:47.168876Z","steps":["trace[2042700409] 'agreement among raft nodes before linearized reading' (duration: 297.886527ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.347114Z","caller":"traceutil/trace.go:171","msg":"trace[1350890665] transaction","detail":"{read_only:false; response_revision:23702; number_of_response:1; }","duration":"173.488098ms","start":"2026-03-06T22:46:47.173575Z","end":"2026-03-06T22:46:47.347063Z","steps":["trace[1350890665] 'process raft request' (duration: 130.092654ms)","trace[1350890665] 'compare' (duration: 43.180419ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:47.433035Z","caller":"traceutil/trace.go:171","msg":"trace[1489545678] transaction","detail":"{read_only:false; response_revision:23703; number_of_response:1; }","duration":"259.368658ms","start":"2026-03-06T22:46:47.17363Z","end":"2026-03-06T22:46:47.432998Z","steps":["trace[1489545678] 'process raft request' (duration: 259.066871ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.486363Z","caller":"traceutil/trace.go:171","msg":"trace[1560157913] transaction","detail":"{read_only:false; response_revision:23704; number_of_response:1; }","duration":"110.772477ms","start":"2026-03-06T22:46:47.375561Z","end":"2026-03-06T22:46:47.486334Z","steps":["trace[1560157913] 'process raft request' (duration: 110.509131ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.672486Z","caller":"traceutil/trace.go:171","msg":"trace[548418450] transaction","detail":"{read_only:false; response_revision:23705; number_of_response:1; }","duration":"180.082934ms","start":"2026-03-06T22:46:47.492285Z","end":"2026-03-06T22:46:47.672368Z","steps":["trace[548418450] 'process raft request' (duration: 179.840698ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.812302Z","caller":"traceutil/trace.go:171","msg":"trace[2060534219] transaction","detail":"{read_only:false; response_revision:23706; number_of_response:1; }","duration":"122.72157ms","start":"2026-03-06T22:46:47.689566Z","end":"2026-03-06T22:46:47.812287Z","steps":["trace[2060534219] 'process raft request' (duration: 113.579474ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:47.81222Z","caller":"traceutil/trace.go:171","msg":"trace[1062614782] linearizableReadLoop","detail":"{readStateIndex:24532; appliedIndex:24531; }","duration":"102.658435ms","start":"2026-03-06T22:46:47.709533Z","end":"2026-03-06T22:46:47.812192Z","steps":["trace[1062614782] 'read index received' (duration: 93.577411ms)","trace[1062614782] 'applied index is now lower than readState.Index' (duration: 9.079284ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:47.813003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.422162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-778de066-bece-44f7-b8de-2b3ca4a473b0\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-03-06T22:46:47.813102Z","caller":"traceutil/trace.go:171","msg":"trace[1966051477] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-778de066-bece-44f7-b8de-2b3ca4a473b0; range_end:; response_count:1; response_revision:23706; }","duration":"103.606827ms","start":"2026-03-06T22:46:47.709473Z","end":"2026-03-06T22:46:47.81308Z","steps":["trace[1966051477] 'agreement among raft nodes before linearized reading' (duration: 103.064714ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:48.401567Z","caller":"traceutil/trace.go:171","msg":"trace[872942114] transaction","detail":"{read_only:false; response_revision:23708; number_of_response:1; }","duration":"135.804079ms","start":"2026-03-06T22:46:48.265724Z","end":"2026-03-06T22:46:48.401528Z","steps":["trace[872942114] 'process raft request' (duration: 94.571625ms)","trace[872942114] 'compare' (duration: 41.012649ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:48.646305Z","caller":"traceutil/trace.go:171","msg":"trace[1177752849] linearizableReadLoop","detail":"{readStateIndex:24535; appliedIndex:24533; }","duration":"361.795738ms","start":"2026-03-06T22:46:48.284476Z","end":"2026-03-06T22:46:48.646272Z","steps":["trace[1177752849] 'read index received' (duration: 75.905563ms)","trace[1177752849] 'applied index is now lower than readState.Index' (duration: 285.888955ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:48.646378Z","caller":"traceutil/trace.go:171","msg":"trace[815334052] transaction","detail":"{read_only:false; response_revision:23709; number_of_response:1; }","duration":"374.508659ms","start":"2026-03-06T22:46:48.271823Z","end":"2026-03-06T22:46:48.646332Z","steps":["trace[815334052] 'process raft request' (duration: 373.147776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:48.646601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:48.27181Z","time spent":"374.662792ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:48.646618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.129376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-06T22:46:48.646591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.867311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:48.646689Z","caller":"traceutil/trace.go:171","msg":"trace[602502576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23709; }","duration":"362.28455ms","start":"2026-03-06T22:46:48.284386Z","end":"2026-03-06T22:46:48.64667Z","steps":["trace[602502576] 'agreement among raft nodes before linearized reading' (duration: 362.031104ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:48.646722Z","caller":"traceutil/trace.go:171","msg":"trace[1074216290] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:23709; }","duration":"335.048995ms","start":"2026-03-06T22:46:48.311636Z","end":"2026-03-06T22:46:48.646685Z","steps":["trace[1074216290] 'agreement among raft nodes before linearized reading' (duration: 334.82931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:48.646739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:48.284367Z","time spent":"362.358911ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T22:46:48.646793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:48.311621Z","time spent":"335.154349ms","remote":"127.0.0.1:38678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:46:48.647699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.411049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-06T22:46:48.647759Z","caller":"traceutil/trace.go:171","msg":"trace[1798780592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23709; }","duration":"182.506622ms","start":"2026-03-06T22:46:48.465239Z","end":"2026-03-06T22:46:48.647746Z","steps":["trace[1798780592] 'agreement among raft nodes before linearized reading' (duration: 182.366019ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:50.91507Z","caller":"traceutil/trace.go:171","msg":"trace[180174584] linearizableReadLoop","detail":"{readStateIndex:24551; appliedIndex:24549; }","duration":"137.111299ms","start":"2026-03-06T22:46:50.777933Z","end":"2026-03-06T22:46:50.915044Z","steps":["trace[180174584] 'read index received' (duration: 87.829984ms)","trace[180174584] 'applied index is now lower than readState.Index' (duration: 49.280015ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:50.915159Z","caller":"traceutil/trace.go:171","msg":"trace[31778500] transaction","detail":"{read_only:false; response_revision:23724; number_of_response:1; }","duration":"179.217914ms","start":"2026-03-06T22:46:50.735872Z","end":"2026-03-06T22:46:50.91509Z","steps":["trace[31778500] 'process raft request' (duration: 129.96141ms)","trace[31778500] 'compare' (duration: 48.987268ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:50.915275Z","caller":"traceutil/trace.go:171","msg":"trace[431293731] transaction","detail":"{read_only:false; response_revision:23725; number_of_response:1; }","duration":"179.312317ms","start":"2026-03-06T22:46:50.735947Z","end":"2026-03-06T22:46:50.915259Z","steps":["trace[431293731] 'process raft request' (duration: 179.049061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:50.915369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.412147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:46:50.915827Z","caller":"traceutil/trace.go:171","msg":"trace[1256056816] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:23725; }","duration":"137.935189ms","start":"2026-03-06T22:46:50.777868Z","end":"2026-03-06T22:46:50.915803Z","steps":["trace[1256056816] 'agreement among raft nodes before linearized reading' (duration: 137.421227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:50.915817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.9742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-06T22:46:50.915942Z","caller":"traceutil/trace.go:171","msg":"trace[1479069004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23725; }","duration":"127.118094ms","start":"2026-03-06T22:46:50.788806Z","end":"2026-03-06T22:46:50.915924Z","steps":["trace[1479069004] 'agreement among raft nodes before linearized reading' (duration: 126.754285ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:55.344939Z","caller":"traceutil/trace.go:171","msg":"trace[1106193973] transaction","detail":"{read_only:false; response_revision:23776; number_of_response:1; }","duration":"150.67397ms","start":"2026-03-06T22:46:55.194229Z","end":"2026-03-06T22:46:55.344903Z","steps":["trace[1106193973] 'process raft request' (duration: 150.471995ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:55.632666Z","caller":"traceutil/trace.go:171","msg":"trace[154484190] linearizableReadLoop","detail":"{readStateIndex:24606; appliedIndex:24604; }","duration":"246.038474ms","start":"2026-03-06T22:46:55.38659Z","end":"2026-03-06T22:46:55.632629Z","steps":["trace[154484190] 'read index received' (duration: 214.705313ms)","trace[154484190] 'applied index is now lower than readState.Index' (duration: 31.331161ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:55.632975Z","caller":"traceutil/trace.go:171","msg":"trace[1512141527] transaction","detail":"{read_only:false; response_revision:23778; number_of_response:1; }","duration":"431.650358ms","start":"2026-03-06T22:46:55.201297Z","end":"2026-03-06T22:46:55.632948Z","steps":["trace[1512141527] 'process raft request' (duration: 431.126186ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:55.632968Z","caller":"traceutil/trace.go:171","msg":"trace[2136971946] transaction","detail":"{read_only:false; response_revision:23777; number_of_response:1; }","duration":"434.721871ms","start":"2026-03-06T22:46:55.198213Z","end":"2026-03-06T22:46:55.632935Z","steps":["trace[2136971946] 'process raft request' (duration: 403.019522ms)","trace[2136971946] 'compare' (duration: 30.90488ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:46:55.633175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.588006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-03-06T22:46:55.633151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:55.198188Z","time spent":"434.865114ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:46:55.633159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:46:55.201282Z","time spent":"431.763971ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:46:55.633208Z","caller":"traceutil/trace.go:171","msg":"trace[1247149033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23778; }","duration":"246.659338ms","start":"2026-03-06T22:46:55.386539Z","end":"2026-03-06T22:46:55.633198Z","steps":["trace[1247149033] 'agreement among raft nodes before linearized reading' (duration: 246.296379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:55.63317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.259378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-06T22:46:55.633291Z","caller":"traceutil/trace.go:171","msg":"trace[1681426043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23778; }","duration":"163.392261ms","start":"2026-03-06T22:46:55.469889Z","end":"2026-03-06T22:46:55.633281Z","steps":["trace[1681426043] 'agreement among raft nodes before linearized reading' (duration: 162.958961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:55.633258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.792055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-03-06T22:46:55.633368Z","caller":"traceutil/trace.go:171","msg":"trace[922598662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:23778; }","duration":"148.948739ms","start":"2026-03-06T22:46:55.48439Z","end":"2026-03-06T22:46:55.633339Z","steps":["trace[922598662] 'agreement among raft nodes before linearized reading' (duration: 148.737344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:46:55.633403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.077877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-03-06T22:46:55.633501Z","caller":"traceutil/trace.go:171","msg":"trace[1894146006] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23778; }","duration":"120.23166ms","start":"2026-03-06T22:46:55.513246Z","end":"2026-03-06T22:46:55.633478Z","steps":["trace[1894146006] 'agreement among raft nodes before linearized reading' (duration: 119.847181ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:55.797533Z","caller":"traceutil/trace.go:171","msg":"trace[446846817] transaction","detail":"{read_only:false; response_revision:23779; number_of_response:1; }","duration":"159.212562ms","start":"2026-03-06T22:46:55.638286Z","end":"2026-03-06T22:46:55.797498Z","steps":["trace[446846817] 'process raft request' (duration: 134.033407ms)","trace[446846817] 'compare' (duration: 24.97913ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:46:55.797833Z","caller":"traceutil/trace.go:171","msg":"trace[785155977] transaction","detail":"{read_only:false; response_revision:23780; number_of_response:1; }","duration":"158.807593ms","start":"2026-03-06T22:46:55.638981Z","end":"2026-03-06T22:46:55.797789Z","steps":["trace[785155977] 'process raft request' (duration: 158.473555ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:46:55.798042Z","caller":"traceutil/trace.go:171","msg":"trace[1804700685] transaction","detail":"{read_only:false; response_revision:23781; number_of_response:1; }","duration":"151.016208ms","start":"2026-03-06T22:46:55.647014Z","end":"2026-03-06T22:46:55.79803Z","steps":["trace[1804700685] 'process raft request' (duration: 150.702911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.415042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.861481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:47:15.415295Z","caller":"traceutil/trace.go:171","msg":"trace[1335933528] transaction","detail":"{read_only:false; response_revision:23933; number_of_response:1; }","duration":"372.653039ms","start":"2026-03-06T22:47:15.042595Z","end":"2026-03-06T22:47:15.415248Z","steps":["trace[1335933528] 'process raft request' (duration: 152.454029ms)","trace[1335933528] 'compare' (duration: 219.659996ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:15.415388Z","caller":"traceutil/trace.go:171","msg":"trace[189055067] linearizableReadLoop","detail":"{readStateIndex:24765; appliedIndex:24763; }","duration":"363.571675ms","start":"2026-03-06T22:47:15.051806Z","end":"2026-03-06T22:47:15.415378Z","steps":["trace[189055067] 'read index received' (duration: 25.107113ms)","trace[189055067] 'applied index is now lower than readState.Index' (duration: 338.463582ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:15.415622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:15.042587Z","time spent":"372.876415ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:47:15.415722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.907443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-03-06T22:47:15.415769Z","caller":"traceutil/trace.go:171","msg":"trace[455032108] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23934; }","duration":"363.984375ms","start":"2026-03-06T22:47:15.051772Z","end":"2026-03-06T22:47:15.415756Z","steps":["trace[455032108] 'agreement among raft nodes before linearized reading' (duration: 363.815761ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:15.415344Z","caller":"traceutil/trace.go:171","msg":"trace[1103105867] transaction","detail":"{read_only:false; response_revision:23934; number_of_response:1; }","duration":"160.471569ms","start":"2026-03-06T22:47:15.254849Z","end":"2026-03-06T22:47:15.415321Z","steps":["trace[1103105867] 'process raft request' (duration: 160.332786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.415815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:15.051764Z","time spent":"364.038026ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-03-06T22:47:15.416047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.310287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-06T22:47:15.416089Z","caller":"traceutil/trace.go:171","msg":"trace[351126520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23934; }","duration":"227.397289ms","start":"2026-03-06T22:47:15.18868Z","end":"2026-03-06T22:47:15.416078Z","steps":["trace[351126520] 'agreement among raft nodes before linearized reading' (duration: 227.280076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.706928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.061026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:47:15.707105Z","caller":"traceutil/trace.go:171","msg":"trace[1243337982] transaction","detail":"{read_only:false; response_revision:23935; number_of_response:1; }","duration":"286.280249ms","start":"2026-03-06T22:47:15.420798Z","end":"2026-03-06T22:47:15.707078Z","steps":["trace[1243337982] 'process raft request' (duration: 122.917621ms)","trace[1243337982] 'compare' (duration: 147.901933ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:15.772762Z","caller":"traceutil/trace.go:171","msg":"trace[857716034] linearizableReadLoop","detail":"{readStateIndex:24768; appliedIndex:24766; }","duration":"331.060337ms","start":"2026-03-06T22:47:15.441669Z","end":"2026-03-06T22:47:15.77273Z","steps":["trace[857716034] 'read index received' (duration: 101.926467ms)","trace[857716034] 'applied index is now lower than readState.Index' (duration: 229.13249ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:15.772846Z","caller":"traceutil/trace.go:171","msg":"trace[543919111] transaction","detail":"{read_only:false; response_revision:23936; number_of_response:1; }","duration":"351.192922ms","start":"2026-03-06T22:47:15.421593Z","end":"2026-03-06T22:47:15.772785Z","steps":["trace[543919111] 'process raft request' (duration: 350.927386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.773052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:15.42158Z","time spent":"351.361216ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:47:15.773071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.375704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-03-06T22:47:15.773135Z","caller":"traceutil/trace.go:171","msg":"trace[1887774189] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:23936; }","duration":"331.464827ms","start":"2026-03-06T22:47:15.44165Z","end":"2026-03-06T22:47:15.773115Z","steps":["trace[1887774189] 'agreement among raft nodes before linearized reading' (duration: 331.224801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.773186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:15.441632Z","time spent":"331.543278ms","remote":"127.0.0.1:37266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1885,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-03-06T22:47:15.888487Z","caller":"traceutil/trace.go:171","msg":"trace[1140948001] transaction","detail":"{read_only:false; response_revision:23938; number_of_response:1; }","duration":"171.670125ms","start":"2026-03-06T22:47:15.716791Z","end":"2026-03-06T22:47:15.888462Z","steps":["trace[1140948001] 'process raft request' (duration: 171.52986ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:15.888877Z","caller":"traceutil/trace.go:171","msg":"trace[726085374] transaction","detail":"{read_only:false; response_revision:23937; number_of_response:1; }","duration":"177.339738ms","start":"2026-03-06T22:47:15.711347Z","end":"2026-03-06T22:47:15.888686Z","steps":["trace[726085374] 'process raft request' (duration: 125.887382ms)","trace[726085374] 'compare' (duration: 50.678907ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:15.888992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.812292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-03-06T22:47:15.889087Z","caller":"traceutil/trace.go:171","msg":"trace[1535698486] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23938; }","duration":"178.951775ms","start":"2026-03-06T22:47:15.710111Z","end":"2026-03-06T22:47:15.889063Z","steps":["trace[1535698486] 'agreement among raft nodes before linearized reading' (duration: 178.72541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.8891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.703893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-03-06T22:47:15.889104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.418343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-03-06T22:47:15.889147Z","caller":"traceutil/trace.go:171","msg":"trace[690035284] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:23938; }","duration":"342.824265ms","start":"2026-03-06T22:47:15.54631Z","end":"2026-03-06T22:47:15.889134Z","steps":["trace[690035284] 'agreement among raft nodes before linearized reading' (duration: 342.61879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.889191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:15.546283Z","time spent":"342.897397ms","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"info","ts":"2026-03-06T22:47:15.889202Z","caller":"traceutil/trace.go:171","msg":"trace[1502234994] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:23938; }","duration":"214.565627ms","start":"2026-03-06T22:47:15.674608Z","end":"2026-03-06T22:47:15.889173Z","steps":["trace[1502234994] 'agreement among raft nodes before linearized reading' (duration: 214.232419ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:15.890773Z","caller":"traceutil/trace.go:171","msg":"trace[735662177] linearizableReadLoop","detail":"{readStateIndex:24770; appliedIndex:24768; }","duration":"115.543989ms","start":"2026-03-06T22:47:15.772846Z","end":"2026-03-06T22:47:15.88839Z","steps":["trace[735662177] 'read index received' (duration: 64.315609ms)","trace[735662177] 'applied index is now lower than readState.Index' (duration: 51.22729ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:15.891762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.805856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:47:15.892022Z","caller":"traceutil/trace.go:171","msg":"trace[737500871] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:23938; }","duration":"108.085213ms","start":"2026-03-06T22:47:15.783921Z","end":"2026-03-06T22:47:15.892006Z","steps":["trace[737500871] 'agreement among raft nodes before linearized reading' (duration: 107.799516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:15.891637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.217248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6b1642fd-008d-4715-ae31-7c3494773ac7\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-03-06T22:47:15.8931Z","caller":"traceutil/trace.go:171","msg":"trace[515970556] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6b1642fd-008d-4715-ae31-7c3494773ac7; range_end:; response_count:1; response_revision:23938; }","duration":"115.677722ms","start":"2026-03-06T22:47:15.777401Z","end":"2026-03-06T22:47:15.893079Z","steps":["trace[515970556] 'agreement among raft nodes before linearized reading' (duration: 114.138996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:16.118129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.39569ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-06T22:47:16.118444Z","caller":"traceutil/trace.go:171","msg":"trace[23373078] transaction","detail":"{read_only:false; response_revision:23939; number_of_response:1; }","duration":"110.764996ms","start":"2026-03-06T22:47:16.00766Z","end":"2026-03-06T22:47:16.118425Z","steps":["trace[23373078] 'process raft request' (duration: 110.628213ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:16.118474Z","caller":"traceutil/trace.go:171","msg":"trace[1408845432] linearizableReadLoop","detail":"{readStateIndex:24771; appliedIndex:24770; }","duration":"218.715184ms","start":"2026-03-06T22:47:15.899716Z","end":"2026-03-06T22:47:16.118431Z","steps":["trace[1408845432] 'read index received' (duration: 118.024287ms)","trace[1408845432] 'applied index is now lower than readState.Index' (duration: 100.686757ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:16.118758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.025871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-06T22:47:16.118808Z","caller":"traceutil/trace.go:171","msg":"trace[1932120170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23939; }","duration":"219.103473ms","start":"2026-03-06T22:47:15.899693Z","end":"2026-03-06T22:47:16.118796Z","steps":["trace[1932120170] 'agreement among raft nodes before linearized reading' (duration: 218.925958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:16.118905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.154104ms","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-03-06T22:47:16.118916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.058166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-03-06T22:47:16.118987Z","caller":"traceutil/trace.go:171","msg":"trace[698831636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23939; }","duration":"219.291218ms","start":"2026-03-06T22:47:15.899676Z","end":"2026-03-06T22:47:16.118967Z","steps":["trace[698831636] 'agreement among raft nodes before linearized reading' (duration: 219.015711ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:16.119011Z","caller":"traceutil/trace.go:171","msg":"trace[1991092947] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:23939; }","duration":"217.211299ms","start":"2026-03-06T22:47:15.901775Z","end":"2026-03-06T22:47:16.118986Z","steps":["trace[1991092947] 'agreement among raft nodes before linearized reading' (duration: 216.905922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:16.119014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.088433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-06T22:47:16.119119Z","caller":"traceutil/trace.go:171","msg":"trace[1616245433] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23939; }","duration":"205.236466ms","start":"2026-03-06T22:47:15.913859Z","end":"2026-03-06T22:47:16.119095Z","steps":["trace[1616245433] 'agreement among raft nodes before linearized reading' (duration: 204.963049ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:16.394977Z","caller":"traceutil/trace.go:171","msg":"trace[701553792] linearizableReadLoop","detail":"{readStateIndex:24776; appliedIndex:24773; }","duration":"266.453841ms","start":"2026-03-06T22:47:16.128505Z","end":"2026-03-06T22:47:16.394959Z","steps":["trace[701553792] 'read index received' (duration: 55.749786ms)","trace[701553792] 'applied index is now lower than readState.Index' (duration: 210.703075ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:16.39506Z","caller":"traceutil/trace.go:171","msg":"trace[280164487] transaction","detail":"{read_only:false; response_revision:23943; number_of_response:1; }","duration":"267.711561ms","start":"2026-03-06T22:47:16.127338Z","end":"2026-03-06T22:47:16.39505Z","steps":["trace[280164487] 'process raft request' (duration: 267.573118ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:16.395063Z","caller":"traceutil/trace.go:171","msg":"trace[1454597822] transaction","detail":"{read_only:false; response_revision:23942; number_of_response:1; }","duration":"268.012247ms","start":"2026-03-06T22:47:16.127007Z","end":"2026-03-06T22:47:16.395019Z","steps":["trace[1454597822] 'process raft request' (duration: 267.845673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:16.395167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.618246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:16.395209Z","caller":"traceutil/trace.go:171","msg":"trace[752630640] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:23943; }","duration":"266.717907ms","start":"2026-03-06T22:47:16.128478Z","end":"2026-03-06T22:47:16.395196Z","steps":["trace[752630640] 'agreement among raft nodes before linearized reading' (duration: 266.612035ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:16.394966Z","caller":"traceutil/trace.go:171","msg":"trace[502755469] transaction","detail":"{read_only:false; response_revision:23941; number_of_response:1; }","duration":"268.054229ms","start":"2026-03-06T22:47:16.126875Z","end":"2026-03-06T22:47:16.394929Z","steps":["trace[502755469] 'process raft request' (duration: 198.134348ms)","trace[502755469] 'compare' (duration: 69.643935ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:16.395364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.044602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:16.395457Z","caller":"traceutil/trace.go:171","msg":"trace[776577361] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:23943; }","duration":"255.171436ms","start":"2026-03-06T22:47:16.140263Z","end":"2026-03-06T22:47:16.395434Z","steps":["trace[776577361] 'agreement among raft nodes before linearized reading' (duration: 254.984831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:16.395521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.666031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-06T22:47:16.39556Z","caller":"traceutil/trace.go:171","msg":"trace[1450870237] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:23943; }","duration":"209.729802ms","start":"2026-03-06T22:47:16.185819Z","end":"2026-03-06T22:47:16.395548Z","steps":["trace[1450870237] 'agreement among raft nodes before linearized reading' (duration: 209.575497ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:17.523695Z","caller":"traceutil/trace.go:171","msg":"trace[351251475] linearizableReadLoop","detail":"{readStateIndex:24781; appliedIndex:24780; }","duration":"100.973974ms","start":"2026-03-06T22:47:17.422693Z","end":"2026-03-06T22:47:17.523667Z","steps":["trace[351251475] 'read index received' (duration: 100.851592ms)","trace[351251475] 'applied index is now lower than readState.Index' (duration: 120.922µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:17.52399Z","caller":"traceutil/trace.go:171","msg":"trace[1364502039] transaction","detail":"{read_only:false; response_revision:23948; number_of_response:1; }","duration":"102.775187ms","start":"2026-03-06T22:47:17.421201Z","end":"2026-03-06T22:47:17.523977Z","steps":["trace[1364502039] 'process raft request' (duration: 102.273705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:17.524251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.520017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:17.524297Z","caller":"traceutil/trace.go:171","msg":"trace[1996200599] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23948; }","duration":"101.64536ms","start":"2026-03-06T22:47:17.422638Z","end":"2026-03-06T22:47:17.524283Z","steps":["trace[1996200599] 'agreement among raft nodes before linearized reading' (duration: 101.536877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:17.748793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.619157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:47:17.749013Z","caller":"traceutil/trace.go:171","msg":"trace[1434878766] transaction","detail":"{read_only:false; response_revision:23950; number_of_response:1; }","duration":"219.072734ms","start":"2026-03-06T22:47:17.529923Z","end":"2026-03-06T22:47:17.748995Z","steps":["trace[1434878766] 'process raft request' (duration: 218.989362ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:17.748998Z","caller":"traceutil/trace.go:171","msg":"trace[1790130322] transaction","detail":"{read_only:false; response_revision:23949; number_of_response:1; }","duration":"323.637483ms","start":"2026-03-06T22:47:17.425321Z","end":"2026-03-06T22:47:17.748958Z","steps":["trace[1790130322] 'process raft request' (duration: 183.73701ms)","trace[1790130322] 'compare' (duration: 139.454563ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:17.749051Z","caller":"traceutil/trace.go:171","msg":"trace[376489565] linearizableReadLoop","detail":"{readStateIndex:24782; appliedIndex:24781; }","duration":"225.23186ms","start":"2026-03-06T22:47:17.52378Z","end":"2026-03-06T22:47:17.749012Z","steps":["trace[376489565] 'read index received' (duration: 85.255214ms)","trace[376489565] 'applied index is now lower than readState.Index' (duration: 139.972235ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:17.749169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:17.42528Z","time spent":"323.794537ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:47:17.749288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.447226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:17.749332Z","caller":"traceutil/trace.go:171","msg":"trace[1160962063] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23950; }","duration":"220.547439ms","start":"2026-03-06T22:47:17.528773Z","end":"2026-03-06T22:47:17.74932Z","steps":["trace[1160962063] 'agreement among raft nodes before linearized reading' (duration: 220.430566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:17.749417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.159994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418256"} {"level":"info","ts":"2026-03-06T22:47:17.749513Z","caller":"traceutil/trace.go:171","msg":"trace[1365788896] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:23950; }","duration":"241.308918ms","start":"2026-03-06T22:47:17.50818Z","end":"2026-03-06T22:47:17.749489Z","steps":["trace[1365788896] 'agreement among raft nodes before linearized reading' (duration: 240.98159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:17.749631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.3088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:17.749681Z","caller":"traceutil/trace.go:171","msg":"trace[1099912174] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:23950; }","duration":"128.409183ms","start":"2026-03-06T22:47:17.621257Z","end":"2026-03-06T22:47:17.749666Z","steps":["trace[1099912174] 'agreement among raft nodes before linearized reading' (duration: 128.316561ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:17.942339Z","caller":"traceutil/trace.go:171","msg":"trace[1672929938] linearizableReadLoop","detail":"{readStateIndex:24784; appliedIndex:24783; }","duration":"175.985486ms","start":"2026-03-06T22:47:17.766325Z","end":"2026-03-06T22:47:17.942311Z","steps":["trace[1672929938] 'read index received' (duration: 91.436269ms)","trace[1672929938] 'applied index is now lower than readState.Index' (duration: 84.548197ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:17.942419Z","caller":"traceutil/trace.go:171","msg":"trace[1387501170] transaction","detail":"{read_only:false; response_revision:23951; number_of_response:1; }","duration":"178.672089ms","start":"2026-03-06T22:47:17.763691Z","end":"2026-03-06T22:47:17.942363Z","steps":["trace[1387501170] 'process raft request' (duration: 94.044081ms)","trace[1387501170] 'compare' (duration: 84.385612ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:17.942883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.541238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18834"} {"level":"info","ts":"2026-03-06T22:47:17.942945Z","caller":"traceutil/trace.go:171","msg":"trace[1851272411] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23951; }","duration":"176.625ms","start":"2026-03-06T22:47:17.766305Z","end":"2026-03-06T22:47:17.94293Z","steps":["trace[1851272411] 'agreement among raft nodes before linearized reading' (duration: 176.117658ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:17.985739Z","caller":"traceutil/trace.go:171","msg":"trace[2139153680] transaction","detail":"{read_only:false; response_revision:23952; number_of_response:1; }","duration":"205.337758ms","start":"2026-03-06T22:47:17.780374Z","end":"2026-03-06T22:47:17.985711Z","steps":["trace[2139153680] 'process raft request' (duration: 204.98093ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:17.999613Z","caller":"traceutil/trace.go:171","msg":"trace[1309859111] transaction","detail":"{read_only:false; response_revision:23953; number_of_response:1; }","duration":"105.036679ms","start":"2026-03-06T22:47:17.894543Z","end":"2026-03-06T22:47:17.999579Z","steps":["trace[1309859111] 'process raft request' (duration: 104.765763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:17.999968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.278981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:18.000038Z","caller":"traceutil/trace.go:171","msg":"trace[148533582] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:23954; }","duration":"112.385984ms","start":"2026-03-06T22:47:17.887637Z","end":"2026-03-06T22:47:18.000023Z","steps":["trace[148533582] 'agreement among raft nodes before linearized reading' (duration: 112.277211ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:18.366087Z","caller":"traceutil/trace.go:171","msg":"trace[1652374917] transaction","detail":"{read_only:false; response_revision:23957; number_of_response:1; }","duration":"167.477925ms","start":"2026-03-06T22:47:18.198568Z","end":"2026-03-06T22:47:18.366046Z","steps":["trace[1652374917] 'process raft request' (duration: 108.156544ms)","trace[1652374917] 'compare' (duration: 59.101106ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:18.75342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.210537ms","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-03-06T22:47:18.753545Z","caller":"traceutil/trace.go:171","msg":"trace[405643544] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23958; }","duration":"150.35751ms","start":"2026-03-06T22:47:18.603165Z","end":"2026-03-06T22:47:18.753523Z","steps":["trace[405643544] 'range keys from in-memory index tree' (duration: 150.188926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:18.753822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.855222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:47:18.754042Z","caller":"traceutil/trace.go:171","msg":"trace[1835845702] transaction","detail":"{read_only:false; response_revision:23959; number_of_response:1; }","duration":"349.39243ms","start":"2026-03-06T22:47:18.404624Z","end":"2026-03-06T22:47:18.754016Z","steps":["trace[1835845702] 'process raft request' (duration: 87.23368ms)","trace[1835845702] 'compare' (duration: 261.194927ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:18.75411Z","caller":"traceutil/trace.go:171","msg":"trace[51430889] transaction","detail":"{read_only:false; response_revision:23961; number_of_response:1; }","duration":"149.610422ms","start":"2026-03-06T22:47:18.604479Z","end":"2026-03-06T22:47:18.754089Z","steps":["trace[51430889] 'process raft request' (duration: 149.556701ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:18.754132Z","caller":"traceutil/trace.go:171","msg":"trace[684367719] transaction","detail":"{read_only:false; response_revision:23960; number_of_response:1; }","duration":"349.223045ms","start":"2026-03-06T22:47:18.404871Z","end":"2026-03-06T22:47:18.754094Z","steps":["trace[684367719] 'process raft request' (duration: 349.071782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:18.754203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:18.404598Z","time spent":"349.475252ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:47:18.754191Z","caller":"traceutil/trace.go:171","msg":"trace[1721924395] linearizableReadLoop","detail":"{readStateIndex:24793; appliedIndex:24791; }","duration":"327.543163ms","start":"2026-03-06T22:47:18.426591Z","end":"2026-03-06T22:47:18.754134Z","steps":["trace[1721924395] 'read index received' (duration: 65.25222ms)","trace[1721924395] 'applied index is now lower than readState.Index' (duration: 262.286363ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:18.754368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:18.404833Z","time spent":"349.407251ms","remote":"127.0.0.1:38658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7732,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T22:47:18.754422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.16293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:18.754453Z","caller":"traceutil/trace.go:171","msg":"trace[2132009609] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:23961; }","duration":"306.247652ms","start":"2026-03-06T22:47:18.44819Z","end":"2026-03-06T22:47:18.754438Z","steps":["trace[2132009609] 'agreement among raft nodes before linearized reading' (duration: 306.194921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:18.754483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:18.44816Z","time spent":"306.316923ms","remote":"127.0.0.1:35256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-06T22:47:18.754494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.863862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:18.754552Z","caller":"traceutil/trace.go:171","msg":"trace[578430913] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:23961; }","duration":"327.990995ms","start":"2026-03-06T22:47:18.426544Z","end":"2026-03-06T22:47:18.754535Z","steps":["trace[578430913] 'agreement among raft nodes before linearized reading' (duration: 327.813361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:18.754585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.711741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-06T22:47:18.7546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:18.426529Z","time spent":"328.060316ms","remote":"127.0.0.1:40838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:47:18.754627Z","caller":"traceutil/trace.go:171","msg":"trace[1431636776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23961; }","duration":"192.797883ms","start":"2026-03-06T22:47:18.561819Z","end":"2026-03-06T22:47:18.754617Z","steps":["trace[1431636776] 'agreement among raft nodes before linearized reading' (duration: 192.714521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:19.164681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.159048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-03-06T22:47:19.164682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.906625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18834"} {"level":"info","ts":"2026-03-06T22:47:19.164798Z","caller":"traceutil/trace.go:171","msg":"trace[2083876685] linearizableReadLoop","detail":"{readStateIndex:24795; appliedIndex:24794; }","duration":"224.03178ms","start":"2026-03-06T22:47:18.940748Z","end":"2026-03-06T22:47:19.16478Z","steps":["trace[2083876685] 'read index received' (duration: 56.568606ms)","trace[2083876685] 'applied index is now lower than readState.Index' (duration: 167.461934ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:19.164818Z","caller":"traceutil/trace.go:171","msg":"trace[666613843] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23961; }","duration":"312.074788ms","start":"2026-03-06T22:47:18.852716Z","end":"2026-03-06T22:47:19.164791Z","steps":["trace[666613843] 'range keys from in-memory index tree' (duration: 311.749251ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:19.164826Z","caller":"traceutil/trace.go:171","msg":"trace[911252264] transaction","detail":"{read_only:false; response_revision:23962; number_of_response:1; }","duration":"277.215226ms","start":"2026-03-06T22:47:18.887576Z","end":"2026-03-06T22:47:19.164792Z","steps":["trace[911252264] 'process raft request' (duration: 109.860843ms)","trace[911252264] 'compare' (duration: 167.047955ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:19.164875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:18.852706Z","time spent":"312.15447ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18857,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-06T22:47:19.164916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.175183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:19.164951Z","caller":"traceutil/trace.go:171","msg":"trace[1723122267] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:23962; }","duration":"224.261825ms","start":"2026-03-06T22:47:18.940677Z","end":"2026-03-06T22:47:19.164939Z","steps":["trace[1723122267] 'agreement among raft nodes before linearized reading' (duration: 224.193713ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:22.152752Z","caller":"traceutil/trace.go:171","msg":"trace[833256691] transaction","detail":"{read_only:false; response_revision:23980; number_of_response:1; }","duration":"155.220386ms","start":"2026-03-06T22:47:21.997509Z","end":"2026-03-06T22:47:22.152729Z","steps":["trace[833256691] 'process raft request' (duration: 99.91077ms)","trace[833256691] 'compare' (duration: 54.955128ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:22.152838Z","caller":"traceutil/trace.go:171","msg":"trace[1605593140] transaction","detail":"{read_only:false; response_revision:23982; number_of_response:1; }","duration":"131.6986ms","start":"2026-03-06T22:47:22.021115Z","end":"2026-03-06T22:47:22.152814Z","steps":["trace[1605593140] 'process raft request' (duration: 131.391843ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:22.153016Z","caller":"traceutil/trace.go:171","msg":"trace[986353489] transaction","detail":"{read_only:false; response_revision:23981; number_of_response:1; }","duration":"140.294523ms","start":"2026-03-06T22:47:22.012716Z","end":"2026-03-06T22:47:22.15301Z","steps":["trace[986353489] 'process raft request' (duration: 139.75746ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:24.691286Z","caller":"traceutil/trace.go:171","msg":"trace[1646721716] linearizableReadLoop","detail":"{readStateIndex:24831; appliedIndex:24830; }","duration":"128.32624ms","start":"2026-03-06T22:47:24.562921Z","end":"2026-03-06T22:47:24.691247Z","steps":["trace[1646721716] 'read index received' (duration: 128.058954ms)","trace[1646721716] 'applied index is now lower than readState.Index' (duration: 265.136µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:24.691437Z","caller":"traceutil/trace.go:171","msg":"trace[1743414352] transaction","detail":"{read_only:false; response_revision:23997; number_of_response:1; }","duration":"190.469158ms","start":"2026-03-06T22:47:24.500918Z","end":"2026-03-06T22:47:24.691387Z","steps":["trace[1743414352] 'process raft request' (duration: 190.051688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:24.691502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.520275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:24.691549Z","caller":"traceutil/trace.go:171","msg":"trace[457112791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23997; }","duration":"128.626857ms","start":"2026-03-06T22:47:24.56291Z","end":"2026-03-06T22:47:24.691537Z","steps":["trace[457112791] 'agreement among raft nodes before linearized reading' (duration: 128.476544ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:24.74485Z","caller":"traceutil/trace.go:171","msg":"trace[1152861212] transaction","detail":"{read_only:false; response_revision:23998; number_of_response:1; }","duration":"148.192079ms","start":"2026-03-06T22:47:24.596631Z","end":"2026-03-06T22:47:24.744823Z","steps":["trace[1152861212] 'process raft request' (duration: 147.712948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:25.00527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.075231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-06T22:47:25.005398Z","caller":"traceutil/trace.go:171","msg":"trace[1490862525] linearizableReadLoop","detail":"{readStateIndex:24833; appliedIndex:24832; }","duration":"223.699762ms","start":"2026-03-06T22:47:24.781665Z","end":"2026-03-06T22:47:25.005365Z","steps":["trace[1490862525] 'read index received' (duration: 93.368344ms)","trace[1490862525] 'applied index is now lower than readState.Index' (duration: 130.329918ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:25.005447Z","caller":"traceutil/trace.go:171","msg":"trace[677798560] transaction","detail":"{read_only:false; response_revision:23999; number_of_response:1; }","duration":"229.645282ms","start":"2026-03-06T22:47:24.775777Z","end":"2026-03-06T22:47:25.005422Z","steps":["trace[677798560] 'process raft request' (duration: 99.318255ms)","trace[677798560] 'compare' (duration: 129.796475ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:25.005565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.895246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-06T22:47:25.0056Z","caller":"traceutil/trace.go:171","msg":"trace[845271410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23999; }","duration":"223.939747ms","start":"2026-03-06T22:47:24.781645Z","end":"2026-03-06T22:47:25.005585Z","steps":["trace[845271410] 'agreement among raft nodes before linearized reading' (duration: 223.780933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:25.420873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.966739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-03-06T22:47:25.421022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.045592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:47:25.421058Z","caller":"traceutil/trace.go:171","msg":"trace[693420449] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:24002; }","duration":"100.120874ms","start":"2026-03-06T22:47:25.32092Z","end":"2026-03-06T22:47:25.421041Z","steps":["trace[693420449] 'agreement among raft nodes before linearized reading' (duration: 100.045212ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:25.421079Z","caller":"traceutil/trace.go:171","msg":"trace[1956060971] transaction","detail":"{read_only:false; response_revision:24002; number_of_response:1; }","duration":"193.259524ms","start":"2026-03-06T22:47:25.227779Z","end":"2026-03-06T22:47:25.421038Z","steps":["trace[1956060971] 'process raft request' (duration: 71.025568ms)","trace[1956060971] 'compare' (duration: 121.639662ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:26.296429Z","caller":"traceutil/trace.go:171","msg":"trace[470147852] transaction","detail":"{read_only:false; response_revision:24007; number_of_response:1; }","duration":"130.780348ms","start":"2026-03-06T22:47:26.165612Z","end":"2026-03-06T22:47:26.296392Z","steps":["trace[470147852] 'process raft request' (duration: 130.514652ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:26.297164Z","caller":"traceutil/trace.go:171","msg":"trace[1753036326] transaction","detail":"{read_only:false; response_revision:24008; number_of_response:1; }","duration":"130.228484ms","start":"2026-03-06T22:47:26.166904Z","end":"2026-03-06T22:47:26.297133Z","steps":["trace[1753036326] 'process raft request' (duration: 130.0466ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:26.297414Z","caller":"traceutil/trace.go:171","msg":"trace[631792186] linearizableReadLoop","detail":"{readStateIndex:24845; appliedIndex:24841; }","duration":"100.422401ms","start":"2026-03-06T22:47:26.196974Z","end":"2026-03-06T22:47:26.297397Z","steps":["trace[631792186] 'read index received' (duration: 99.169312ms)","trace[631792186] 'applied index is now lower than readState.Index' (duration: 1.252449ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:26.297698Z","caller":"traceutil/trace.go:171","msg":"trace[1833927275] transaction","detail":"{read_only:false; response_revision:24009; number_of_response:1; }","duration":"128.76256ms","start":"2026-03-06T22:47:26.16892Z","end":"2026-03-06T22:47:26.297683Z","steps":["trace[1833927275] 'process raft request' (duration: 128.160376ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:26.298076Z","caller":"traceutil/trace.go:171","msg":"trace[1220464421] transaction","detail":"{read_only:false; response_revision:24010; number_of_response:1; }","duration":"112.22018ms","start":"2026-03-06T22:47:26.185827Z","end":"2026-03-06T22:47:26.298047Z","steps":["trace[1220464421] 'process raft request' (duration: 111.485813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:26.298289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.292961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:267206"} {"level":"info","ts":"2026-03-06T22:47:26.298355Z","caller":"traceutil/trace.go:171","msg":"trace[1436469951] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:24010; }","duration":"101.411725ms","start":"2026-03-06T22:47:26.196925Z","end":"2026-03-06T22:47:26.298337Z","steps":["trace[1436469951] 'agreement among raft nodes before linearized reading' (duration: 100.78063ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:28.078815Z","caller":"traceutil/trace.go:171","msg":"trace[1119316908] transaction","detail":"{read_only:false; response_revision:24018; number_of_response:1; }","duration":"311.804101ms","start":"2026-03-06T22:47:27.76698Z","end":"2026-03-06T22:47:28.078784Z","steps":["trace[1119316908] 'process raft request' (duration: 311.470444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:28.079012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:27.766953Z","time spent":"311.941185ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:47:28.079544Z","caller":"traceutil/trace.go:171","msg":"trace[872717359] linearizableReadLoop","detail":"{readStateIndex:24853; appliedIndex:24853; }","duration":"275.472753ms","start":"2026-03-06T22:47:27.804008Z","end":"2026-03-06T22:47:28.079481Z","steps":["trace[872717359] 'read index received' (duration: 275.467963ms)","trace[872717359] 'applied index is now lower than readState.Index' (duration: 4.14µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:28.079706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.703369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-06T22:47:28.079779Z","caller":"traceutil/trace.go:171","msg":"trace[1919073680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24018; }","duration":"275.812561ms","start":"2026-03-06T22:47:27.803958Z","end":"2026-03-06T22:47:28.07977Z","steps":["trace[1919073680] 'agreement among raft nodes before linearized reading' (duration: 275.638417ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:28.26725Z","caller":"traceutil/trace.go:171","msg":"trace[304892914] transaction","detail":"{read_only:false; response_revision:24019; number_of_response:1; }","duration":"367.293602ms","start":"2026-03-06T22:47:27.899907Z","end":"2026-03-06T22:47:28.2672Z","steps":["trace[304892914] 'process raft request' (duration: 297.061624ms)","trace[304892914] 'compare' (duration: 69.978162ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:28.267439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:27.899877Z","time spent":"367.457766ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T22:47:28.267409Z","caller":"traceutil/trace.go:171","msg":"trace[38072374] linearizableReadLoop","detail":"{readStateIndex:24854; appliedIndex:24853; }","duration":"187.816524ms","start":"2026-03-06T22:47:28.079569Z","end":"2026-03-06T22:47:28.267386Z","steps":["trace[38072374] 'read index received' (duration: 117.295289ms)","trace[38072374] 'applied index is now lower than readState.Index' (duration: 70.519545ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T22:47:28.267614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.977003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:28.26769Z","caller":"traceutil/trace.go:171","msg":"trace[1715336442] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24020; }","duration":"239.117707ms","start":"2026-03-06T22:47:28.028553Z","end":"2026-03-06T22:47:28.267671Z","steps":["trace[1715336442] 'agreement among raft nodes before linearized reading' (duration: 238.985233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:28.267849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.239612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-06T22:47:28.267894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.774755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:47:28.267917Z","caller":"traceutil/trace.go:171","msg":"trace[422301211] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24020; }","duration":"215.846276ms","start":"2026-03-06T22:47:28.052065Z","end":"2026-03-06T22:47:28.267911Z","steps":["trace[422301211] 'agreement among raft nodes before linearized reading' (duration: 215.784495ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:47:28.267917Z","caller":"traceutil/trace.go:171","msg":"trace[1403004275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24020; }","duration":"265.372396ms","start":"2026-03-06T22:47:28.002535Z","end":"2026-03-06T22:47:28.267908Z","steps":["trace[1403004275] 'agreement among raft nodes before linearized reading' (duration: 265.011607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:28.268165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.176068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:47:28.26821Z","caller":"traceutil/trace.go:171","msg":"trace[1397622652] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:24020; }","duration":"361.252019ms","start":"2026-03-06T22:47:27.906945Z","end":"2026-03-06T22:47:28.268197Z","steps":["trace[1397622652] 'agreement among raft nodes before linearized reading' (duration: 361.167457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:28.26824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T22:47:27.906931Z","time spent":"361.301491ms","remote":"127.0.0.1:37266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":17,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-03-06T22:47:29.04205Z","caller":"traceutil/trace.go:171","msg":"trace[1019924509] linearizableReadLoop","detail":"{readStateIndex:24863; appliedIndex:24862; }","duration":"148.857974ms","start":"2026-03-06T22:47:28.893163Z","end":"2026-03-06T22:47:29.042021Z","steps":["trace[1019924509] 'read index received' (duration: 148.641959ms)","trace[1019924509] 'applied index is now lower than readState.Index' (duration: 214.255µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T22:47:29.042131Z","caller":"traceutil/trace.go:171","msg":"trace[533365239] transaction","detail":"{read_only:false; response_revision:24028; number_of_response:1; }","duration":"178.071975ms","start":"2026-03-06T22:47:28.864016Z","end":"2026-03-06T22:47:29.042088Z","steps":["trace[533365239] 'process raft request' (duration: 177.795869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:29.042406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.202153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-06T22:47:29.042522Z","caller":"traceutil/trace.go:171","msg":"trace[1786516860] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24028; }","duration":"149.397548ms","start":"2026-03-06T22:47:28.893106Z","end":"2026-03-06T22:47:29.042504Z","steps":["trace[1786516860] 'agreement among raft nodes before linearized reading' (duration: 149.115711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T22:47:29.044739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.24093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-06T22:47:29.04485Z","caller":"traceutil/trace.go:171","msg":"trace[1793609594] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:24029; }","duration":"101.423985ms","start":"2026-03-06T22:47:28.943396Z","end":"2026-03-06T22:47:29.04482Z","steps":["trace[1793609594] 'agreement among raft nodes before linearized reading' (duration: 101.21508ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T22:48:02.392822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21571} {"level":"info","ts":"2026-03-06T22:48:02.529722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21571,"took":"131.629048ms","hash":2337591358,"current-db-size-bytes":49610752,"current-db-size":"50 MB","current-db-size-in-use-bytes":31764480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-06T22:48:02.529785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2337591358,"revision":21571,"compact-revision":18862} {"level":"info","ts":"2026-03-06T22:53:02.406311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24418} {"level":"info","ts":"2026-03-06T22:53:02.53646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24418,"took":"125.335145ms","hash":4173854598,"current-db-size-bytes":50876416,"current-db-size":"51 MB","current-db-size-in-use-bytes":33996800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-06T22:53:02.536528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4173854598,"revision":24418,"compact-revision":21571} {"level":"info","ts":"2026-03-06T22:57:55.58645Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30aa8136fb61a722","local-member-applied-index":30011,"local-member-snapshot-index":20010,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T22:57:55.592373Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30011} {"level":"info","ts":"2026-03-06T22:57:55.592489Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25011} {"level":"info","ts":"2026-03-06T22:58:02.416162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27006} {"level":"info","ts":"2026-03-06T22:58:02.554521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27006,"took":"132.63299ms","hash":3347699144,"current-db-size-bytes":50876416,"current-db-size":"51 MB","current-db-size-in-use-bytes":27058176,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-06T22:58:02.55463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3347699144,"revision":27006,"compact-revision":24418} {"level":"info","ts":"2026-03-06T23:03:02.424781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29080} {"level":"info","ts":"2026-03-06T23:03:02.545289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29080,"took":"115.813313ms","hash":4090910757,"current-db-size-bytes":50876416,"current-db-size":"51 MB","current-db-size-in-use-bytes":29057024,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-06T23:03:02.545381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4090910757,"revision":29080,"compact-revision":27006} {"level":"info","ts":"2026-03-06T23:08:02.431883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31264} {"level":"info","ts":"2026-03-06T23:08:02.570488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31264,"took":"134.716519ms","hash":3804039678,"current-db-size-bytes":50876416,"current-db-size":"51 MB","current-db-size-in-use-bytes":28262400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-06T23:08:02.570575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804039678,"revision":31264,"compact-revision":29080} {"level":"info","ts":"2026-03-06T23:11:17.942741Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008c7e.wal"} {"level":"info","ts":"2026-03-06T23:13:02.438527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33437} {"level":"info","ts":"2026-03-06T23:13:02.570762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33437,"took":"126.105919ms","hash":1447827678,"current-db-size-bytes":51335168,"current-db-size":"51 MB","current-db-size-in-use-bytes":29442048,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-06T23:13:02.570866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1447827678,"revision":33437,"compact-revision":31264} {"level":"info","ts":"2026-03-06T23:18:02.444241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35521} {"level":"info","ts":"2026-03-06T23:18:02.566326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35521,"took":"116.901942ms","hash":3848490666,"current-db-size-bytes":53022720,"current-db-size":"53 MB","current-db-size-in-use-bytes":29061120,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-06T23:18:02.566382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3848490666,"revision":35521,"compact-revision":33437} {"level":"info","ts":"2026-03-06T23:20:35.667051Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30aa8136fb61a722","local-member-applied-index":40012,"local-member-snapshot-index":30011,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-06T23:20:35.67442Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40012} {"level":"info","ts":"2026-03-06T23:20:35.674535Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35012}