{"level":"warn","ts":"2026-02-26T03:00:03.098232Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T03:00:03.098342Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-02-26T03:00:03.098433Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T03:00:03.098449Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-26T03:00:03.098467Z","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-02-26T03:00:03.099247Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-26T03:00:03.09934Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-02-26T03:00:03.122604Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"22.974484ms"} {"level":"info","ts":"2026-02-26T03:00:03.161409Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-26T03:00:03.161518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T03:00:03.16154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-26T03:00:03.161554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T03:00:03.161566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-26T03:00:03.16162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-26T03:00:03.167833Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T03:00:03.173954Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T03:00:03.177928Z","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-02-26T03:00:03.181252Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T03:00:03.181517Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T03:00:03.181614Z","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-02-26T03:00:03.181682Z","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-02-26T03:00:03.181696Z","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-02-26T03:00:03.182537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-26T03:00:03.182735Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-26T03:00:03.185611Z","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-02-26T03:00:03.185722Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-26T03:00:03.185871Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-26T03:00:03.185941Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T03:00:03.186034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T03:00:04.162249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T03:00:04.162362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T03:00:04.162411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-26T03:00:04.16244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-26T03:00:04.162455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-26T03:00:04.162476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-26T03:00:04.162535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-26T03:00:04.16374Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:00:04.164646Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T03:00:04.164654Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T03:00:04.1647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T03:00:04.165061Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T03:00:04.165161Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T03:00:04.165306Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:00:04.165453Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:00:04.16549Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:00:04.169838Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-26T03:00:04.170704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T03:00:21.092166Z","caller":"traceutil/trace.go:171","msg":"trace[1509671415] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"188.315314ms","start":"2026-02-26T03:00:20.903823Z","end":"2026-02-26T03:00:21.092139Z","steps":["trace[1509671415] 'process raft request' (duration: 188.155779ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:00:21.213903Z","caller":"traceutil/trace.go:171","msg":"trace[1512190099] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"109.257946ms","start":"2026-02-26T03:00:21.104312Z","end":"2026-02-26T03:00:21.21357Z","steps":["trace[1512190099] 'process raft request' (duration: 109.103902ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:00:26.478098Z","caller":"traceutil/trace.go:171","msg":"trace[1301421952] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"143.345508ms","start":"2026-02-26T03:00:26.334728Z","end":"2026-02-26T03:00:26.478074Z","steps":["trace[1301421952] 'process raft request' (duration: 143.153363ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:00:26.499187Z","caller":"traceutil/trace.go:171","msg":"trace[639250125] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"100.930709ms","start":"2026-02-26T03:00:26.39824Z","end":"2026-02-26T03:00:26.499171Z","steps":["trace[639250125] 'process raft request' (duration: 95.745536ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:00:41.217597Z","caller":"traceutil/trace.go:171","msg":"trace[829765792] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"107.773728ms","start":"2026-02-26T03:00:41.109789Z","end":"2026-02-26T03:00:41.217562Z","steps":["trace[829765792] 'process raft request' (duration: 107.550762ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:00:41.413706Z","caller":"traceutil/trace.go:171","msg":"trace[1642536494] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"303.732545ms","start":"2026-02-26T03:00:41.109822Z","end":"2026-02-26T03:00:41.413554Z","steps":["trace[1642536494] 'process raft request' (duration: 206.172288ms)","trace[1642536494] 'compare' (duration: 97.36033ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:00:41.414613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:00:41.109801Z","time spent":"304.017313ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:00:43.712655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.797294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:00:43.713695Z","caller":"traceutil/trace.go:171","msg":"trace[954926955] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"110.930926ms","start":"2026-02-26T03:00:43.602691Z","end":"2026-02-26T03:00:43.713622Z","steps":["trace[954926955] 'process raft request' (duration: 110.324388ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:00:43.713919Z","caller":"traceutil/trace.go:171","msg":"trace[413970088] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"112.709965ms","start":"2026-02-26T03:00:43.600974Z","end":"2026-02-26T03:00:43.713684Z","steps":["trace[413970088] 'compare' (duration: 105.675361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:01:00.977107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.169564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:01:00.977351Z","caller":"traceutil/trace.go:171","msg":"trace[528179736] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:892; }","duration":"283.942459ms","start":"2026-02-26T03:01:00.693388Z","end":"2026-02-26T03:01:00.977331Z","steps":["trace[528179736] 'read index received' (duration: 59.008295ms)","trace[528179736] 'applied index is now lower than readState.Index' (duration: 224.932774ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:01:00.977428Z","caller":"traceutil/trace.go:171","msg":"trace[950629878] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"346.107411ms","start":"2026-02-26T03:01:00.63129Z","end":"2026-02-26T03:01:00.977397Z","steps":["trace[950629878] 'process raft request' (duration: 121.205318ms)","trace[950629878] 'compare' (duration: 223.998879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:01:00.977573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:01:00.631275Z","time spent":"346.230705ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1602,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:01:00.977421Z","caller":"traceutil/trace.go:171","msg":"trace[202535494] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"344.536388ms","start":"2026-02-26T03:01:00.632873Z","end":"2026-02-26T03:01:00.977409Z","steps":["trace[202535494] 'process raft request' (duration: 344.376394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:01:00.977755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.295159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T03:01:00.977873Z","caller":"traceutil/trace.go:171","msg":"trace[321770082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"284.522495ms","start":"2026-02-26T03:01:00.69332Z","end":"2026-02-26T03:01:00.977843Z","steps":["trace[321770082] 'agreement among raft nodes before linearized reading' (duration: 284.121354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:01:00.977919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:01:00.63286Z","time spent":"344.884158ms","remote":"127.0.0.1:39392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4503,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:01:49.989258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.941938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T03:01:49.98933Z","caller":"traceutil/trace.go:171","msg":"trace[1645493629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1537; }","duration":"203.040761ms","start":"2026-02-26T03:01:49.786271Z","end":"2026-02-26T03:01:49.989311Z","steps":["trace[1645493629] 'range keys from in-memory index tree' (duration: 202.791124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:01:49.989448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.636672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02e2ac5e-8d32-4c3d-8f73-75d0597dacb5\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-02-26T03:01:49.989582Z","caller":"traceutil/trace.go:171","msg":"trace[586934796] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-02e2ac5e-8d32-4c3d-8f73-75d0597dacb5; range_end:; response_count:1; response_revision:1537; }","duration":"112.810877ms","start":"2026-02-26T03:01:49.876744Z","end":"2026-02-26T03:01:49.989555Z","steps":["trace[586934796] 'range keys from in-memory index tree' (duration: 112.303274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:01:49.989099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.284788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02e2ac5e-8d32-4c3d-8f73-75d0597dacb5\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-02-26T03:01:49.989843Z","caller":"traceutil/trace.go:171","msg":"trace[1785713945] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-02e2ac5e-8d32-4c3d-8f73-75d0597dacb5; range_end:; response_count:1; response_revision:1537; }","duration":"305.10405ms","start":"2026-02-26T03:01:49.684723Z","end":"2026-02-26T03:01:49.989827Z","steps":["trace[1785713945] 'range keys from in-memory index tree' (duration: 303.931238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:01:49.989886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:01:49.684695Z","time spent":"305.176952ms","remote":"127.0.0.1:39102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02e2ac5e-8d32-4c3d-8f73-75d0597dacb5\" "} {"level":"info","ts":"2026-02-26T03:01:50.14622Z","caller":"traceutil/trace.go:171","msg":"trace[1525976148] linearizableReadLoop","detail":"{readStateIndex:1602; appliedIndex:1600; }","duration":"132.492564ms","start":"2026-02-26T03:01:50.013693Z","end":"2026-02-26T03:01:50.146186Z","steps":["trace[1525976148] 'read index received' (duration: 15.735587ms)","trace[1525976148] 'applied index is now lower than readState.Index' (duration: 116.755657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:01:50.146469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.750001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T03:01:50.14656Z","caller":"traceutil/trace.go:171","msg":"trace[2086111823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1539; }","duration":"132.900145ms","start":"2026-02-26T03:01:50.013646Z","end":"2026-02-26T03:01:50.146546Z","steps":["trace[2086111823] 'agreement among raft nodes before linearized reading' (duration: 132.673589ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:01:50.146453Z","caller":"traceutil/trace.go:171","msg":"trace[1035147639] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"147.422938ms","start":"2026-02-26T03:01:49.99887Z","end":"2026-02-26T03:01:50.146293Z","steps":["trace[1035147639] 'process raft request' (duration: 136.6909ms)","trace[1035147639] 'compare' (duration: 10.453551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:01:50.470728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.777485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:01:50.470878Z","caller":"traceutil/trace.go:171","msg":"trace[1405426729] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"318.944924ms","start":"2026-02-26T03:01:50.15188Z","end":"2026-02-26T03:01:50.470825Z","steps":["trace[1405426729] 'process raft request' (duration: 116.992164ms)","trace[1405426729] 'compare' (duration: 201.662822ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:01:50.471102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:01:50.151847Z","time spent":"319.15255ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:02:03.833106Z","caller":"traceutil/trace.go:171","msg":"trace[999612572] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"122.436735ms","start":"2026-02-26T03:02:03.710636Z","end":"2026-02-26T03:02:03.833073Z","steps":["trace[999612572] 'process raft request' (duration: 122.153116ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:02:33.681393Z","caller":"traceutil/trace.go:171","msg":"trace[1896439405] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"133.285115ms","start":"2026-02-26T03:02:33.548072Z","end":"2026-02-26T03:02:33.681357Z","steps":["trace[1896439405] 'process raft request' (duration: 82.362914ms)","trace[1896439405] 'compare' (duration: 50.765257ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:02:40.195899Z","caller":"traceutil/trace.go:171","msg":"trace[1508744783] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"173.435055ms","start":"2026-02-26T03:02:40.022432Z","end":"2026-02-26T03:02:40.195867Z","steps":["trace[1508744783] 'process raft request' (duration: 173.24134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:02:45.946994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.129075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:02:45.947255Z","caller":"traceutil/trace.go:171","msg":"trace[1044246389] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"309.867934ms","start":"2026-02-26T03:02:45.637366Z","end":"2026-02-26T03:02:45.947234Z","steps":["trace[1044246389] 'process raft request' (duration: 77.4091ms)","trace[1044246389] 'compare' (duration: 231.971091ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:02:45.947363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:02:45.637325Z","time spent":"309.982667ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:02:45.947785Z","caller":"traceutil/trace.go:171","msg":"trace[2016488049] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"218.305131ms","start":"2026-02-26T03:02:45.729429Z","end":"2026-02-26T03:02:45.947734Z","steps":["trace[2016488049] 'process raft request' (duration: 217.690204ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:04:10.383209Z","caller":"traceutil/trace.go:171","msg":"trace[2050862628] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"116.530082ms","start":"2026-02-26T03:04:10.266645Z","end":"2026-02-26T03:04:10.383175Z","steps":["trace[2050862628] 'process raft request' (duration: 116.365317ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:23.305508Z","caller":"traceutil/trace.go:171","msg":"trace[1473281953] linearizableReadLoop","detail":"{readStateIndex:4825; appliedIndex:4824; }","duration":"233.018694ms","start":"2026-02-26T03:08:23.072469Z","end":"2026-02-26T03:08:23.305487Z","steps":["trace[1473281953] 'read index received' (duration: 232.987504ms)","trace[1473281953] 'applied index is now lower than readState.Index' (duration: 30.28µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:23.305678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.190119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:23.305769Z","caller":"traceutil/trace.go:171","msg":"trace[1850055351] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4635; }","duration":"233.347193ms","start":"2026-02-26T03:08:23.072401Z","end":"2026-02-26T03:08:23.305748Z","steps":["trace[1850055351] 'agreement among raft nodes before linearized reading' (duration: 233.186708ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:23.30562Z","caller":"traceutil/trace.go:171","msg":"trace[512052548] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"280.092662ms","start":"2026-02-26T03:08:23.025462Z","end":"2026-02-26T03:08:23.305554Z","steps":["trace[512052548] 'process raft request' (duration: 279.849056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:23.332481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.593039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d475ba12-8447-4f33-85fc-31e4c957a4ef\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-02-26T03:08:23.332607Z","caller":"traceutil/trace.go:171","msg":"trace[1905588554] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d475ba12-8447-4f33-85fc-31e4c957a4ef; range_end:; response_count:1; response_revision:4636; }","duration":"188.757614ms","start":"2026-02-26T03:08:23.14382Z","end":"2026-02-26T03:08:23.332577Z","steps":["trace[1905588554] 'agreement among raft nodes before linearized reading' (duration: 188.529278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:23.332572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.743856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T03:08:23.333312Z","caller":"traceutil/trace.go:171","msg":"trace[796563627] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4636; }","duration":"197.555538ms","start":"2026-02-26T03:08:23.135738Z","end":"2026-02-26T03:08:23.333293Z","steps":["trace[796563627] 'agreement among raft nodes before linearized reading' (duration: 196.718615ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:33.331848Z","caller":"traceutil/trace.go:171","msg":"trace[198323894] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"103.452515ms","start":"2026-02-26T03:08:33.228362Z","end":"2026-02-26T03:08:33.331815Z","steps":["trace[198323894] 'process raft request' (duration: 103.186018ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:33.46886Z","caller":"traceutil/trace.go:171","msg":"trace[681680906] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"116.674251ms","start":"2026-02-26T03:08:33.35215Z","end":"2026-02-26T03:08:33.468824Z","steps":["trace[681680906] 'process raft request' (duration: 86.3998ms)","trace[681680906] 'compare' (duration: 29.976983ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:08:33.468946Z","caller":"traceutil/trace.go:171","msg":"trace[1956575673] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"115.730045ms","start":"2026-02-26T03:08:33.353177Z","end":"2026-02-26T03:08:33.468907Z","steps":["trace[1956575673] 'process raft request' (duration: 115.54806ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:33.469206Z","caller":"traceutil/trace.go:171","msg":"trace[391615421] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"104.346569ms","start":"2026-02-26T03:08:33.364823Z","end":"2026-02-26T03:08:33.46917Z","steps":["trace[391615421] 'process raft request' (duration: 103.963259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:42.986184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.685201ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-26T03:08:42.986478Z","caller":"traceutil/trace.go:171","msg":"trace[597535148] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5215; }","duration":"127.163094ms","start":"2026-02-26T03:08:42.859278Z","end":"2026-02-26T03:08:42.986441Z","steps":["trace[597535148] 'read index received' (duration: 88.523µs)","trace[597535148] 'applied index is now lower than readState.Index' (duration: 127.071181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:42.986839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.537714ms","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-02-26T03:08:42.986944Z","caller":"traceutil/trace.go:171","msg":"trace[1674314081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5015; }","duration":"127.719689ms","start":"2026-02-26T03:08:42.859202Z","end":"2026-02-26T03:08:42.986922Z","steps":["trace[1674314081] 'agreement among raft nodes before linearized reading' (duration: 127.39383ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:42.986947Z","caller":"traceutil/trace.go:171","msg":"trace[1642763044] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"126.68885ms","start":"2026-02-26T03:08:42.860224Z","end":"2026-02-26T03:08:42.986913Z","steps":["trace[1642763044] 'process raft request' (duration: 126.100004ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:10:04.196767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2735} {"level":"info","ts":"2026-02-26T03:10:04.254714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2735,"took":"56.584865ms","hash":503158994,"current-db-size-bytes":41619456,"current-db-size":"42 MB","current-db-size-in-use-bytes":30892032,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-26T03:10:04.254793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":503158994,"revision":2735,"compact-revision":-1} {"level":"info","ts":"2026-02-26T03:15:04.203423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5717} {"level":"info","ts":"2026-02-26T03:15:04.292273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5717,"took":"86.81699ms","hash":545905808,"current-db-size-bytes":42336256,"current-db-size":"42 MB","current-db-size-in-use-bytes":23166976,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T03:15:04.29235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":545905808,"revision":5717,"compact-revision":2735} {"level":"info","ts":"2026-02-26T03:15:07.819654Z","caller":"traceutil/trace.go:171","msg":"trace[1466871863] transaction","detail":"{read_only:false; response_revision:7747; number_of_response:1; }","duration":"106.362912ms","start":"2026-02-26T03:15:07.713262Z","end":"2026-02-26T03:15:07.819625Z","steps":["trace[1466871863] 'process raft request' (duration: 87.144639ms)","trace[1466871863] 'compare' (duration: 19.044909ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:15:09.254279Z","caller":"traceutil/trace.go:171","msg":"trace[1064476932] linearizableReadLoop","detail":"{readStateIndex:8048; appliedIndex:8047; }","duration":"104.873791ms","start":"2026-02-26T03:15:09.149334Z","end":"2026-02-26T03:15:09.254208Z","steps":["trace[1064476932] 'read index received' (duration: 86.685347ms)","trace[1064476932] 'applied index is now lower than readState.Index' (duration: 18.187094ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:15:09.254289Z","caller":"traceutil/trace.go:171","msg":"trace[1676716498] transaction","detail":"{read_only:false; response_revision:7754; number_of_response:1; }","duration":"117.115462ms","start":"2026-02-26T03:15:09.137123Z","end":"2026-02-26T03:15:09.254238Z","steps":["trace[1676716498] 'process raft request' (duration: 98.917647ms)","trace[1676716498] 'compare' (duration: 17.887156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:15:09.254653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.296383ms","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-02-26T03:15:09.25476Z","caller":"traceutil/trace.go:171","msg":"trace[156308310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7754; }","duration":"105.447366ms","start":"2026-02-26T03:15:09.149287Z","end":"2026-02-26T03:15:09.254734Z","steps":["trace[156308310] 'agreement among raft nodes before linearized reading' (duration: 105.041955ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:15:36.940109Z","caller":"traceutil/trace.go:171","msg":"trace[317089911] linearizableReadLoop","detail":"{readStateIndex:8242; appliedIndex:8241; }","duration":"420.770452ms","start":"2026-02-26T03:15:36.51932Z","end":"2026-02-26T03:15:36.94009Z","steps":["trace[317089911] 'read index received' (duration: 420.556326ms)","trace[317089911] 'applied index is now lower than readState.Index' (duration: 213.216µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:15:36.940382Z","caller":"traceutil/trace.go:171","msg":"trace[348421615] transaction","detail":"{read_only:false; response_revision:7941; number_of_response:1; }","duration":"529.658905ms","start":"2026-02-26T03:15:36.410663Z","end":"2026-02-26T03:15:36.940322Z","steps":["trace[348421615] 'process raft request' (duration: 529.299126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:15:36.940843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.79388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T03:15:36.940909Z","caller":"traceutil/trace.go:171","msg":"trace[120647749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7941; }","duration":"273.879193ms","start":"2026-02-26T03:15:36.667008Z","end":"2026-02-26T03:15:36.940887Z","steps":["trace[120647749] 'agreement among raft nodes before linearized reading' (duration: 273.688668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:15:36.941153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.996746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T03:15:36.941179Z","caller":"traceutil/trace.go:171","msg":"trace[1199726697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7941; }","duration":"274.036158ms","start":"2026-02-26T03:15:36.667136Z","end":"2026-02-26T03:15:36.941172Z","steps":["trace[1199726697] 'agreement among raft nodes before linearized reading' (duration: 273.969536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:15:36.940505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.139462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:15:36.941892Z","caller":"traceutil/trace.go:171","msg":"trace[1771284488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7941; }","duration":"422.597753ms","start":"2026-02-26T03:15:36.519285Z","end":"2026-02-26T03:15:36.941882Z","steps":["trace[1771284488] 'agreement among raft nodes before linearized reading' (duration: 421.081411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:15:36.941925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:15:36.519268Z","time spent":"422.647495ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T03:15:36.942533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.79137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-df6k7\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-02-26T03:15:36.942654Z","caller":"traceutil/trace.go:171","msg":"trace[278429183] range","detail":"{range_begin:/registry/pods/openstack/keepalived-df6k7; range_end:; response_count:1; response_revision:7941; }","duration":"238.933024ms","start":"2026-02-26T03:15:36.703705Z","end":"2026-02-26T03:15:36.942638Z","steps":["trace[278429183] 'agreement among raft nodes before linearized reading' (duration: 237.35525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:15:36.940879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:15:36.410646Z","time spent":"530.097128ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:15:49.67419Z","caller":"traceutil/trace.go:171","msg":"trace[51548752] transaction","detail":"{read_only:false; response_revision:8048; number_of_response:1; }","duration":"122.691897ms","start":"2026-02-26T03:15:49.551465Z","end":"2026-02-26T03:15:49.674157Z","steps":["trace[51548752] 'process raft request' (duration: 109.294875ms)","trace[51548752] 'compare' (duration: 13.224767ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:19:29.871464Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T03:19:29.876866Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T03:19:29.876945Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T03:20:04.210728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7728} {"level":"info","ts":"2026-02-26T03:20:04.277725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7728,"took":"64.197849ms","hash":662077203,"current-db-size-bytes":42336256,"current-db-size":"42 MB","current-db-size-in-use-bytes":24915968,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-02-26T03:20:04.27793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":662077203,"revision":7728,"compact-revision":5717} {"level":"warn","ts":"2026-02-26T03:22:19.424588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.732174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T03:22:19.424685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.138405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T03:22:19.424748Z","caller":"traceutil/trace.go:171","msg":"trace[1517601655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10909; }","duration":"104.900748ms","start":"2026-02-26T03:22:19.319818Z","end":"2026-02-26T03:22:19.424719Z","steps":["trace[1517601655] 'range keys from in-memory index tree' (duration: 104.560929ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:22:19.424778Z","caller":"traceutil/trace.go:171","msg":"trace[1531957314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10909; }","duration":"105.28075ms","start":"2026-02-26T03:22:19.319477Z","end":"2026-02-26T03:22:19.424758Z","steps":["trace[1531957314] 'range keys from in-memory index tree' (duration: 104.850617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:22:19.424592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.245878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:22:19.42489Z","caller":"traceutil/trace.go:171","msg":"trace[146889266] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:10909; }","duration":"101.592588ms","start":"2026-02-26T03:22:19.323246Z","end":"2026-02-26T03:22:19.424839Z","steps":["trace[146889266] 'count revisions from in-memory index tree' (duration: 101.123274ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:22:43.451062Z","caller":"traceutil/trace.go:171","msg":"trace[1919229564] transaction","detail":"{read_only:false; response_revision:11034; number_of_response:1; }","duration":"358.305021ms","start":"2026-02-26T03:22:43.092725Z","end":"2026-02-26T03:22:43.45103Z","steps":["trace[1919229564] 'process raft request' (duration: 358.083535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:22:43.451257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:22:43.092641Z","time spent":"358.529157ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:22:43.456309Z","caller":"traceutil/trace.go:171","msg":"trace[922656483] transaction","detail":"{read_only:false; response_revision:11035; number_of_response:1; }","duration":"153.386318ms","start":"2026-02-26T03:22:43.302895Z","end":"2026-02-26T03:22:43.456281Z","steps":["trace[922656483] 'process raft request' (duration: 153.136071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:24:27.28624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.579974ms","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-02-26T03:24:27.286957Z","caller":"traceutil/trace.go:171","msg":"trace[1723737698] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11666; }","duration":"115.352746ms","start":"2026-02-26T03:24:27.171577Z","end":"2026-02-26T03:24:27.286929Z","steps":["trace[1723737698] 'range keys from in-memory index tree' (duration: 114.495392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:24:27.286463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.731569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-02-26T03:24:27.287172Z","caller":"traceutil/trace.go:171","msg":"trace[934281734] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11666; }","duration":"115.458088ms","start":"2026-02-26T03:24:27.171684Z","end":"2026-02-26T03:24:27.287142Z","steps":["trace[934281734] 'range keys from in-memory index tree' (duration: 114.43761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:24:27.286551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.749259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T03:24:27.287292Z","caller":"traceutil/trace.go:171","msg":"trace[848437031] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:11666; }","duration":"111.483569ms","start":"2026-02-26T03:24:27.175784Z","end":"2026-02-26T03:24:27.287268Z","steps":["trace[848437031] 'count revisions from in-memory index tree' (duration: 110.605165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:24:27.286566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.701794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T03:24:27.287495Z","caller":"traceutil/trace.go:171","msg":"trace[1146630855] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:11666; }","duration":"255.723112ms","start":"2026-02-26T03:24:27.031727Z","end":"2026-02-26T03:24:27.28745Z","steps":["trace[1146630855] 'count revisions from in-memory index tree' (duration: 254.548219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:24:27.286623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.021496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T03:24:27.2876Z","caller":"traceutil/trace.go:171","msg":"trace[2034063096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11666; }","duration":"217.258759ms","start":"2026-02-26T03:24:27.070311Z","end":"2026-02-26T03:24:27.28757Z","steps":["trace[2034063096] 'range keys from in-memory index tree' (duration: 215.83572ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:25:04.217506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9801} {"level":"info","ts":"2026-02-26T03:25:04.298009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9801,"took":"77.314995ms","hash":1359333787,"current-db-size-bytes":42336256,"current-db-size":"42 MB","current-db-size-in-use-bytes":22601728,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T03:25:04.298135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1359333787,"revision":9801,"compact-revision":7728} {"level":"info","ts":"2026-02-26T03:27:44.327186Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000003456.wal"} {"level":"info","ts":"2026-02-26T03:28:45.118447Z","caller":"traceutil/trace.go:171","msg":"trace[481383533] transaction","detail":"{read_only:false; response_revision:13302; number_of_response:1; }","duration":"124.590894ms","start":"2026-02-26T03:28:44.993822Z","end":"2026-02-26T03:28:45.118413Z","steps":["trace[481383533] 'process raft request' (duration: 124.264005ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:29:15.137158Z","caller":"traceutil/trace.go:171","msg":"trace[573942993] transaction","detail":"{read_only:false; response_revision:13445; number_of_response:1; }","duration":"113.963443ms","start":"2026-02-26T03:29:15.023159Z","end":"2026-02-26T03:29:15.137123Z","steps":["trace[573942993] 'process raft request' (duration: 80.640797ms)","trace[573942993] 'compare' (duration: 33.076599ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:29:23.724479Z","caller":"traceutil/trace.go:171","msg":"trace[2692280] transaction","detail":"{read_only:false; response_revision:13499; number_of_response:1; }","duration":"138.684832ms","start":"2026-02-26T03:29:23.585749Z","end":"2026-02-26T03:29:23.724433Z","steps":["trace[2692280] 'process raft request' (duration: 138.356033ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:29:23.948704Z","caller":"traceutil/trace.go:171","msg":"trace[1870433917] transaction","detail":"{read_only:false; response_revision:13500; number_of_response:1; }","duration":"107.283159ms","start":"2026-02-26T03:29:23.841382Z","end":"2026-02-26T03:29:23.948665Z","steps":["trace[1870433917] 'process raft request' (duration: 100.78207ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:30:04.225023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11914} {"level":"info","ts":"2026-02-26T03:30:04.307561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11914,"took":"79.386126ms","hash":3560194477,"current-db-size-bytes":42336256,"current-db-size":"42 MB","current-db-size-in-use-bytes":25268224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-02-26T03:30:04.307657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3560194477,"revision":11914,"compact-revision":9801} {"level":"info","ts":"2026-02-26T03:31:02.672292Z","caller":"traceutil/trace.go:171","msg":"trace[1168399228] transaction","detail":"{read_only:false; response_revision:14521; number_of_response:1; }","duration":"113.255438ms","start":"2026-02-26T03:31:02.559002Z","end":"2026-02-26T03:31:02.672258Z","steps":["trace[1168399228] 'process raft request' (duration: 113.079683ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:31:02.701459Z","caller":"traceutil/trace.go:171","msg":"trace[827270619] transaction","detail":"{read_only:false; response_revision:14522; number_of_response:1; }","duration":"142.243317ms","start":"2026-02-26T03:31:02.559172Z","end":"2026-02-26T03:31:02.701416Z","steps":["trace[827270619] 'process raft request' (duration: 141.993539ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:35:04.229685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13915} {"level":"info","ts":"2026-02-26T03:35:04.309313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13915,"took":"75.798359ms","hash":3674477300,"current-db-size-bytes":43184128,"current-db-size":"43 MB","current-db-size-in-use-bytes":30187520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-26T03:35:04.309372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3674477300,"revision":13915,"compact-revision":11914} {"level":"info","ts":"2026-02-26T03:37:12.834011Z","caller":"traceutil/trace.go:171","msg":"trace[1039644187] linearizableReadLoop","detail":"{readStateIndex:17661; appliedIndex:17660; }","duration":"196.504395ms","start":"2026-02-26T03:37:12.637483Z","end":"2026-02-26T03:37:12.833987Z","steps":["trace[1039644187] 'read index received' (duration: 196.31365ms)","trace[1039644187] 'applied index is now lower than readState.Index' (duration: 189.495µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:37:12.834061Z","caller":"traceutil/trace.go:171","msg":"trace[703085801] transaction","detail":"{read_only:false; response_revision:17035; number_of_response:1; }","duration":"212.26084ms","start":"2026-02-26T03:37:12.621763Z","end":"2026-02-26T03:37:12.834024Z","steps":["trace[703085801] 'process raft request' (duration: 212.051184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:37:12.834325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.753781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T03:37:12.83439Z","caller":"traceutil/trace.go:171","msg":"trace[734494695] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:17035; }","duration":"196.920216ms","start":"2026-02-26T03:37:12.637457Z","end":"2026-02-26T03:37:12.834377Z","steps":["trace[734494695] 'agreement among raft nodes before linearized reading' (duration: 196.68533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:37:12.834395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.142575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-82x2c\" ","response":"range_response_count:1 size:22939"} {"level":"info","ts":"2026-02-26T03:37:12.834439Z","caller":"traceutil/trace.go:171","msg":"trace[2114267193] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-82x2c; range_end:; response_count:1; response_revision:17035; }","duration":"108.231068ms","start":"2026-02-26T03:37:12.726194Z","end":"2026-02-26T03:37:12.834426Z","steps":["trace[2114267193] 'agreement among raft nodes before linearized reading' (duration: 107.966371ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:37:12.948939Z","caller":"traceutil/trace.go:171","msg":"trace[1897587496] transaction","detail":"{read_only:false; response_revision:17037; number_of_response:1; }","duration":"105.385399ms","start":"2026-02-26T03:37:12.843524Z","end":"2026-02-26T03:37:12.948909Z","steps":["trace[1897587496] 'process raft request' (duration: 104.725201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:37:13.308336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.233911ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T03:37:13.3091Z","caller":"traceutil/trace.go:171","msg":"trace[787834166] linearizableReadLoop","detail":"{readStateIndex:17666; appliedIndex:17664; }","duration":"272.789831ms","start":"2026-02-26T03:37:13.036292Z","end":"2026-02-26T03:37:13.309082Z","steps":["trace[787834166] 'read index received' (duration: 53.131µs)","trace[787834166] 'applied index is now lower than readState.Index' (duration: 272.735439ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:37:13.309187Z","caller":"traceutil/trace.go:171","msg":"trace[1613427802] transaction","detail":"{read_only:false; response_revision:17039; number_of_response:1; }","duration":"350.458955ms","start":"2026-02-26T03:37:12.958676Z","end":"2026-02-26T03:37:13.309135Z","steps":["trace[1613427802] 'process raft request' (duration: 349.768616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:37:13.309437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:12.958644Z","time spent":"350.670291ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:37:13.632828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.500608ms","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-02-26T03:37:13.633177Z","caller":"traceutil/trace.go:171","msg":"trace[2034795129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17039; }","duration":"596.901049ms","start":"2026-02-26T03:37:13.036235Z","end":"2026-02-26T03:37:13.633136Z","steps":["trace[2034795129] 'agreement among raft nodes before linearized reading' (duration: 272.897694ms)","trace[2034795129] 'range keys from in-memory index tree' (duration: 323.386318ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:37:13.632846Z","caller":"traceutil/trace.go:171","msg":"trace[1297972661] transaction","detail":"{read_only:false; response_revision:17040; number_of_response:1; }","duration":"456.195873ms","start":"2026-02-26T03:37:13.176614Z","end":"2026-02-26T03:37:13.63281Z","steps":["trace[1297972661] 'process raft request' (duration: 393.907964ms)","trace[1297972661] 'compare' (duration: 62.120205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:37:13.635077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:13.176581Z","time spent":"458.366244ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:37:13.635637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:13.036206Z","time spent":"598.599876ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T03:37:13.92822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.942872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:37:13.928628Z","caller":"traceutil/trace.go:171","msg":"trace[656890013] transaction","detail":"{read_only:false; response_revision:17043; number_of_response:1; }","duration":"288.576007ms","start":"2026-02-26T03:37:13.640028Z","end":"2026-02-26T03:37:13.928604Z","steps":["trace[656890013] 'process raft request' (duration: 288.513685ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:37:13.928854Z","caller":"traceutil/trace.go:171","msg":"trace[994389440] transaction","detail":"{read_only:false; response_revision:17041; number_of_response:1; }","duration":"712.598583ms","start":"2026-02-26T03:37:13.216238Z","end":"2026-02-26T03:37:13.928837Z","steps":["trace[994389440] 'process raft request' (duration: 541.88954ms)","trace[994389440] 'compare' (duration: 169.638934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:37:13.928937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:13.216194Z","time spent":"712.697046ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:37:13.928972Z","caller":"traceutil/trace.go:171","msg":"trace[1305471505] transaction","detail":"{read_only:false; response_revision:17042; number_of_response:1; }","duration":"707.44136ms","start":"2026-02-26T03:37:13.221498Z","end":"2026-02-26T03:37:13.928939Z","steps":["trace[1305471505] 'process raft request' (duration: 706.875924ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:37:13.929097Z","caller":"traceutil/trace.go:171","msg":"trace[1666948096] linearizableReadLoop","detail":"{readStateIndex:17669; appliedIndex:17666; }","duration":"409.6852ms","start":"2026-02-26T03:37:13.519397Z","end":"2026-02-26T03:37:13.929082Z","steps":["trace[1666948096] 'read index received' (duration: 51.139881ms)","trace[1666948096] 'applied index is now lower than readState.Index' (duration: 358.543169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:37:13.929371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:13.221468Z","time spent":"707.603855ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:37:13.929848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.615194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-mv4n8\" ","response":"range_response_count:1 size:22018"} {"level":"warn","ts":"2026-02-26T03:37:13.930053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.825793ms","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-02-26T03:37:13.930117Z","caller":"traceutil/trace.go:171","msg":"trace[1330579392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17043; }","duration":"205.937126ms","start":"2026-02-26T03:37:13.724165Z","end":"2026-02-26T03:37:13.930102Z","steps":["trace[1330579392] 'agreement among raft nodes before linearized reading' (duration: 205.71664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:37:13.930213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.104577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-ckwhx\" ","response":"range_response_count:1 size:9709"} {"level":"warn","ts":"2026-02-26T03:37:13.929884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.481312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:37:13.9303Z","caller":"traceutil/trace.go:171","msg":"trace[2072176437] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-ckwhx; range_end:; response_count:1; response_revision:17043; }","duration":"312.18881ms","start":"2026-02-26T03:37:13.618097Z","end":"2026-02-26T03:37:13.930285Z","steps":["trace[2072176437] 'agreement among raft nodes before linearized reading' (duration: 311.930103ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:37:13.930313Z","caller":"traceutil/trace.go:171","msg":"trace[2121780741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17043; }","duration":"410.951366ms","start":"2026-02-26T03:37:13.519347Z","end":"2026-02-26T03:37:13.930298Z","steps":["trace[2121780741] 'agreement among raft nodes before linearized reading' (duration: 410.492564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:37:13.930346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:13.618034Z","time spent":"312.301122ms","remote":"127.0.0.1:39102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9731,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-ckwhx\" "} {"level":"warn","ts":"2026-02-26T03:37:13.930349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:13.51932Z","time spent":"411.017517ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:37:13.930275Z","caller":"traceutil/trace.go:171","msg":"trace[2088174106] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-mv4n8; range_end:; response_count:1; response_revision:17043; }","duration":"369.934963ms","start":"2026-02-26T03:37:13.560114Z","end":"2026-02-26T03:37:13.930049Z","steps":["trace[2088174106] 'agreement among raft nodes before linearized reading' (duration: 369.549432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:37:13.930697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:37:13.560079Z","time spent":"370.597201ms","remote":"127.0.0.1:39102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22040,"request content":"key:\"/registry/pods/openstack/nova-compute-default-mv4n8\" "} {"level":"info","ts":"2026-02-26T03:40:04.237318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16235} {"level":"info","ts":"2026-02-26T03:40:04.344746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16235,"took":"103.507458ms","hash":1061998269,"current-db-size-bytes":44027904,"current-db-size":"44 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-26T03:40:04.344843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1061998269,"revision":16235,"compact-revision":13915} {"level":"warn","ts":"2026-02-26T03:40:18.255605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.385075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7392"} {"level":"info","ts":"2026-02-26T03:40:18.255662Z","caller":"traceutil/trace.go:171","msg":"trace[1213685865] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:18512; }","duration":"103.500148ms","start":"2026-02-26T03:40:18.152149Z","end":"2026-02-26T03:40:18.255649Z","steps":["trace[1213685865] 'range keys from in-memory index tree' (duration: 103.198009ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:40:18.542669Z","caller":"traceutil/trace.go:171","msg":"trace[758734208] transaction","detail":"{read_only:false; response_revision:18513; number_of_response:1; }","duration":"144.838153ms","start":"2026-02-26T03:40:18.397802Z","end":"2026-02-26T03:40:18.54264Z","steps":["trace[758734208] 'process raft request' (duration: 144.417422ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:42:07.265735Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T03:42:07.276264Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-02-26T03:42:07.276411Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-02-26T03:43:11.99364Z","caller":"traceutil/trace.go:171","msg":"trace[954187468] transaction","detail":"{read_only:false; response_revision:19611; number_of_response:1; }","duration":"117.269853ms","start":"2026-02-26T03:43:11.87634Z","end":"2026-02-26T03:43:11.99361Z","steps":["trace[954187468] 'process raft request' (duration: 117.087568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:43:12.268906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.311136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:43:12.269011Z","caller":"traceutil/trace.go:171","msg":"trace[10826975] linearizableReadLoop","detail":"{readStateIndex:20331; appliedIndex:20330; }","duration":"251.796003ms","start":"2026-02-26T03:43:12.017198Z","end":"2026-02-26T03:43:12.268994Z","steps":["trace[10826975] 'read index received' (duration: 105.117787ms)","trace[10826975] 'applied index is now lower than readState.Index' (duration: 146.676896ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:43:12.269232Z","caller":"traceutil/trace.go:171","msg":"trace[772300123] transaction","detail":"{read_only:false; response_revision:19612; number_of_response:1; }","duration":"341.167515ms","start":"2026-02-26T03:43:11.928051Z","end":"2026-02-26T03:43:12.269219Z","steps":["trace[772300123] 'process raft request' (duration: 194.424056ms)","trace[772300123] 'compare' (duration: 146.193733ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:43:12.269314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:43:11.928027Z","time spent":"341.243538ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:43:12.269925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.734599ms","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-02-26T03:43:12.269968Z","caller":"traceutil/trace.go:171","msg":"trace[62079959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19612; }","duration":"252.820332ms","start":"2026-02-26T03:43:12.017135Z","end":"2026-02-26T03:43:12.269955Z","steps":["trace[62079959] 'agreement among raft nodes before linearized reading' (duration: 252.678018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:43:12.6019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.191165ms","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-02-26T03:43:12.601982Z","caller":"traceutil/trace.go:171","msg":"trace[2047078566] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19613; }","duration":"218.367269ms","start":"2026-02-26T03:43:12.383597Z","end":"2026-02-26T03:43:12.601964Z","steps":["trace[2047078566] 'range keys from in-memory index tree' (duration: 218.03766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:43:12.601952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.194645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-02-26T03:43:12.603244Z","caller":"traceutil/trace.go:171","msg":"trace[387812823] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:19613; }","duration":"176.518422ms","start":"2026-02-26T03:43:12.426703Z","end":"2026-02-26T03:43:12.603221Z","steps":["trace[387812823] 'range keys from in-memory index tree' (duration: 174.910597ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:45:04.243504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18440} {"level":"info","ts":"2026-02-26T03:45:04.350567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18440,"took":"103.228826ms","hash":3589945996,"current-db-size-bytes":44449792,"current-db-size":"44 MB","current-db-size-in-use-bytes":30199808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-26T03:45:04.35062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3589945996,"revision":18440,"compact-revision":16235} {"level":"info","ts":"2026-02-26T03:47:01.888282Z","caller":"traceutil/trace.go:171","msg":"trace[1561650232] transaction","detail":"{read_only:false; response_revision:21909; number_of_response:1; }","duration":"192.205828ms","start":"2026-02-26T03:47:01.696031Z","end":"2026-02-26T03:47:01.888237Z","steps":["trace[1561650232] 'process raft request' (duration: 191.928271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:47:02.207841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.407539ms","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:626"} {"level":"info","ts":"2026-02-26T03:47:02.207937Z","caller":"traceutil/trace.go:171","msg":"trace[351129939] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:21909; }","duration":"216.550843ms","start":"2026-02-26T03:47:01.991363Z","end":"2026-02-26T03:47:02.207914Z","steps":["trace[351129939] 'range keys from in-memory index tree' (duration: 216.246054ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:04.249993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20506} {"level":"info","ts":"2026-02-26T03:50:04.365323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20506,"took":"109.706477ms","hash":233303834,"current-db-size-bytes":47984640,"current-db-size":"48 MB","current-db-size-in-use-bytes":39309312,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-02-26T03:50:04.365403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":233303834,"revision":20506,"compact-revision":18440} {"level":"info","ts":"2026-02-26T03:50:23.713494Z","caller":"traceutil/trace.go:171","msg":"trace[488387822] transaction","detail":"{read_only:false; response_revision:23866; number_of_response:1; }","duration":"164.402752ms","start":"2026-02-26T03:50:23.549059Z","end":"2026-02-26T03:50:23.713462Z","steps":["trace[488387822] 'process raft request' (duration: 160.37651ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:24.017593Z","caller":"traceutil/trace.go:171","msg":"trace[1340727337] transaction","detail":"{read_only:false; response_revision:23868; number_of_response:1; }","duration":"178.331259ms","start":"2026-02-26T03:50:23.839232Z","end":"2026-02-26T03:50:24.017563Z","steps":["trace[1340727337] 'process raft request' (duration: 178.080612ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:24.018129Z","caller":"traceutil/trace.go:171","msg":"trace[1011557075] linearizableReadLoop","detail":"{readStateIndex:24711; appliedIndex:24709; }","duration":"130.723599ms","start":"2026-02-26T03:50:23.887375Z","end":"2026-02-26T03:50:24.018099Z","steps":["trace[1011557075] 'read index received' (duration: 129.986089ms)","trace[1011557075] 'applied index is now lower than readState.Index' (duration: 735.94µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:24.018356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.955205ms","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-02-26T03:50:24.018352Z","caller":"traceutil/trace.go:171","msg":"trace[481754554] transaction","detail":"{read_only:false; response_revision:23869; number_of_response:1; }","duration":"146.388323ms","start":"2026-02-26T03:50:23.871924Z","end":"2026-02-26T03:50:24.018312Z","steps":["trace[481754554] 'process raft request' (duration: 146.014633ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:24.018409Z","caller":"traceutil/trace.go:171","msg":"trace[1296523308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23869; }","duration":"131.070748ms","start":"2026-02-26T03:50:23.887327Z","end":"2026-02-26T03:50:24.018398Z","steps":["trace[1296523308] 'agreement among raft nodes before linearized reading' (duration: 130.873443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:24.280572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.17317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:24.280675Z","caller":"traceutil/trace.go:171","msg":"trace[2063025152] linearizableReadLoop","detail":"{readStateIndex:24712; appliedIndex:24711; }","duration":"246.054354ms","start":"2026-02-26T03:50:24.034603Z","end":"2026-02-26T03:50:24.280657Z","steps":["trace[2063025152] 'read index received' (duration: 113.541015ms)","trace[2063025152] 'applied index is now lower than readState.Index' (duration: 132.512379ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:24.280749Z","caller":"traceutil/trace.go:171","msg":"trace[902718371] transaction","detail":"{read_only:false; response_revision:23870; number_of_response:1; }","duration":"257.87163ms","start":"2026-02-26T03:50:24.022849Z","end":"2026-02-26T03:50:24.280721Z","steps":["trace[902718371] 'process raft request' (duration: 125.471575ms)","trace[902718371] 'compare' (duration: 132.055976ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:24.280792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.190937ms","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-02-26T03:50:24.280819Z","caller":"traceutil/trace.go:171","msg":"trace[1547342523] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23870; }","duration":"246.27791ms","start":"2026-02-26T03:50:24.03453Z","end":"2026-02-26T03:50:24.280808Z","steps":["trace[1547342523] 'agreement among raft nodes before linearized reading' (duration: 246.194407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:24.294353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.91463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T03:50:24.294419Z","caller":"traceutil/trace.go:171","msg":"trace[312674885] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:23871; }","duration":"181.044663ms","start":"2026-02-26T03:50:24.113359Z","end":"2026-02-26T03:50:24.294404Z","steps":["trace[312674885] 'agreement among raft nodes before linearized reading' (duration: 180.795927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:24.29471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.934196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-02-26T03:50:24.294741Z","caller":"traceutil/trace.go:171","msg":"trace[1702108926] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23871; }","duration":"131.002657ms","start":"2026-02-26T03:50:24.163728Z","end":"2026-02-26T03:50:24.294731Z","steps":["trace[1702108926] 'agreement among raft nodes before linearized reading' (duration: 130.891364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:24.551833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.618791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:24.551943Z","caller":"traceutil/trace.go:171","msg":"trace[669691602] linearizableReadLoop","detail":"{readStateIndex:24714; appliedIndex:24713; }","duration":"247.630097ms","start":"2026-02-26T03:50:24.304295Z","end":"2026-02-26T03:50:24.551925Z","steps":["trace[669691602] 'read index received' (duration: 113.734519ms)","trace[669691602] 'applied index is now lower than readState.Index' (duration: 133.894128ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:24.552102Z","caller":"traceutil/trace.go:171","msg":"trace[713109788] transaction","detail":"{read_only:false; response_revision:23872; number_of_response:1; }","duration":"253.717977ms","start":"2026-02-26T03:50:24.298365Z","end":"2026-02-26T03:50:24.552083Z","steps":["trace[713109788] 'process raft request' (duration: 119.740346ms)","trace[713109788] 'compare' (duration: 133.265601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:24.55228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.142512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:24.552354Z","caller":"traceutil/trace.go:171","msg":"trace[828644793] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:23872; }","duration":"161.310488ms","start":"2026-02-26T03:50:24.391027Z","end":"2026-02-26T03:50:24.552337Z","steps":["trace[828644793] 'agreement among raft nodes before linearized reading' (duration: 161.121932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:24.552319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.987667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-02-26T03:50:24.552411Z","caller":"traceutil/trace.go:171","msg":"trace[597158349] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23872; }","duration":"248.122851ms","start":"2026-02-26T03:50:24.304265Z","end":"2026-02-26T03:50:24.552388Z","steps":["trace[597158349] 'agreement among raft nodes before linearized reading' (duration: 247.791932ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:29.173104Z","caller":"traceutil/trace.go:171","msg":"trace[641814690] transaction","detail":"{read_only:false; response_revision:23903; number_of_response:1; }","duration":"125.167455ms","start":"2026-02-26T03:50:29.047911Z","end":"2026-02-26T03:50:29.173078Z","steps":["trace[641814690] 'process raft request' (duration: 124.965489ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:29.581453Z","caller":"traceutil/trace.go:171","msg":"trace[1008899564] linearizableReadLoop","detail":"{readStateIndex:24748; appliedIndex:24746; }","duration":"288.628972ms","start":"2026-02-26T03:50:29.292802Z","end":"2026-02-26T03:50:29.581431Z","steps":["trace[1008899564] 'read index received' (duration: 36.11833ms)","trace[1008899564] 'applied index is now lower than readState.Index' (duration: 252.509262ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:29.58154Z","caller":"traceutil/trace.go:171","msg":"trace[1488180318] transaction","detail":"{read_only:false; response_revision:23905; number_of_response:1; }","duration":"309.914291ms","start":"2026-02-26T03:50:29.271589Z","end":"2026-02-26T03:50:29.581503Z","steps":["trace[1488180318] 'process raft request' (duration: 309.670305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:29.581617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.793027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:29.581663Z","caller":"traceutil/trace.go:171","msg":"trace[747604814] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:23905; }","duration":"288.906479ms","start":"2026-02-26T03:50:29.292744Z","end":"2026-02-26T03:50:29.58165Z","steps":["trace[747604814] 'agreement among raft nodes before linearized reading' (duration: 288.803857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:29.581736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:29.271561Z","time spent":"310.065625ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:29.604596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.260443ms","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-02-26T03:50:29.604674Z","caller":"traceutil/trace.go:171","msg":"trace[403852491] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23906; }","duration":"231.376796ms","start":"2026-02-26T03:50:29.373277Z","end":"2026-02-26T03:50:29.604654Z","steps":["trace[403852491] 'agreement among raft nodes before linearized reading' (duration: 231.235222ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:29.604858Z","caller":"traceutil/trace.go:171","msg":"trace[695123349] transaction","detail":"{read_only:false; response_revision:23906; number_of_response:1; }","duration":"183.419779ms","start":"2026-02-26T03:50:29.421401Z","end":"2026-02-26T03:50:29.604821Z","steps":["trace[695123349] 'process raft request' (duration: 183.012168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:29.858586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.331517ms","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-02-26T03:50:29.858666Z","caller":"traceutil/trace.go:171","msg":"trace[439617306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23906; }","duration":"226.463021ms","start":"2026-02-26T03:50:29.632187Z","end":"2026-02-26T03:50:29.85865Z","steps":["trace[439617306] 'range keys from in-memory index tree' (duration: 226.111442ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:29.859115Z","caller":"traceutil/trace.go:171","msg":"trace[848295549] linearizableReadLoop","detail":"{readStateIndex:24750; appliedIndex:24749; }","duration":"120.786335ms","start":"2026-02-26T03:50:29.738316Z","end":"2026-02-26T03:50:29.859103Z","steps":["trace[848295549] 'read index received' (duration: 119.818659ms)","trace[848295549] 'applied index is now lower than readState.Index' (duration: 966.626µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:29.859204Z","caller":"traceutil/trace.go:171","msg":"trace[1682146053] transaction","detail":"{read_only:false; response_revision:23907; number_of_response:1; }","duration":"122.356027ms","start":"2026-02-26T03:50:29.736836Z","end":"2026-02-26T03:50:29.859192Z","steps":["trace[1682146053] 'process raft request' (duration: 121.282848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:29.859364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.057172ms","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-02-26T03:50:29.859396Z","caller":"traceutil/trace.go:171","msg":"trace[571134648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23907; }","duration":"121.139814ms","start":"2026-02-26T03:50:29.738248Z","end":"2026-02-26T03:50:29.859388Z","steps":["trace[571134648] 'agreement among raft nodes before linearized reading' (duration: 121.044831ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:29.972897Z","caller":"traceutil/trace.go:171","msg":"trace[1280219559] transaction","detail":"{read_only:false; response_revision:23909; number_of_response:1; }","duration":"108.30737ms","start":"2026-02-26T03:50:29.864568Z","end":"2026-02-26T03:50:29.972876Z","steps":["trace[1280219559] 'process raft request' (duration: 108.190207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:29.973153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.248275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra-registry\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-02-26T03:50:29.973273Z","caller":"traceutil/trace.go:171","msg":"trace[1359107515] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:23909; }","duration":"109.40566ms","start":"2026-02-26T03:50:29.863837Z","end":"2026-02-26T03:50:29.973243Z","steps":["trace[1359107515] 'agreement among raft nodes before linearized reading' (duration: 109.05364ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:30.134431Z","caller":"traceutil/trace.go:171","msg":"trace[674128625] transaction","detail":"{read_only:false; response_revision:23910; number_of_response:1; }","duration":"185.97471ms","start":"2026-02-26T03:50:29.948412Z","end":"2026-02-26T03:50:30.134387Z","steps":["trace[674128625] 'process raft request' (duration: 150.621251ms)","trace[674128625] 'compare' (duration: 35.130702ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:30.173872Z","caller":"traceutil/trace.go:171","msg":"trace[632185136] linearizableReadLoop","detail":"{readStateIndex:24756; appliedIndex:24752; }","duration":"103.474605ms","start":"2026-02-26T03:50:30.070373Z","end":"2026-02-26T03:50:30.173848Z","steps":["trace[632185136] 'read index received' (duration: 28.676544ms)","trace[632185136] 'applied index is now lower than readState.Index' (duration: 74.796631ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:30.173892Z","caller":"traceutil/trace.go:171","msg":"trace[733712673] transaction","detail":"{read_only:false; response_revision:23911; number_of_response:1; }","duration":"196.11058ms","start":"2026-02-26T03:50:29.977748Z","end":"2026-02-26T03:50:30.173858Z","steps":["trace[733712673] 'process raft request' (duration: 195.843333ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:30.174414Z","caller":"traceutil/trace.go:171","msg":"trace[668388563] transaction","detail":"{read_only:false; response_revision:23912; number_of_response:1; }","duration":"136.439989ms","start":"2026-02-26T03:50:30.037947Z","end":"2026-02-26T03:50:30.174387Z","steps":["trace[668388563] 'process raft request' (duration: 135.811241ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:30.174443Z","caller":"traceutil/trace.go:171","msg":"trace[986034271] transaction","detail":"{read_only:false; response_revision:23913; number_of_response:1; }","duration":"135.219634ms","start":"2026-02-26T03:50:30.039214Z","end":"2026-02-26T03:50:30.174434Z","steps":["trace[986034271] 'process raft request' (duration: 134.590417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:30.174433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.040801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-02-26T03:50:30.17469Z","caller":"traceutil/trace.go:171","msg":"trace[1579689184] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:23913; }","duration":"104.370779ms","start":"2026-02-26T03:50:30.070287Z","end":"2026-02-26T03:50:30.174658Z","steps":["trace[1579689184] 'agreement among raft nodes before linearized reading' (duration: 103.739532ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:30.41609Z","caller":"traceutil/trace.go:171","msg":"trace[182613643] linearizableReadLoop","detail":"{readStateIndex:24757; appliedIndex:24756; }","duration":"112.258199ms","start":"2026-02-26T03:50:30.303804Z","end":"2026-02-26T03:50:30.416062Z","steps":["trace[182613643] 'read index received' (duration: 108.625368ms)","trace[182613643] 'applied index is now lower than readState.Index' (duration: 3.63112ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:30.416119Z","caller":"traceutil/trace.go:171","msg":"trace[1787739419] transaction","detail":"{read_only:false; response_revision:23914; number_of_response:1; }","duration":"238.289218ms","start":"2026-02-26T03:50:30.177796Z","end":"2026-02-26T03:50:30.416086Z","steps":["trace[1787739419] 'process raft request' (duration: 234.655938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:30.416346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.533246ms","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-02-26T03:50:30.416395Z","caller":"traceutil/trace.go:171","msg":"trace[886397232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23914; }","duration":"112.643949ms","start":"2026-02-26T03:50:30.30374Z","end":"2026-02-26T03:50:30.416384Z","steps":["trace[886397232] 'agreement among raft nodes before linearized reading' (duration: 112.443164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:30.647874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.361805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:30.648159Z","caller":"traceutil/trace.go:171","msg":"trace[1796874405] transaction","detail":"{read_only:false; response_revision:23916; number_of_response:1; }","duration":"227.049467ms","start":"2026-02-26T03:50:30.421091Z","end":"2026-02-26T03:50:30.64814Z","steps":["trace[1796874405] 'process raft request' (duration: 226.966325ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:30.648322Z","caller":"traceutil/trace.go:171","msg":"trace[872610074] transaction","detail":"{read_only:false; response_revision:23915; number_of_response:1; }","duration":"323.58939ms","start":"2026-02-26T03:50:30.324712Z","end":"2026-02-26T03:50:30.648301Z","steps":["trace[872610074] 'process raft request' (duration: 199.6873ms)","trace[872610074] 'compare' (duration: 123.046127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:30.648423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:30.324677Z","time spent":"323.695153ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:30.648682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.26394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.1897af6251e3aca0\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-02-26T03:50:30.64881Z","caller":"traceutil/trace.go:171","msg":"trace[1479077073] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.1897af6251e3aca0; range_end:; response_count:1; response_revision:23916; }","duration":"228.434554ms","start":"2026-02-26T03:50:30.420347Z","end":"2026-02-26T03:50:30.648782Z","steps":["trace[1479077073] 'agreement among raft nodes before linearized reading' (duration: 228.108386ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:30.648221Z","caller":"traceutil/trace.go:171","msg":"trace[2026795701] linearizableReadLoop","detail":"{readStateIndex:24758; appliedIndex:24757; }","duration":"227.816828ms","start":"2026-02-26T03:50:30.420383Z","end":"2026-02-26T03:50:30.6482Z","steps":["trace[2026795701] 'read index received' (duration: 103.990519ms)","trace[2026795701] 'applied index is now lower than readState.Index' (duration: 123.823658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:30.648975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.405357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:30.649018Z","caller":"traceutil/trace.go:171","msg":"trace[274873790] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:23916; }","duration":"201.497249ms","start":"2026-02-26T03:50:30.447506Z","end":"2026-02-26T03:50:30.649003Z","steps":["trace[274873790] 'agreement among raft nodes before linearized reading' (duration: 201.413557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:30.649257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.933808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:30.649343Z","caller":"traceutil/trace.go:171","msg":"trace[1466882376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23916; }","duration":"130.094231ms","start":"2026-02-26T03:50:30.519229Z","end":"2026-02-26T03:50:30.649324Z","steps":["trace[1466882376] 'agreement among raft nodes before linearized reading' (duration: 129.930467ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:30.86106Z","caller":"traceutil/trace.go:171","msg":"trace[513289309] transaction","detail":"{read_only:false; response_revision:23919; number_of_response:1; }","duration":"133.169068ms","start":"2026-02-26T03:50:30.727855Z","end":"2026-02-26T03:50:30.861025Z","steps":["trace[513289309] 'process raft request' (duration: 131.922653ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:31.242995Z","caller":"traceutil/trace.go:171","msg":"trace[806273540] transaction","detail":"{read_only:false; response_revision:23920; number_of_response:1; }","duration":"308.171714ms","start":"2026-02-26T03:50:30.934801Z","end":"2026-02-26T03:50:31.242972Z","steps":["trace[806273540] 'process raft request' (duration: 307.583308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:31.243152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:30.934771Z","time spent":"308.298787ms","remote":"127.0.0.1:58208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:50:31.467604Z","caller":"traceutil/trace.go:171","msg":"trace[927045546] transaction","detail":"{read_only:false; response_revision:23921; number_of_response:1; }","duration":"119.065436ms","start":"2026-02-26T03:50:31.348481Z","end":"2026-02-26T03:50:31.467547Z","steps":["trace[927045546] 'process raft request' (duration: 118.089229ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:31.817085Z","caller":"traceutil/trace.go:171","msg":"trace[2004005633] transaction","detail":"{read_only:false; response_revision:23922; number_of_response:1; }","duration":"230.206284ms","start":"2026-02-26T03:50:31.58685Z","end":"2026-02-26T03:50:31.817056Z","steps":["trace[2004005633] 'process raft request' (duration: 230.020899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:32.049014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.021111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:32.049143Z","caller":"traceutil/trace.go:171","msg":"trace[598559678] linearizableReadLoop","detail":"{readStateIndex:24766; appliedIndex:24764; }","duration":"345.415284ms","start":"2026-02-26T03:50:31.7037Z","end":"2026-02-26T03:50:32.049115Z","steps":["trace[598559678] 'read index received' (duration: 113.263306ms)","trace[598559678] 'applied index is now lower than readState.Index' (duration: 232.150168ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:32.049382Z","caller":"traceutil/trace.go:171","msg":"trace[953664071] transaction","detail":"{read_only:false; response_revision:23923; number_of_response:1; }","duration":"438.555674ms","start":"2026-02-26T03:50:31.610597Z","end":"2026-02-26T03:50:32.049152Z","steps":["trace[953664071] 'process raft request' (duration: 321.288557ms)","trace[953664071] 'compare' (duration: 116.801144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:32.049553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:31.61058Z","time spent":"438.878612ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:32.049752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.054412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T03:50:32.049828Z","caller":"traceutil/trace.go:171","msg":"trace[613085511] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23923; }","duration":"346.189226ms","start":"2026-02-26T03:50:31.703615Z","end":"2026-02-26T03:50:32.049804Z","steps":["trace[613085511] 'agreement among raft nodes before linearized reading' (duration: 345.786165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:32.049888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:31.703592Z","time spent":"346.281648ms","remote":"127.0.0.1:39102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-26T03:50:32.063348Z","caller":"traceutil/trace.go:171","msg":"trace[1225301424] transaction","detail":"{read_only:false; response_revision:23925; number_of_response:1; }","duration":"194.001581ms","start":"2026-02-26T03:50:31.869314Z","end":"2026-02-26T03:50:32.063316Z","steps":["trace[1225301424] 'process raft request' (duration: 193.824046ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:32.063388Z","caller":"traceutil/trace.go:171","msg":"trace[921164346] transaction","detail":"{read_only:false; response_revision:23924; number_of_response:1; }","duration":"195.797202ms","start":"2026-02-26T03:50:31.867557Z","end":"2026-02-26T03:50:32.063354Z","steps":["trace[921164346] 'process raft request' (duration: 195.405212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:32.063466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.604069ms","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-02-26T03:50:32.063548Z","caller":"traceutil/trace.go:171","msg":"trace[961134152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23925; }","duration":"116.719352ms","start":"2026-02-26T03:50:31.946817Z","end":"2026-02-26T03:50:32.063536Z","steps":["trace[961134152] 'agreement among raft nodes before linearized reading' (duration: 116.554428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:32.382646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.244355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:32.382758Z","caller":"traceutil/trace.go:171","msg":"trace[782849137] linearizableReadLoop","detail":"{readStateIndex:24770; appliedIndex:24768; }","duration":"308.464801ms","start":"2026-02-26T03:50:32.074274Z","end":"2026-02-26T03:50:32.382739Z","steps":["trace[782849137] 'read index received' (duration: 20.288831ms)","trace[782849137] 'applied index is now lower than readState.Index' (duration: 288.17452ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:32.382811Z","caller":"traceutil/trace.go:171","msg":"trace[741800083] transaction","detail":"{read_only:false; response_revision:23927; number_of_response:1; }","duration":"311.554317ms","start":"2026-02-26T03:50:32.071191Z","end":"2026-02-26T03:50:32.382745Z","steps":["trace[741800083] 'process raft request' (duration: 202.093146ms)","trace[741800083] 'compare' (duration: 108.885495ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:32.383641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:32.071167Z","time spent":"312.40713ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:32.38286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.579434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:32.383849Z","caller":"traceutil/trace.go:171","msg":"trace[968099737] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:23927; }","duration":"309.555711ms","start":"2026-02-26T03:50:32.074248Z","end":"2026-02-26T03:50:32.383803Z","steps":["trace[968099737] 'agreement among raft nodes before linearized reading' (duration: 308.568204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:32.384086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:32.074238Z","time spent":"309.699696ms","remote":"127.0.0.1:39108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-02-26T03:50:32.386335Z","caller":"traceutil/trace.go:171","msg":"trace[1084487001] transaction","detail":"{read_only:false; response_revision:23928; number_of_response:1; }","duration":"205.705516ms","start":"2026-02-26T03:50:32.180608Z","end":"2026-02-26T03:50:32.386313Z","steps":["trace[1084487001] 'process raft request' (duration: 205.256624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:32.386388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.896209ms","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-02-26T03:50:32.386457Z","caller":"traceutil/trace.go:171","msg":"trace[463721873] transaction","detail":"{read_only:false; response_revision:23929; number_of_response:1; }","duration":"205.385107ms","start":"2026-02-26T03:50:32.181036Z","end":"2026-02-26T03:50:32.386421Z","steps":["trace[463721873] 'process raft request' (duration: 205.12433ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:32.386565Z","caller":"traceutil/trace.go:171","msg":"trace[1495633274] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23929; }","duration":"233.115635ms","start":"2026-02-26T03:50:32.153431Z","end":"2026-02-26T03:50:32.386546Z","steps":["trace[1495633274] 'agreement among raft nodes before linearized reading' (duration: 232.796027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:32.656658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.862097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:32.656742Z","caller":"traceutil/trace.go:171","msg":"trace[1787657709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23929; }","duration":"137.995791ms","start":"2026-02-26T03:50:32.518725Z","end":"2026-02-26T03:50:32.656721Z","steps":["trace[1787657709] 'range keys from in-memory index tree' (duration: 137.696783ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:32.810242Z","caller":"traceutil/trace.go:171","msg":"trace[1799503913] transaction","detail":"{read_only:false; response_revision:23931; number_of_response:1; }","duration":"148.520623ms","start":"2026-02-26T03:50:32.661679Z","end":"2026-02-26T03:50:32.810199Z","steps":["trace[1799503913] 'process raft request' (duration: 143.888444ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:32.810658Z","caller":"traceutil/trace.go:171","msg":"trace[224672811] transaction","detail":"{read_only:false; response_revision:23932; number_of_response:1; }","duration":"148.516183ms","start":"2026-02-26T03:50:32.662117Z","end":"2026-02-26T03:50:32.810633Z","steps":["trace[224672811] 'process raft request' (duration: 148.03567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:33.057568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.499738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:33.057749Z","caller":"traceutil/trace.go:171","msg":"trace[2113142175] transaction","detail":"{read_only:false; response_revision:23933; number_of_response:1; }","duration":"243.009639ms","start":"2026-02-26T03:50:32.814698Z","end":"2026-02-26T03:50:33.057708Z","steps":["trace[2113142175] 'process raft request' (duration: 109.275406ms)","trace[2113142175] 'compare' (duration: 133.340762ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:33.057817Z","caller":"traceutil/trace.go:171","msg":"trace[1827708700] linearizableReadLoop","detail":"{readStateIndex:24776; appliedIndex:24775; }","duration":"191.980275ms","start":"2026-02-26T03:50:32.865812Z","end":"2026-02-26T03:50:33.057792Z","steps":["trace[1827708700] 'read index received' (duration: 58.148289ms)","trace[1827708700] 'applied index is now lower than readState.Index' (duration: 133.829516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:33.336002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.288226ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-26T03:50:33.336414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.58163ms","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-02-26T03:50:33.337316Z","caller":"traceutil/trace.go:171","msg":"trace[1745172029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23933; }","duration":"471.518006ms","start":"2026-02-26T03:50:32.865754Z","end":"2026-02-26T03:50:33.337272Z","steps":["trace[1745172029] 'agreement among raft nodes before linearized reading' (duration: 192.11742ms)","trace[1745172029] 'range keys from in-memory index tree' (duration: 278.368597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:33.337424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:32.865731Z","time spent":"471.654759ms","remote":"127.0.0.1:39170","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-02-26T03:50:33.337786Z","caller":"traceutil/trace.go:171","msg":"trace[1899729605] linearizableReadLoop","detail":"{readStateIndex:24777; appliedIndex:24776; }","duration":"204.548934ms","start":"2026-02-26T03:50:33.133223Z","end":"2026-02-26T03:50:33.337772Z","steps":["trace[1899729605] 'read index received' (duration: 44.111µs)","trace[1899729605] 'applied index is now lower than readState.Index' (duration: 204.502663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:33.338008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.75961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-76bfd8c79d-mhlfp.1897af642a1de166\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-02-26T03:50:33.338064Z","caller":"traceutil/trace.go:171","msg":"trace[815316004] range","detail":"{range_begin:/registry/events/openstack/magnum-api-76bfd8c79d-mhlfp.1897af642a1de166; range_end:; response_count:1; response_revision:23934; }","duration":"204.831831ms","start":"2026-02-26T03:50:33.133217Z","end":"2026-02-26T03:50:33.338049Z","steps":["trace[815316004] 'agreement among raft nodes before linearized reading' (duration: 204.606295ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:33.337791Z","caller":"traceutil/trace.go:171","msg":"trace[1979178656] transaction","detail":"{read_only:false; response_revision:23934; number_of_response:1; }","duration":"183.948964ms","start":"2026-02-26T03:50:33.153818Z","end":"2026-02-26T03:50:33.337767Z","steps":["trace[1979178656] 'process raft request' (duration: 183.746368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:33.625129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.386021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:33.625434Z","caller":"traceutil/trace.go:171","msg":"trace[387671460] linearizableReadLoop","detail":"{readStateIndex:24780; appliedIndex:24778; }","duration":"190.227657ms","start":"2026-02-26T03:50:33.435187Z","end":"2026-02-26T03:50:33.625414Z","steps":["trace[387671460] 'read index received' (duration: 42.424804ms)","trace[387671460] 'applied index is now lower than readState.Index' (duration: 147.801743ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:33.62553Z","caller":"traceutil/trace.go:171","msg":"trace[925500902] transaction","detail":"{read_only:false; response_revision:23937; number_of_response:1; }","duration":"151.369002ms","start":"2026-02-26T03:50:33.474147Z","end":"2026-02-26T03:50:33.625516Z","steps":["trace[925500902] 'process raft request' (duration: 151.228028ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:33.625508Z","caller":"traceutil/trace.go:171","msg":"trace[1854819082] transaction","detail":"{read_only:false; response_revision:23936; number_of_response:1; }","duration":"283.560142ms","start":"2026-02-26T03:50:33.341923Z","end":"2026-02-26T03:50:33.625483Z","steps":["trace[1854819082] 'process raft request' (duration: 283.335366ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:33.625649Z","caller":"traceutil/trace.go:171","msg":"trace[1141608427] transaction","detail":"{read_only:false; response_revision:23935; number_of_response:1; }","duration":"283.786618ms","start":"2026-02-26T03:50:33.341853Z","end":"2026-02-26T03:50:33.62564Z","steps":["trace[1141608427] 'process raft request' (duration: 135.683127ms)","trace[1141608427] 'compare' (duration: 147.134124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:33.625723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.95677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:33.625793Z","caller":"traceutil/trace.go:171","msg":"trace[588679271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23937; }","duration":"108.072193ms","start":"2026-02-26T03:50:33.517703Z","end":"2026-02-26T03:50:33.625775Z","steps":["trace[588679271] 'agreement among raft nodes before linearized reading' (duration: 107.95647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:33.625912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.714991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-02-26T03:50:33.625936Z","caller":"traceutil/trace.go:171","msg":"trace[66424174] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:23937; }","duration":"190.782883ms","start":"2026-02-26T03:50:33.435144Z","end":"2026-02-26T03:50:33.625927Z","steps":["trace[66424174] 'agreement among raft nodes before linearized reading' (duration: 190.66389ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:34.151939Z","caller":"traceutil/trace.go:171","msg":"trace[1809556594] transaction","detail":"{read_only:false; response_revision:23938; number_of_response:1; }","duration":"327.948751ms","start":"2026-02-26T03:50:33.82397Z","end":"2026-02-26T03:50:34.151918Z","steps":["trace[1809556594] 'process raft request' (duration: 327.772356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:34.152087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:33.823934Z","time spent":"328.072395ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:34.389677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.333914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:34.389926Z","caller":"traceutil/trace.go:171","msg":"trace[1836847712] linearizableReadLoop","detail":"{readStateIndex:24784; appliedIndex:24781; }","duration":"290.568196ms","start":"2026-02-26T03:50:34.099334Z","end":"2026-02-26T03:50:34.389903Z","steps":["trace[1836847712] 'read index received' (duration: 52.395401ms)","trace[1836847712] 'applied index is now lower than readState.Index' (duration: 238.170975ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:34.39006Z","caller":"traceutil/trace.go:171","msg":"trace[647089757] transaction","detail":"{read_only:false; response_revision:23939; number_of_response:1; }","duration":"334.02133ms","start":"2026-02-26T03:50:34.056019Z","end":"2026-02-26T03:50:34.390041Z","steps":["trace[647089757] 'process raft request' (duration: 231.172012ms)","trace[647089757] 'compare' (duration: 102.129647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:34.39014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:34.055992Z","time spent":"334.108103ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:50:34.390691Z","caller":"traceutil/trace.go:171","msg":"trace[1769267778] transaction","detail":"{read_only:false; response_revision:23940; number_of_response:1; }","duration":"320.990219ms","start":"2026-02-26T03:50:34.069657Z","end":"2026-02-26T03:50:34.390647Z","steps":["trace[1769267778] 'process raft request' (duration: 320.159436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:34.390756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.393849ms","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-02-26T03:50:34.390835Z","caller":"traceutil/trace.go:171","msg":"trace[2002578024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23940; }","duration":"291.515082ms","start":"2026-02-26T03:50:34.099299Z","end":"2026-02-26T03:50:34.390814Z","steps":["trace[2002578024] 'agreement among raft nodes before linearized reading' (duration: 291.156822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:34.390917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:34.06964Z","time spent":"321.135723ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:50:34.749773Z","caller":"traceutil/trace.go:171","msg":"trace[1871854860] linearizableReadLoop","detail":"{readStateIndex:24788; appliedIndex:24787; }","duration":"230.331258ms","start":"2026-02-26T03:50:34.519419Z","end":"2026-02-26T03:50:34.749751Z","steps":["trace[1871854860] 'read index received' (duration: 230.285287ms)","trace[1871854860] 'applied index is now lower than readState.Index' (duration: 44.921µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:34.749966Z","caller":"traceutil/trace.go:171","msg":"trace[721138461] transaction","detail":"{read_only:false; response_revision:23943; number_of_response:1; }","duration":"353.451117ms","start":"2026-02-26T03:50:34.396498Z","end":"2026-02-26T03:50:34.74995Z","steps":["trace[721138461] 'process raft request' (duration: 353.16657ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:34.750041Z","caller":"traceutil/trace.go:171","msg":"trace[946294078] transaction","detail":"{read_only:false; response_revision:23944; number_of_response:1; }","duration":"353.153409ms","start":"2026-02-26T03:50:34.396849Z","end":"2026-02-26T03:50:34.750002Z","steps":["trace[946294078] 'process raft request' (duration: 352.861941ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:34.750003Z","caller":"traceutil/trace.go:171","msg":"trace[667938089] transaction","detail":"{read_only:false; response_revision:23942; number_of_response:1; }","duration":"356.144282ms","start":"2026-02-26T03:50:34.393851Z","end":"2026-02-26T03:50:34.749995Z","steps":["trace[667938089] 'process raft request' (duration: 355.520415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:34.750245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:34.396827Z","time spent":"353.306843ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:34.750316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:34.393822Z","time spent":"356.408239ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:34.75018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.737469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:34.750559Z","caller":"traceutil/trace.go:171","msg":"trace[1995814070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23944; }","duration":"231.203111ms","start":"2026-02-26T03:50:34.519337Z","end":"2026-02-26T03:50:34.75054Z","steps":["trace[1995814070] 'agreement among raft nodes before linearized reading' (duration: 230.616875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:34.750365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:34.396472Z","time spent":"353.631072ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:50:34.843761Z","caller":"traceutil/trace.go:171","msg":"trace[1192250926] transaction","detail":"{read_only:false; response_revision:23945; number_of_response:1; }","duration":"168.394274ms","start":"2026-02-26T03:50:34.675342Z","end":"2026-02-26T03:50:34.843736Z","steps":["trace[1192250926] 'process raft request' (duration: 166.585953ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:35.007697Z","caller":"traceutil/trace.go:171","msg":"trace[723934208] transaction","detail":"{read_only:false; response_revision:23946; number_of_response:1; }","duration":"145.494779ms","start":"2026-02-26T03:50:34.862149Z","end":"2026-02-26T03:50:35.007644Z","steps":["trace[723934208] 'process raft request' (duration: 144.290976ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:35.26787Z","caller":"traceutil/trace.go:171","msg":"trace[1312512024] transaction","detail":"{read_only:false; response_revision:23947; number_of_response:1; }","duration":"199.135984ms","start":"2026-02-26T03:50:35.0687Z","end":"2026-02-26T03:50:35.267835Z","steps":["trace[1312512024] 'process raft request' (duration: 118.704876ms)","trace[1312512024] 'compare' (duration: 80.031787ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:41.401762Z","caller":"traceutil/trace.go:171","msg":"trace[1818001125] transaction","detail":"{read_only:false; response_revision:23989; number_of_response:1; }","duration":"107.991661ms","start":"2026-02-26T03:50:41.293744Z","end":"2026-02-26T03:50:41.401735Z","steps":["trace[1818001125] 'process raft request' (duration: 107.796915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:41.743659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.579262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:41.744878Z","caller":"traceutil/trace.go:171","msg":"trace[1302213386] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:23989; }","duration":"241.866317ms","start":"2026-02-26T03:50:41.50299Z","end":"2026-02-26T03:50:41.744856Z","steps":["trace[1302213386] 'count revisions from in-memory index tree' (duration: 240.49686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:41.744391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.097658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:41.745049Z","caller":"traceutil/trace.go:171","msg":"trace[13679724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23989; }","duration":"227.756257ms","start":"2026-02-26T03:50:41.517257Z","end":"2026-02-26T03:50:41.745014Z","steps":["trace[13679724] 'range keys from in-memory index tree' (duration: 227.043607ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:42.265866Z","caller":"traceutil/trace.go:171","msg":"trace[1882805374] linearizableReadLoop","detail":"{readStateIndex:24836; appliedIndex:24835; }","duration":"440.462776ms","start":"2026-02-26T03:50:41.825376Z","end":"2026-02-26T03:50:42.265839Z","steps":["trace[1882805374] 'read index received' (duration: 440.337473ms)","trace[1882805374] 'applied index is now lower than readState.Index' (duration: 123.593µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:42.265959Z","caller":"traceutil/trace.go:171","msg":"trace[255375216] transaction","detail":"{read_only:false; response_revision:23991; number_of_response:1; }","duration":"516.43982ms","start":"2026-02-26T03:50:41.749474Z","end":"2026-02-26T03:50:42.265914Z","steps":["trace[255375216] 'process raft request' (duration: 516.197394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:42.26608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.671602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T03:50:42.266142Z","caller":"traceutil/trace.go:171","msg":"trace[648015668] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:23991; }","duration":"440.811646ms","start":"2026-02-26T03:50:41.825313Z","end":"2026-02-26T03:50:42.266125Z","steps":["trace[648015668] 'agreement among raft nodes before linearized reading' (duration: 440.639411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:42.266198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:41.825285Z","time spent":"440.897358ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":18,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-26T03:50:42.266198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:41.749456Z","time spent":"516.617895ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:50:42.298147Z","caller":"traceutil/trace.go:171","msg":"trace[1673853527] transaction","detail":"{read_only:false; response_revision:23992; number_of_response:1; }","duration":"219.259942ms","start":"2026-02-26T03:50:42.078857Z","end":"2026-02-26T03:50:42.298117Z","steps":["trace[1673853527] 'process raft request' (duration: 218.983194ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:42.298331Z","caller":"traceutil/trace.go:171","msg":"trace[1399636381] transaction","detail":"{read_only:false; response_revision:23993; number_of_response:1; }","duration":"114.639364ms","start":"2026-02-26T03:50:42.183677Z","end":"2026-02-26T03:50:42.298316Z","steps":["trace[1399636381] 'process raft request' (duration: 114.380927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:42.572662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.904783ms","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-02-26T03:50:42.572802Z","caller":"traceutil/trace.go:171","msg":"trace[1612298144] 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:23993; }","duration":"162.124469ms","start":"2026-02-26T03:50:42.410653Z","end":"2026-02-26T03:50:42.572777Z","steps":["trace[1612298144] 'range keys from in-memory index tree' (duration: 161.777469ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:42.572941Z","caller":"traceutil/trace.go:171","msg":"trace[1040886595] transaction","detail":"{read_only:false; response_revision:23994; number_of_response:1; }","duration":"149.660904ms","start":"2026-02-26T03:50:42.423247Z","end":"2026-02-26T03:50:42.572908Z","steps":["trace[1040886595] 'process raft request' (duration: 149.479039ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:42.573868Z","caller":"traceutil/trace.go:171","msg":"trace[804453928] transaction","detail":"{read_only:false; response_revision:23996; number_of_response:1; }","duration":"131.8102ms","start":"2026-02-26T03:50:42.442022Z","end":"2026-02-26T03:50:42.573832Z","steps":["trace[804453928] 'process raft request' (duration: 131.686706ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:42.573799Z","caller":"traceutil/trace.go:171","msg":"trace[1015575618] transaction","detail":"{read_only:false; response_revision:23995; number_of_response:1; }","duration":"149.751207ms","start":"2026-02-26T03:50:42.42403Z","end":"2026-02-26T03:50:42.573782Z","steps":["trace[1015575618] 'process raft request' (duration: 149.447689ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:43.01319Z","caller":"traceutil/trace.go:171","msg":"trace[1680243338] linearizableReadLoop","detail":"{readStateIndex:24842; appliedIndex:24841; }","duration":"213.697987ms","start":"2026-02-26T03:50:42.79946Z","end":"2026-02-26T03:50:43.013158Z","steps":["trace[1680243338] 'read index received' (duration: 144.550352ms)","trace[1680243338] 'applied index is now lower than readState.Index' (duration: 69.146185ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:43.013309Z","caller":"traceutil/trace.go:171","msg":"trace[278011362] transaction","detail":"{read_only:false; response_revision:23997; number_of_response:1; }","duration":"215.118107ms","start":"2026-02-26T03:50:42.798141Z","end":"2026-02-26T03:50:43.013259Z","steps":["trace[278011362] 'process raft request' (duration: 145.934971ms)","trace[278011362] 'compare' (duration: 68.748744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:43.013442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.958764ms","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-02-26T03:50:43.013512Z","caller":"traceutil/trace.go:171","msg":"trace[1597374398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23997; }","duration":"214.076358ms","start":"2026-02-26T03:50:42.799417Z","end":"2026-02-26T03:50:43.013494Z","steps":["trace[1597374398] 'agreement among raft nodes before linearized reading' (duration: 213.870432ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:43.267615Z","caller":"traceutil/trace.go:171","msg":"trace[1703216854] transaction","detail":"{read_only:false; response_revision:23998; number_of_response:1; }","duration":"371.141008ms","start":"2026-02-26T03:50:42.896437Z","end":"2026-02-26T03:50:43.267578Z","steps":["trace[1703216854] 'process raft request' (duration: 309.713777ms)","trace[1703216854] 'compare' (duration: 60.981168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:43.267837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:42.89641Z","time spent":"371.295701ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:50:43.270127Z","caller":"traceutil/trace.go:171","msg":"trace[1068868134] linearizableReadLoop","detail":"{readStateIndex:24844; appliedIndex:24842; }","duration":"256.855482ms","start":"2026-02-26T03:50:43.013258Z","end":"2026-02-26T03:50:43.270114Z","steps":["trace[1068868134] 'read index received' (duration: 193.050695ms)","trace[1068868134] 'applied index is now lower than readState.Index' (duration: 63.803777ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:43.270218Z","caller":"traceutil/trace.go:171","msg":"trace[1867057349] transaction","detail":"{read_only:false; response_revision:23999; number_of_response:1; }","duration":"251.812903ms","start":"2026-02-26T03:50:43.018366Z","end":"2026-02-26T03:50:43.270179Z","steps":["trace[1867057349] 'process raft request' (duration: 251.546095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:43.270317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.288053ms","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-02-26T03:50:43.270361Z","caller":"traceutil/trace.go:171","msg":"trace[1337781660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23999; }","duration":"470.386816ms","start":"2026-02-26T03:50:42.799962Z","end":"2026-02-26T03:50:43.270349Z","steps":["trace[1337781660] 'agreement among raft nodes before linearized reading' (duration: 470.248852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:43.270434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:42.799922Z","time spent":"470.503889ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T03:50:43.270615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.300562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-76bfd8c79d-mhlfp.1897af642a1de166\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-02-26T03:50:43.27065Z","caller":"traceutil/trace.go:171","msg":"trace[114451869] range","detail":"{range_begin:/registry/events/openstack/magnum-api-76bfd8c79d-mhlfp.1897af642a1de166; range_end:; response_count:1; response_revision:23999; }","duration":"137.374464ms","start":"2026-02-26T03:50:43.133262Z","end":"2026-02-26T03:50:43.270637Z","steps":["trace[114451869] 'agreement among raft nodes before linearized reading' (duration: 137.277521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:43.270726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.843347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-26T03:50:43.270854Z","caller":"traceutil/trace.go:171","msg":"trace[453715732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23999; }","duration":"225.043093ms","start":"2026-02-26T03:50:43.045782Z","end":"2026-02-26T03:50:43.270825Z","steps":["trace[453715732] 'agreement among raft nodes before linearized reading' (duration: 224.597731ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:48.558107Z","caller":"traceutil/trace.go:171","msg":"trace[1518812158] transaction","detail":"{read_only:false; response_revision:24090; number_of_response:1; }","duration":"215.757554ms","start":"2026-02-26T03:50:48.342331Z","end":"2026-02-26T03:50:48.558088Z","steps":["trace[1518812158] 'process raft request' (duration: 215.571599ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:48.558544Z","caller":"traceutil/trace.go:171","msg":"trace[686107144] transaction","detail":"{read_only:false; response_revision:24091; number_of_response:1; }","duration":"204.979225ms","start":"2026-02-26T03:50:48.353556Z","end":"2026-02-26T03:50:48.558536Z","steps":["trace[686107144] 'process raft request' (duration: 204.501772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:48.85698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.615289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:50:48.857124Z","caller":"traceutil/trace.go:171","msg":"trace[948903666] transaction","detail":"{read_only:false; response_revision:24092; number_of_response:1; }","duration":"262.942991ms","start":"2026-02-26T03:50:48.594158Z","end":"2026-02-26T03:50:48.857101Z","steps":["trace[948903666] 'process raft request' (duration: 146.086555ms)","trace[948903666] 'compare' (duration: 116.462625ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:48.864425Z","caller":"traceutil/trace.go:171","msg":"trace[1896158376] linearizableReadLoop","detail":"{readStateIndex:24941; appliedIndex:24938; }","duration":"186.797612ms","start":"2026-02-26T03:50:48.677609Z","end":"2026-02-26T03:50:48.864407Z","steps":["trace[1896158376] 'read index received' (duration: 62.649355ms)","trace[1896158376] 'applied index is now lower than readState.Index' (duration: 124.138367ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:48.864488Z","caller":"traceutil/trace.go:171","msg":"trace[1386575174] transaction","detail":"{read_only:false; response_revision:24093; number_of_response:1; }","duration":"269.902475ms","start":"2026-02-26T03:50:48.594562Z","end":"2026-02-26T03:50:48.864464Z","steps":["trace[1386575174] 'process raft request' (duration: 269.638006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:48.864576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.957468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T03:50:48.864651Z","caller":"traceutil/trace.go:171","msg":"trace[286094226] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24094; }","duration":"187.100921ms","start":"2026-02-26T03:50:48.677538Z","end":"2026-02-26T03:50:48.864639Z","steps":["trace[286094226] 'agreement among raft nodes before linearized reading' (duration: 186.972848ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:48.864577Z","caller":"traceutil/trace.go:171","msg":"trace[943827206] transaction","detail":"{read_only:false; response_revision:24094; number_of_response:1; }","duration":"269.240806ms","start":"2026-02-26T03:50:48.595296Z","end":"2026-02-26T03:50:48.864537Z","steps":["trace[943827206] 'process raft request' (duration: 269.0309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:48.892754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.285134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-979fa380-949d-4928-83a2-4a57a47ef1cc\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-02-26T03:50:48.892868Z","caller":"traceutil/trace.go:171","msg":"trace[720266911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-979fa380-949d-4928-83a2-4a57a47ef1cc; range_end:; response_count:1; response_revision:24095; }","duration":"162.420877ms","start":"2026-02-26T03:50:48.730424Z","end":"2026-02-26T03:50:48.892844Z","steps":["trace[720266911] 'agreement among raft nodes before linearized reading' (duration: 162.088598ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:49.236465Z","caller":"traceutil/trace.go:171","msg":"trace[1550552027] transaction","detail":"{read_only:false; response_revision:24096; number_of_response:1; }","duration":"336.97465ms","start":"2026-02-26T03:50:48.899456Z","end":"2026-02-26T03:50:49.23643Z","steps":["trace[1550552027] 'process raft request' (duration: 336.744234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:49.236666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:48.899427Z","time spent":"337.125705ms","remote":"127.0.0.1:39102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:50:49.375689Z","caller":"traceutil/trace.go:171","msg":"trace[1306726834] linearizableReadLoop","detail":"{readStateIndex:24944; appliedIndex:24943; }","duration":"118.425319ms","start":"2026-02-26T03:50:49.257242Z","end":"2026-02-26T03:50:49.375667Z","steps":["trace[1306726834] 'read index received' (duration: 118.349087ms)","trace[1306726834] 'applied index is now lower than readState.Index' (duration: 75.182µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:49.375798Z","caller":"traceutil/trace.go:171","msg":"trace[1768414657] transaction","detail":"{read_only:false; response_revision:24097; number_of_response:1; }","duration":"339.14117ms","start":"2026-02-26T03:50:49.036646Z","end":"2026-02-26T03:50:49.375787Z","steps":["trace[1768414657] 'process raft request' (duration: 338.865053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:49.375909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:50:49.036614Z","time spent":"339.202372ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:50:49.376002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.730587ms","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-02-26T03:50:49.376101Z","caller":"traceutil/trace.go:171","msg":"trace[344903468] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:24097; }","duration":"118.890812ms","start":"2026-02-26T03:50:49.257191Z","end":"2026-02-26T03:50:49.376082Z","steps":["trace[344903468] 'agreement among raft nodes before linearized reading' (duration: 118.711117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:49.377655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.091936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-02-26T03:50:49.377673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.31557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-979fa380-949d-4928-83a2-4a57a47ef1cc\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-02-26T03:50:49.377763Z","caller":"traceutil/trace.go:171","msg":"trace[161238239] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24098; }","duration":"116.27337ms","start":"2026-02-26T03:50:49.261464Z","end":"2026-02-26T03:50:49.377737Z","steps":["trace[161238239] 'agreement among raft nodes before linearized reading' (duration: 115.979112ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:49.377787Z","caller":"traceutil/trace.go:171","msg":"trace[2068484812] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-979fa380-949d-4928-83a2-4a57a47ef1cc; range_end:; response_count:1; response_revision:24098; }","duration":"108.509925ms","start":"2026-02-26T03:50:49.269256Z","end":"2026-02-26T03:50:49.377766Z","steps":["trace[2068484812] 'agreement among raft nodes before linearized reading' (duration: 108.247098ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:51.151508Z","caller":"traceutil/trace.go:171","msg":"trace[949160962] transaction","detail":"{read_only:false; response_revision:24112; number_of_response:1; }","duration":"280.392454ms","start":"2026-02-26T03:50:50.871089Z","end":"2026-02-26T03:50:51.151481Z","steps":["trace[949160962] 'process raft request' (duration: 280.182958ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:51.151724Z","caller":"traceutil/trace.go:171","msg":"trace[1824437077] linearizableReadLoop","detail":"{readStateIndex:24960; appliedIndex:24958; }","duration":"193.750054ms","start":"2026-02-26T03:50:50.957942Z","end":"2026-02-26T03:50:51.151692Z","steps":["trace[1824437077] 'read index received' (duration: 193.307142ms)","trace[1824437077] 'applied index is now lower than readState.Index' (duration: 441.222µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:51.151819Z","caller":"traceutil/trace.go:171","msg":"trace[1859779572] transaction","detail":"{read_only:false; response_revision:24113; number_of_response:1; }","duration":"280.60867ms","start":"2026-02-26T03:50:50.87117Z","end":"2026-02-26T03:50:51.151779Z","steps":["trace[1859779572] 'process raft request' (duration: 280.270831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:50:51.151893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.92003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:51.151948Z","caller":"traceutil/trace.go:171","msg":"trace[1748800358] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:24113; }","duration":"194.037323ms","start":"2026-02-26T03:50:50.957882Z","end":"2026-02-26T03:50:51.151919Z","steps":["trace[1748800358] 'agreement among raft nodes before linearized reading' (duration: 193.919119ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:55.673774Z","caller":"traceutil/trace.go:171","msg":"trace[1940159347] linearizableReadLoop","detail":"{readStateIndex:25015; appliedIndex:25014; }","duration":"154.636161ms","start":"2026-02-26T03:50:55.519104Z","end":"2026-02-26T03:50:55.67374Z","steps":["trace[1940159347] 'read index received' (duration: 99.323589ms)","trace[1940159347] 'applied index is now lower than readState.Index' (duration: 55.311142ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:50:55.673936Z","caller":"traceutil/trace.go:171","msg":"trace[830779516] transaction","detail":"{read_only:false; response_revision:24167; number_of_response:1; }","duration":"181.400722ms","start":"2026-02-26T03:50:55.492515Z","end":"2026-02-26T03:50:55.673916Z","steps":["trace[830779516] 'process raft request' (duration: 125.893735ms)","trace[830779516] 'compare' (duration: 54.963232ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:50:55.674156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.036703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:50:55.674195Z","caller":"traceutil/trace.go:171","msg":"trace[2038182417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24167; }","duration":"155.115754ms","start":"2026-02-26T03:50:55.519069Z","end":"2026-02-26T03:50:55.674185Z","steps":["trace[2038182417] 'agreement among raft nodes before linearized reading' (duration: 155.023192ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:55.936569Z","caller":"traceutil/trace.go:171","msg":"trace[1346386967] transaction","detail":"{read_only:false; response_revision:24168; number_of_response:1; }","duration":"141.955582ms","start":"2026-02-26T03:50:55.794588Z","end":"2026-02-26T03:50:55.936543Z","steps":["trace[1346386967] 'process raft request' (duration: 141.748526ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:50:56.213541Z","caller":"traceutil/trace.go:171","msg":"trace[1753484182] transaction","detail":"{read_only:false; response_revision:24169; number_of_response:1; }","duration":"148.662446ms","start":"2026-02-26T03:50:56.064853Z","end":"2026-02-26T03:50:56.213515Z","steps":["trace[1753484182] 'process raft request' (duration: 148.487431ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:12.840073Z","caller":"traceutil/trace.go:171","msg":"trace[733214005] linearizableReadLoop","detail":"{readStateIndex:25142; appliedIndex:25141; }","duration":"196.608055ms","start":"2026-02-26T03:51:12.643439Z","end":"2026-02-26T03:51:12.840047Z","steps":["trace[733214005] 'read index received' (duration: 196.334667ms)","trace[733214005] 'applied index is now lower than readState.Index' (duration: 272.248µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:12.840281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.81374ms","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-02-26T03:51:12.840331Z","caller":"traceutil/trace.go:171","msg":"trace[359515490] 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:24291; }","duration":"196.936923ms","start":"2026-02-26T03:51:12.643381Z","end":"2026-02-26T03:51:12.840318Z","steps":["trace[359515490] 'agreement among raft nodes before linearized reading' (duration: 196.780099ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:12.840882Z","caller":"traceutil/trace.go:171","msg":"trace[907468641] transaction","detail":"{read_only:false; response_revision:24291; number_of_response:1; }","duration":"197.980482ms","start":"2026-02-26T03:51:12.64289Z","end":"2026-02-26T03:51:12.84087Z","steps":["trace[907468641] 'process raft request' (duration: 196.976815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.311386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.65806ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T03:51:13.312095Z","caller":"traceutil/trace.go:171","msg":"trace[1581072690] linearizableReadLoop","detail":"{readStateIndex:25144; appliedIndex:25142; }","duration":"178.160422ms","start":"2026-02-26T03:51:13.133903Z","end":"2026-02-26T03:51:13.312064Z","steps":["trace[1581072690] 'read index received' (duration: 43.192165ms)","trace[1581072690] 'applied index is now lower than readState.Index' (duration: 134.966347ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:13.312125Z","caller":"traceutil/trace.go:171","msg":"trace[308074688] transaction","detail":"{read_only:false; response_revision:24292; number_of_response:1; }","duration":"272.725071ms","start":"2026-02-26T03:51:13.039371Z","end":"2026-02-26T03:51:13.312096Z","steps":["trace[308074688] 'process raft request' (duration: 272.151815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.4549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.958867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-76bfd8c79d-mhlfp.1897af642a1de166\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-02-26T03:51:13.455005Z","caller":"traceutil/trace.go:171","msg":"trace[1337957592] range","detail":"{range_begin:/registry/events/openstack/magnum-api-76bfd8c79d-mhlfp.1897af642a1de166; range_end:; response_count:1; response_revision:24292; }","duration":"321.08968ms","start":"2026-02-26T03:51:13.133885Z","end":"2026-02-26T03:51:13.454975Z","steps":["trace[1337957592] 'agreement among raft nodes before linearized reading' (duration: 178.352779ms)","trace[1337957592] 'range keys from in-memory index tree' (duration: 142.436924ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:13.455062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.133816Z","time spent":"321.235236ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":805,"request content":"key:\"/registry/events/openstack/magnum-api-76bfd8c79d-mhlfp.1897af642a1de166\" "} {"level":"warn","ts":"2026-02-26T03:51:13.455107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.956809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:13.455258Z","caller":"traceutil/trace.go:171","msg":"trace[1581580402] linearizableReadLoop","detail":"{readStateIndex:25146; appliedIndex:25144; }","duration":"143.038881ms","start":"2026-02-26T03:51:13.312204Z","end":"2026-02-26T03:51:13.455243Z","steps":["trace[1581580402] 'read index received' (duration: 9.444091ms)","trace[1581580402] 'applied index is now lower than readState.Index' (duration: 133.59341ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:13.455347Z","caller":"traceutil/trace.go:171","msg":"trace[1926331018] transaction","detail":"{read_only:false; response_revision:24294; number_of_response:1; }","duration":"201.505859ms","start":"2026-02-26T03:51:13.253799Z","end":"2026-02-26T03:51:13.455304Z","steps":["trace[1926331018] 'process raft request' (duration: 201.401067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.455429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.859606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T03:51:13.455453Z","caller":"traceutil/trace.go:171","msg":"trace[341507074] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24294; }","duration":"272.922238ms","start":"2026-02-26T03:51:13.182524Z","end":"2026-02-26T03:51:13.455446Z","steps":["trace[341507074] 'agreement among raft nodes before linearized reading' (duration: 272.799405ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:13.455522Z","caller":"traceutil/trace.go:171","msg":"trace[1639496365] transaction","detail":"{read_only:false; response_revision:24293; number_of_response:1; }","duration":"208.319139ms","start":"2026-02-26T03:51:13.247186Z","end":"2026-02-26T03:51:13.455505Z","steps":["trace[1639496365] 'process raft request' (duration: 64.867466ms)","trace[1639496365] 'compare' (duration: 142.832925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:13.455668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.878001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:51:13.455704Z","caller":"traceutil/trace.go:171","msg":"trace[1986863406] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24294; }","duration":"283.921892ms","start":"2026-02-26T03:51:13.171773Z","end":"2026-02-26T03:51:13.455695Z","steps":["trace[1986863406] 'agreement among raft nodes before linearized reading' (duration: 283.84924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.826441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.951504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:13.826738Z","caller":"traceutil/trace.go:171","msg":"trace[155160251] transaction","detail":"{read_only:false; response_revision:24296; number_of_response:1; }","duration":"364.564945ms","start":"2026-02-26T03:51:13.462136Z","end":"2026-02-26T03:51:13.826701Z","steps":["trace[155160251] 'process raft request' (duration: 198.211959ms)","trace[155160251] 'compare' (duration: 165.287477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:13.826882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.462129Z","time spent":"364.687518ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:51:13.826869Z","caller":"traceutil/trace.go:171","msg":"trace[48797657] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"363.66262ms","start":"2026-02-26T03:51:13.463157Z","end":"2026-02-26T03:51:13.826819Z","steps":["trace[48797657] 'process raft request' (duration: 363.456854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.826981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.026447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f57edee4-6f07-4beb-b1d0-0dafb0671403\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-02-26T03:51:13.827054Z","caller":"traceutil/trace.go:171","msg":"trace[421128559] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f57edee4-6f07-4beb-b1d0-0dafb0671403; range_end:; response_count:1; response_revision:24297; }","duration":"365.110349ms","start":"2026-02-26T03:51:13.461925Z","end":"2026-02-26T03:51:13.827035Z","steps":["trace[421128559] 'agreement among raft nodes before linearized reading' (duration: 364.881033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.827081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.46315Z","time spent":"363.816414ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:51:13.827108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.461917Z","time spent":"365.177102ms","remote":"127.0.0.1:39058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1338,"request content":"key:\"/registry/persistentvolumes/pvc-f57edee4-6f07-4beb-b1d0-0dafb0671403\" "} {"level":"info","ts":"2026-02-26T03:51:13.826767Z","caller":"traceutil/trace.go:171","msg":"trace[1701402828] linearizableReadLoop","detail":"{readStateIndex:25149; appliedIndex:25147; }","duration":"358.475576ms","start":"2026-02-26T03:51:13.468273Z","end":"2026-02-26T03:51:13.826749Z","steps":["trace[1701402828] 'read index received' (duration: 191.973336ms)","trace[1701402828] 'applied index is now lower than readState.Index' (duration: 166.50057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:13.827382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.747625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18630"} {"level":"info","ts":"2026-02-26T03:51:13.827474Z","caller":"traceutil/trace.go:171","msg":"trace[420567558] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24297; }","duration":"357.855389ms","start":"2026-02-26T03:51:13.469602Z","end":"2026-02-26T03:51:13.827457Z","steps":["trace[420567558] 'agreement among raft nodes before linearized reading' (duration: 357.661103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.827516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.469595Z","time spent":"357.90897ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18652,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-26T03:51:13.827602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.439038ms","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":"warn","ts":"2026-02-26T03:51:13.827686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.448101ms","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-02-26T03:51:13.827717Z","caller":"traceutil/trace.go:171","msg":"trace[1081424506] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24297; }","duration":"274.598704ms","start":"2026-02-26T03:51:13.553093Z","end":"2026-02-26T03:51:13.827691Z","steps":["trace[1081424506] 'agreement among raft nodes before linearized reading' (duration: 274.308025ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:13.827759Z","caller":"traceutil/trace.go:171","msg":"trace[112411762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24297; }","duration":"291.541663ms","start":"2026-02-26T03:51:13.536199Z","end":"2026-02-26T03:51:13.82774Z","steps":["trace[112411762] 'agreement among raft nodes before linearized reading' (duration: 291.363817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.827827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.82157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:51:13.827908Z","caller":"traceutil/trace.go:171","msg":"trace[1027827426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24297; }","duration":"309.968353ms","start":"2026-02-26T03:51:13.517916Z","end":"2026-02-26T03:51:13.827885Z","steps":["trace[1027827426] 'agreement among raft nodes before linearized reading' (duration: 309.796029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.827937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.528802ms","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-02-26T03:51:13.827979Z","caller":"traceutil/trace.go:171","msg":"trace[1811489482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24297; }","duration":"300.592124ms","start":"2026-02-26T03:51:13.527373Z","end":"2026-02-26T03:51:13.827965Z","steps":["trace[1811489482] 'agreement among raft nodes before linearized reading' (duration: 300.497942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.827972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.869754ms","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-02-26T03:51:13.828015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.527363Z","time spent":"300.645446ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T03:51:13.828034Z","caller":"traceutil/trace.go:171","msg":"trace[1554965704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24297; }","duration":"286.963917ms","start":"2026-02-26T03:51:13.541056Z","end":"2026-02-26T03:51:13.82802Z","steps":["trace[1554965704] 'agreement among raft nodes before linearized reading' (duration: 286.816453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:13.828049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.813843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10853"} {"level":"warn","ts":"2026-02-26T03:51:13.827958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.517894Z","time spent":"310.053166ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:51:13.828107Z","caller":"traceutil/trace.go:171","msg":"trace[761332784] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24297; }","duration":"268.882625ms","start":"2026-02-26T03:51:13.55921Z","end":"2026-02-26T03:51:13.828093Z","steps":["trace[761332784] 'agreement among raft nodes before linearized reading' (duration: 268.755611ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:13.956696Z","caller":"traceutil/trace.go:171","msg":"trace[1841721655] transaction","detail":"{read_only:false; response_revision:24298; number_of_response:1; }","duration":"123.469219ms","start":"2026-02-26T03:51:13.833193Z","end":"2026-02-26T03:51:13.956662Z","steps":["trace[1841721655] 'process raft request' (duration: 122.08581ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.026824Z","caller":"traceutil/trace.go:171","msg":"trace[1377750996] transaction","detail":"{read_only:false; response_revision:24299; number_of_response:1; }","duration":"193.56859ms","start":"2026-02-26T03:51:13.833224Z","end":"2026-02-26T03:51:14.026792Z","steps":["trace[1377750996] 'process raft request' (duration: 193.364864ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.027248Z","caller":"traceutil/trace.go:171","msg":"trace[506144276] transaction","detail":"{read_only:false; response_revision:24300; number_of_response:1; }","duration":"193.869518ms","start":"2026-02-26T03:51:13.83335Z","end":"2026-02-26T03:51:14.027219Z","steps":["trace[506144276] 'process raft request' (duration: 193.404435ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.027312Z","caller":"traceutil/trace.go:171","msg":"trace[1735081085] transaction","detail":"{read_only:false; response_revision:24301; number_of_response:1; }","duration":"193.832237ms","start":"2026-02-26T03:51:13.833446Z","end":"2026-02-26T03:51:14.027279Z","steps":["trace[1735081085] 'process raft request' (duration: 193.603921ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.027611Z","caller":"traceutil/trace.go:171","msg":"trace[1804431477] transaction","detail":"{read_only:false; response_revision:24302; number_of_response:1; }","duration":"190.606268ms","start":"2026-02-26T03:51:13.836989Z","end":"2026-02-26T03:51:14.027595Z","steps":["trace[1804431477] 'process raft request' (duration: 190.226908ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.027684Z","caller":"traceutil/trace.go:171","msg":"trace[1173156726] linearizableReadLoop","detail":"{readStateIndex:25155; appliedIndex:25149; }","duration":"115.263982ms","start":"2026-02-26T03:51:13.912407Z","end":"2026-02-26T03:51:14.027671Z","steps":["trace[1173156726] 'read index received' (duration: 42.890968ms)","trace[1173156726] 'applied index is now lower than readState.Index' (duration: 72.371734ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:14.027846Z","caller":"traceutil/trace.go:171","msg":"trace[1823225121] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"187.233234ms","start":"2026-02-26T03:51:13.840598Z","end":"2026-02-26T03:51:14.027832Z","steps":["trace[1823225121] 'process raft request' (duration: 186.819033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.027921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.503758ms","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-02-26T03:51:14.028068Z","caller":"traceutil/trace.go:171","msg":"trace[2075552875] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24303; }","duration":"115.710154ms","start":"2026-02-26T03:51:13.912346Z","end":"2026-02-26T03:51:14.028057Z","steps":["trace[2075552875] 'agreement among raft nodes before linearized reading' (duration: 115.493928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.273374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.715719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:14.273508Z","caller":"traceutil/trace.go:171","msg":"trace[721398328] linearizableReadLoop","detail":"{readStateIndex:25156; appliedIndex:25155; }","duration":"245.764316ms","start":"2026-02-26T03:51:14.027721Z","end":"2026-02-26T03:51:14.273486Z","steps":["trace[721398328] 'read index received' (duration: 92.775549ms)","trace[721398328] 'applied index is now lower than readState.Index' (duration: 152.986887ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:14.273756Z","caller":"traceutil/trace.go:171","msg":"trace[571896798] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"272.366572ms","start":"2026-02-26T03:51:14.001375Z","end":"2026-02-26T03:51:14.273742Z","steps":["trace[571896798] 'process raft request' (duration: 119.16552ms)","trace[571896798] 'compare' (duration: 152.493793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:14.274535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.654725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-02-26T03:51:14.274648Z","caller":"traceutil/trace.go:171","msg":"trace[1693245988] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24304; }","duration":"335.84751ms","start":"2026-02-26T03:51:13.938767Z","end":"2026-02-26T03:51:14.274615Z","steps":["trace[1693245988] 'agreement among raft nodes before linearized reading' (duration: 335.149381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.274691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:13.93875Z","time spent":"335.933783ms","remote":"127.0.0.1:39066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-02-26T03:51:14.284217Z","caller":"traceutil/trace.go:171","msg":"trace[2079885589] transaction","detail":"{read_only:false; response_revision:24305; number_of_response:1; }","duration":"250.2714ms","start":"2026-02-26T03:51:14.033926Z","end":"2026-02-26T03:51:14.284197Z","steps":["trace[2079885589] 'process raft request' (duration: 250.053034ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.284307Z","caller":"traceutil/trace.go:171","msg":"trace[1625239877] transaction","detail":"{read_only:false; response_revision:24306; number_of_response:1; }","duration":"250.164238ms","start":"2026-02-26T03:51:14.034119Z","end":"2026-02-26T03:51:14.284283Z","steps":["trace[1625239877] 'process raft request' (duration: 250.010113ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.284515Z","caller":"traceutil/trace.go:171","msg":"trace[1579105213] transaction","detail":"{read_only:false; response_revision:24307; number_of_response:1; }","duration":"247.828433ms","start":"2026-02-26T03:51:14.036677Z","end":"2026-02-26T03:51:14.284506Z","steps":["trace[1579105213] 'process raft request' (duration: 247.502003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.284689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.732123ms","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-02-26T03:51:14.284761Z","caller":"traceutil/trace.go:171","msg":"trace[2066581839] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24308; }","duration":"250.859547ms","start":"2026-02-26T03:51:14.03389Z","end":"2026-02-26T03:51:14.284749Z","steps":["trace[2066581839] 'agreement among raft nodes before linearized reading' (duration: 250.741554ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.284857Z","caller":"traceutil/trace.go:171","msg":"trace[177323651] transaction","detail":"{read_only:false; response_revision:24308; number_of_response:1; }","duration":"243.648896ms","start":"2026-02-26T03:51:14.041197Z","end":"2026-02-26T03:51:14.284846Z","steps":["trace[177323651] 'process raft request' (duration: 243.024359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.284925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.588398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:51:14.284948Z","caller":"traceutil/trace.go:171","msg":"trace[34869646] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24308; }","duration":"103.658081ms","start":"2026-02-26T03:51:14.181279Z","end":"2026-02-26T03:51:14.284938Z","steps":["trace[34869646] 'agreement among raft nodes before linearized reading' (duration: 103.61515ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.502842Z","caller":"traceutil/trace.go:171","msg":"trace[121809562] linearizableReadLoop","detail":"{readStateIndex:25161; appliedIndex:25160; }","duration":"200.338998ms","start":"2026-02-26T03:51:14.302483Z","end":"2026-02-26T03:51:14.502822Z","steps":["trace[121809562] 'read index received' (duration: 200.159703ms)","trace[121809562] 'applied index is now lower than readState.Index' (duration: 178.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:14.50293Z","caller":"traceutil/trace.go:171","msg":"trace[651241120] transaction","detail":"{read_only:false; response_revision:24309; number_of_response:1; }","duration":"202.675823ms","start":"2026-02-26T03:51:14.300212Z","end":"2026-02-26T03:51:14.502888Z","steps":["trace[651241120] 'process raft request' (duration: 202.453946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.503142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.722586ms","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-02-26T03:51:14.503238Z","caller":"traceutil/trace.go:171","msg":"trace[150794329] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24309; }","duration":"166.884511ms","start":"2026-02-26T03:51:14.336331Z","end":"2026-02-26T03:51:14.503216Z","steps":["trace[150794329] 'agreement among raft nodes before linearized reading' (duration: 166.679006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.503504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.008426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-02-26T03:51:14.503549Z","caller":"traceutil/trace.go:171","msg":"trace[1754537358] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24309; }","duration":"201.078838ms","start":"2026-02-26T03:51:14.302455Z","end":"2026-02-26T03:51:14.503534Z","steps":["trace[1754537358] 'agreement among raft nodes before linearized reading' (duration: 200.501012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.794678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.723525ms","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-02-26T03:51:14.794866Z","caller":"traceutil/trace.go:171","msg":"trace[32574125] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24310; }","duration":"145.933471ms","start":"2026-02-26T03:51:14.648909Z","end":"2026-02-26T03:51:14.794843Z","steps":["trace[32574125] 'range keys from in-memory index tree' (duration: 145.701114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:14.794919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.276826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:14.795063Z","caller":"traceutil/trace.go:171","msg":"trace[782249249] transaction","detail":"{read_only:false; response_revision:24311; number_of_response:1; }","duration":"209.699547ms","start":"2026-02-26T03:51:14.585341Z","end":"2026-02-26T03:51:14.79504Z","steps":["trace[782249249] 'process raft request' (duration: 74.209444ms)","trace[782249249] 'compare' (duration: 135.127413ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:14.795034Z","caller":"traceutil/trace.go:171","msg":"trace[259520337] linearizableReadLoop","detail":"{readStateIndex:25163; appliedIndex:25162; }","duration":"205.82356ms","start":"2026-02-26T03:51:14.589195Z","end":"2026-02-26T03:51:14.795018Z","steps":["trace[259520337] 'read index received' (duration: 70.327877ms)","trace[259520337] 'applied index is now lower than readState.Index' (duration: 135.494413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:14.795361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.492067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-26T03:51:14.79535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.138388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-02-26T03:51:14.79541Z","caller":"traceutil/trace.go:171","msg":"trace[1202561267] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24311; }","duration":"133.610539ms","start":"2026-02-26T03:51:14.661788Z","end":"2026-02-26T03:51:14.795399Z","steps":["trace[1202561267] 'agreement among raft nodes before linearized reading' (duration: 133.526747ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.795431Z","caller":"traceutil/trace.go:171","msg":"trace[196343218] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24311; }","duration":"206.291413ms","start":"2026-02-26T03:51:14.589119Z","end":"2026-02-26T03:51:14.79541Z","steps":["trace[196343218] 'agreement among raft nodes before linearized reading' (duration: 205.990454ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.956173Z","caller":"traceutil/trace.go:171","msg":"trace[1784503559] transaction","detail":"{read_only:false; response_revision:24313; number_of_response:1; }","duration":"125.233037ms","start":"2026-02-26T03:51:14.830901Z","end":"2026-02-26T03:51:14.956134Z","steps":["trace[1784503559] 'process raft request' (duration: 124.692812ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.956457Z","caller":"traceutil/trace.go:171","msg":"trace[242417120] linearizableReadLoop","detail":"{readStateIndex:25165; appliedIndex:25164; }","duration":"122.201893ms","start":"2026-02-26T03:51:14.83423Z","end":"2026-02-26T03:51:14.956432Z","steps":["trace[242417120] 'read index received' (duration: 121.530945ms)","trace[242417120] 'applied index is now lower than readState.Index' (duration: 280.777µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:14.956597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.331207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10673"} {"level":"info","ts":"2026-02-26T03:51:14.956678Z","caller":"traceutil/trace.go:171","msg":"trace[1532134710] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24313; }","duration":"122.459371ms","start":"2026-02-26T03:51:14.834199Z","end":"2026-02-26T03:51:14.956658Z","steps":["trace[1532134710] 'agreement among raft nodes before linearized reading' (duration: 122.037669ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:14.971383Z","caller":"traceutil/trace.go:171","msg":"trace[2107562448] transaction","detail":"{read_only:false; response_revision:24314; number_of_response:1; }","duration":"124.672733ms","start":"2026-02-26T03:51:14.846687Z","end":"2026-02-26T03:51:14.97136Z","steps":["trace[2107562448] 'process raft request' (duration: 124.522419ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:15.43521Z","caller":"traceutil/trace.go:171","msg":"trace[2057812012] transaction","detail":"{read_only:false; response_revision:24315; number_of_response:1; }","duration":"118.388018ms","start":"2026-02-26T03:51:15.316796Z","end":"2026-02-26T03:51:15.435184Z","steps":["trace[2057812012] 'process raft request' (duration: 117.896044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:15.681295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.952917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:15.68141Z","caller":"traceutil/trace.go:171","msg":"trace[1030848915] transaction","detail":"{read_only:false; response_revision:24316; number_of_response:1; }","duration":"220.66901ms","start":"2026-02-26T03:51:15.460725Z","end":"2026-02-26T03:51:15.681394Z","steps":["trace[1030848915] 'process raft request' (duration: 85.529829ms)","trace[1030848915] 'compare' (duration: 134.269438ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:15.762053Z","caller":"traceutil/trace.go:171","msg":"trace[925015507] linearizableReadLoop","detail":"{readStateIndex:25169; appliedIndex:25167; }","duration":"245.290702ms","start":"2026-02-26T03:51:15.516742Z","end":"2026-02-26T03:51:15.762032Z","steps":["trace[925015507] 'read index received' (duration: 29.533478ms)","trace[925015507] 'applied index is now lower than readState.Index' (duration: 215.756154ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:15.762099Z","caller":"traceutil/trace.go:171","msg":"trace[1293049771] transaction","detail":"{read_only:false; response_revision:24317; number_of_response:1; }","duration":"300.209023ms","start":"2026-02-26T03:51:15.461861Z","end":"2026-02-26T03:51:15.76207Z","steps":["trace[1293049771] 'process raft request' (duration: 300.015777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:15.762214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.676203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T03:51:15.762245Z","caller":"traceutil/trace.go:171","msg":"trace[1547962863] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24317; }","duration":"232.757235ms","start":"2026-02-26T03:51:15.529477Z","end":"2026-02-26T03:51:15.762235Z","steps":["trace[1547962863] 'agreement among raft nodes before linearized reading' (duration: 232.676013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:15.762241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.474957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-26T03:51:15.762283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:15.461827Z","time spent":"300.373407ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:51:15.762304Z","caller":"traceutil/trace.go:171","msg":"trace[1623742276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24317; }","duration":"245.599531ms","start":"2026-02-26T03:51:15.51669Z","end":"2026-02-26T03:51:15.76229Z","steps":["trace[1623742276] 'agreement among raft nodes before linearized reading' (duration: 245.458497ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:16.12956Z","caller":"traceutil/trace.go:171","msg":"trace[1865401922] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"122.478761ms","start":"2026-02-26T03:51:16.007054Z","end":"2026-02-26T03:51:16.129533Z","steps":["trace[1865401922] 'process raft request' (duration: 122.225345ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:16.240673Z","caller":"traceutil/trace.go:171","msg":"trace[2047103475] linearizableReadLoop","detail":"{readStateIndex:25173; appliedIndex:25172; }","duration":"111.152158ms","start":"2026-02-26T03:51:16.129478Z","end":"2026-02-26T03:51:16.24063Z","steps":["trace[2047103475] 'read index received' (duration: 33.704273ms)","trace[2047103475] 'applied index is now lower than readState.Index' (duration: 77.446225ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:16.240702Z","caller":"traceutil/trace.go:171","msg":"trace[1032378628] transaction","detail":"{read_only:false; response_revision:24321; number_of_response:1; }","duration":"204.77438ms","start":"2026-02-26T03:51:16.035886Z","end":"2026-02-26T03:51:16.24066Z","steps":["trace[1032378628] 'process raft request' (duration: 127.328335ms)","trace[1032378628] 'compare' (duration: 77.138856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:16.240945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.185323ms","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-02-26T03:51:16.241007Z","caller":"traceutil/trace.go:171","msg":"trace[715209369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24321; }","duration":"200.279535ms","start":"2026-02-26T03:51:16.040712Z","end":"2026-02-26T03:51:16.240992Z","steps":["trace[715209369] 'agreement among raft nodes before linearized reading' (duration: 200.017368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:16.241007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.781043ms","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-02-26T03:51:16.241052Z","caller":"traceutil/trace.go:171","msg":"trace[1342304049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24321; }","duration":"207.879126ms","start":"2026-02-26T03:51:16.033159Z","end":"2026-02-26T03:51:16.241038Z","steps":["trace[1342304049] 'agreement among raft nodes before linearized reading' (duration: 207.6506ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:16.270481Z","caller":"traceutil/trace.go:171","msg":"trace[1911676432] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"135.141152ms","start":"2026-02-26T03:51:16.135315Z","end":"2026-02-26T03:51:16.270456Z","steps":["trace[1911676432] 'process raft request' (duration: 134.908525ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:16.270684Z","caller":"traceutil/trace.go:171","msg":"trace[592310041] transaction","detail":"{read_only:false; response_revision:24323; number_of_response:1; }","duration":"131.656285ms","start":"2026-02-26T03:51:16.138988Z","end":"2026-02-26T03:51:16.270644Z","steps":["trace[592310041] 'process raft request' (duration: 131.405857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:16.27078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.771186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T03:51:16.270875Z","caller":"traceutil/trace.go:171","msg":"trace[277429782] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24323; }","duration":"119.956641ms","start":"2026-02-26T03:51:16.150904Z","end":"2026-02-26T03:51:16.27086Z","steps":["trace[277429782] 'agreement among raft nodes before linearized reading' (duration: 119.677524ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:16.542292Z","caller":"traceutil/trace.go:171","msg":"trace[197276336] linearizableReadLoop","detail":"{readStateIndex:25178; appliedIndex:25175; }","duration":"260.787702ms","start":"2026-02-26T03:51:16.28148Z","end":"2026-02-26T03:51:16.542268Z","steps":["trace[197276336] 'read index received' (duration: 205.646745ms)","trace[197276336] 'applied index is now lower than readState.Index' (duration: 55.139437ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:16.542617Z","caller":"traceutil/trace.go:171","msg":"trace[556631888] transaction","detail":"{read_only:false; response_revision:24324; number_of_response:1; }","duration":"297.609602ms","start":"2026-02-26T03:51:16.244992Z","end":"2026-02-26T03:51:16.542602Z","steps":["trace[556631888] 'process raft request' (duration: 242.122465ms)","trace[556631888] 'compare' (duration: 54.636863ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:16.54294Z","caller":"traceutil/trace.go:171","msg":"trace[1970961586] transaction","detail":"{read_only:false; response_revision:24325; number_of_response:1; }","duration":"296.576972ms","start":"2026-02-26T03:51:16.246297Z","end":"2026-02-26T03:51:16.542874Z","steps":["trace[1970961586] 'process raft request' (duration: 295.81492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:16.542941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.411259ms","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-02-26T03:51:16.543043Z","caller":"traceutil/trace.go:171","msg":"trace[1051640323] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24326; }","duration":"261.581604ms","start":"2026-02-26T03:51:16.281436Z","end":"2026-02-26T03:51:16.543017Z","steps":["trace[1051640323] 'agreement among raft nodes before linearized reading' (duration: 261.296126ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:16.543297Z","caller":"traceutil/trace.go:171","msg":"trace[168254075] transaction","detail":"{read_only:false; response_revision:24326; number_of_response:1; }","duration":"265.183973ms","start":"2026-02-26T03:51:16.278098Z","end":"2026-02-26T03:51:16.543282Z","steps":["trace[168254075] 'process raft request' (duration: 264.122214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:16.77087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.533767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-02-26T03:51:16.770936Z","caller":"traceutil/trace.go:171","msg":"trace[1784879214] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24327; }","duration":"124.651921ms","start":"2026-02-26T03:51:16.646269Z","end":"2026-02-26T03:51:16.770921Z","steps":["trace[1784879214] 'range keys from in-memory index tree' (duration: 124.332602ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:23.990569Z","caller":"traceutil/trace.go:171","msg":"trace[46629770] transaction","detail":"{read_only:false; response_revision:24376; number_of_response:1; }","duration":"184.080036ms","start":"2026-02-26T03:51:23.806445Z","end":"2026-02-26T03:51:23.990525Z","steps":["trace[46629770] 'process raft request' (duration: 123.838799ms)","trace[46629770] 'compare' (duration: 60.066563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:24.240814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.922401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:24.240961Z","caller":"traceutil/trace.go:171","msg":"trace[2127605969] transaction","detail":"{read_only:false; response_revision:24377; number_of_response:1; }","duration":"334.552084ms","start":"2026-02-26T03:51:23.90637Z","end":"2026-02-26T03:51:24.240922Z","steps":["trace[2127605969] 'process raft request' (duration: 227.409597ms)","trace[2127605969] 'compare' (duration: 106.580262ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:24.241061Z","caller":"traceutil/trace.go:171","msg":"trace[843835209] transaction","detail":"{read_only:false; response_revision:24378; number_of_response:1; }","duration":"284.778246ms","start":"2026-02-26T03:51:23.956264Z","end":"2026-02-26T03:51:24.241042Z","steps":["trace[843835209] 'process raft request' (duration: 284.667983ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:24.241134Z","caller":"traceutil/trace.go:171","msg":"trace[1994241697] linearizableReadLoop","detail":"{readStateIndex:25232; appliedIndex:25230; }","duration":"326.525212ms","start":"2026-02-26T03:51:23.914539Z","end":"2026-02-26T03:51:24.241064Z","steps":["trace[1994241697] 'read index received' (duration: 15.705215ms)","trace[1994241697] 'applied index is now lower than readState.Index' (duration: 310.811817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:24.241196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.641505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:51:24.241262Z","caller":"traceutil/trace.go:171","msg":"trace[2026896679] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24378; }","duration":"326.760798ms","start":"2026-02-26T03:51:23.914482Z","end":"2026-02-26T03:51:24.241243Z","steps":["trace[2026896679] 'agreement among raft nodes before linearized reading' (duration: 326.646435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:24.241303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:23.914473Z","time spent":"326.822001ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T03:51:24.241571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:23.906338Z","time spent":"335.145471ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:51:24.454096Z","caller":"traceutil/trace.go:171","msg":"trace[495272379] transaction","detail":"{read_only:false; response_revision:24380; number_of_response:1; }","duration":"117.887844ms","start":"2026-02-26T03:51:24.336176Z","end":"2026-02-26T03:51:24.454064Z","steps":["trace[495272379] 'process raft request' (duration: 109.516672ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:24.702621Z","caller":"traceutil/trace.go:171","msg":"trace[1284315698] linearizableReadLoop","detail":"{readStateIndex:25236; appliedIndex:25235; }","duration":"126.00159ms","start":"2026-02-26T03:51:24.576598Z","end":"2026-02-26T03:51:24.702599Z","steps":["trace[1284315698] 'read index received' (duration: 124.008244ms)","trace[1284315698] 'applied index is now lower than readState.Index' (duration: 1.992485ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:24.703009Z","caller":"traceutil/trace.go:171","msg":"trace[2096029027] transaction","detail":"{read_only:false; response_revision:24381; number_of_response:1; }","duration":"130.630868ms","start":"2026-02-26T03:51:24.572338Z","end":"2026-02-26T03:51:24.702969Z","steps":["trace[2096029027] 'process raft request' (duration: 128.332834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:24.703357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.721599ms","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-02-26T03:51:24.703383Z","caller":"traceutil/trace.go:171","msg":"trace[1982058878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24381; }","duration":"126.804411ms","start":"2026-02-26T03:51:24.576572Z","end":"2026-02-26T03:51:24.703377Z","steps":["trace[1982058878] 'agreement among raft nodes before linearized reading' (duration: 126.635866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:24.703466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.667527ms","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-02-26T03:51:24.70357Z","caller":"traceutil/trace.go:171","msg":"trace[1823431693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24381; }","duration":"126.847542ms","start":"2026-02-26T03:51:24.576697Z","end":"2026-02-26T03:51:24.703544Z","steps":["trace[1823431693] 'agreement among raft nodes before linearized reading' (duration: 126.567385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:25.034404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.609254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:25.034583Z","caller":"traceutil/trace.go:171","msg":"trace[1534340858] linearizableReadLoop","detail":"{readStateIndex:25238; appliedIndex:25236; }","duration":"322.660124ms","start":"2026-02-26T03:51:24.711909Z","end":"2026-02-26T03:51:25.034569Z","steps":["trace[1534340858] 'read index received' (duration: 125.758162ms)","trace[1534340858] 'applied index is now lower than readState.Index' (duration: 196.901302ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:25.034735Z","caller":"traceutil/trace.go:171","msg":"trace[1890888774] transaction","detail":"{read_only:false; response_revision:24382; number_of_response:1; }","duration":"326.033277ms","start":"2026-02-26T03:51:24.708694Z","end":"2026-02-26T03:51:25.034727Z","steps":["trace[1890888774] 'process raft request' (duration: 129.035133ms)","trace[1890888774] 'compare' (duration: 196.353327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:25.034779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:24.708665Z","time spent":"326.094489ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:51:25.034909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.739974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T03:51:25.034935Z","caller":"traceutil/trace.go:171","msg":"trace[1326623280] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24384; }","duration":"219.927199ms","start":"2026-02-26T03:51:24.814996Z","end":"2026-02-26T03:51:25.034924Z","steps":["trace[1326623280] 'agreement among raft nodes before linearized reading' (duration: 219.890298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:25.034986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.047925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18630"} {"level":"info","ts":"2026-02-26T03:51:25.035059Z","caller":"traceutil/trace.go:171","msg":"trace[281099240] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24384; }","duration":"323.170418ms","start":"2026-02-26T03:51:24.711873Z","end":"2026-02-26T03:51:25.035043Z","steps":["trace[281099240] 'agreement among raft nodes before linearized reading' (duration: 322.926591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:25.035103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:24.711864Z","time spent":"323.23246ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18652,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-26T03:51:25.035137Z","caller":"traceutil/trace.go:171","msg":"trace[1703476589] transaction","detail":"{read_only:false; response_revision:24383; number_of_response:1; }","duration":"326.371366ms","start":"2026-02-26T03:51:24.70876Z","end":"2026-02-26T03:51:25.035131Z","steps":["trace[1703476589] 'process raft request' (duration: 325.75428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:25.035175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:24.708731Z","time spent":"326.426888ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:51:25.035195Z","caller":"traceutil/trace.go:171","msg":"trace[1505271997] transaction","detail":"{read_only:false; response_revision:24384; number_of_response:1; }","duration":"323.175268ms","start":"2026-02-26T03:51:24.711982Z","end":"2026-02-26T03:51:25.035157Z","steps":["trace[1505271997] 'process raft request' (duration: 322.567622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:25.035375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:24.711976Z","time spent":"323.301431ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:51:25.260464Z","caller":"traceutil/trace.go:171","msg":"trace[1147264996] linearizableReadLoop","detail":"{readStateIndex:25240; appliedIndex:25239; }","duration":"149.058188ms","start":"2026-02-26T03:51:25.111378Z","end":"2026-02-26T03:51:25.260437Z","steps":["trace[1147264996] 'read index received' (duration: 62.628514ms)","trace[1147264996] 'applied index is now lower than readState.Index' (duration: 86.428174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:25.260602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.195262ms","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-02-26T03:51:25.260633Z","caller":"traceutil/trace.go:171","msg":"trace[186221929] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24385; }","duration":"149.283174ms","start":"2026-02-26T03:51:25.111341Z","end":"2026-02-26T03:51:25.260624Z","steps":["trace[186221929] 'agreement among raft nodes before linearized reading' (duration: 149.184221ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:25.260694Z","caller":"traceutil/trace.go:171","msg":"trace[229870940] transaction","detail":"{read_only:false; response_revision:24385; number_of_response:1; }","duration":"155.978059ms","start":"2026-02-26T03:51:25.104683Z","end":"2026-02-26T03:51:25.260662Z","steps":["trace[229870940] 'process raft request' (duration: 69.288668ms)","trace[229870940] 'compare' (duration: 86.054243ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:27.176164Z","caller":"traceutil/trace.go:171","msg":"trace[1669170636] transaction","detail":"{read_only:false; response_revision:24397; number_of_response:1; }","duration":"127.637404ms","start":"2026-02-26T03:51:27.0485Z","end":"2026-02-26T03:51:27.176137Z","steps":["trace[1669170636] 'process raft request' (duration: 127.590963ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:27.176215Z","caller":"traceutil/trace.go:171","msg":"trace[470631523] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"130.889063ms","start":"2026-02-26T03:51:27.045288Z","end":"2026-02-26T03:51:27.176177Z","steps":["trace[470631523] 'process raft request' (duration: 130.644006ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:27.621922Z","caller":"traceutil/trace.go:171","msg":"trace[277057971] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"256.076472ms","start":"2026-02-26T03:51:27.365812Z","end":"2026-02-26T03:51:27.621889Z","steps":["trace[277057971] 'process raft request' (duration: 236.626243ms)","trace[277057971] 'compare' (duration: 19.293685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:27.871702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.203178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-26T03:51:27.871873Z","caller":"traceutil/trace.go:171","msg":"trace[299778614] transaction","detail":"{read_only:false; response_revision:24400; number_of_response:1; }","duration":"399.002029ms","start":"2026-02-26T03:51:27.472841Z","end":"2026-02-26T03:51:27.871843Z","steps":["trace[299778614] 'process raft request' (duration: 269.547195ms)","trace[299778614] 'compare' (duration: 129.045493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:27.87203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:27.472777Z","time spent":"399.168534ms","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:51:27.872142Z","caller":"traceutil/trace.go:171","msg":"trace[349646478] linearizableReadLoop","detail":"{readStateIndex:25255; appliedIndex:25253; }","duration":"352.066439ms","start":"2026-02-26T03:51:27.520069Z","end":"2026-02-26T03:51:27.872135Z","steps":["trace[349646478] 'read index received' (duration: 82.402802ms)","trace[349646478] 'applied index is now lower than readState.Index' (duration: 269.662047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:27.872296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.212693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:51:27.872404Z","caller":"traceutil/trace.go:171","msg":"trace[24616556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24401; }","duration":"352.392797ms","start":"2026-02-26T03:51:27.519989Z","end":"2026-02-26T03:51:27.872382Z","steps":["trace[24616556] 'agreement among raft nodes before linearized reading' (duration: 352.191572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:27.872673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:51:27.519957Z","time spent":"352.702596ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:51:43.338092Z","caller":"traceutil/trace.go:171","msg":"trace[362627347] transaction","detail":"{read_only:false; response_revision:24520; number_of_response:1; }","duration":"145.596881ms","start":"2026-02-26T03:51:43.192464Z","end":"2026-02-26T03:51:43.33806Z","steps":["trace[362627347] 'process raft request' (duration: 103.855475ms)","trace[362627347] 'compare' (duration: 41.231782ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:43.487525Z","caller":"traceutil/trace.go:171","msg":"trace[1608838417] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"143.332428ms","start":"2026-02-26T03:51:43.344174Z","end":"2026-02-26T03:51:43.487506Z","steps":["trace[1608838417] 'process raft request' (duration: 143.241716ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:43.487869Z","caller":"traceutil/trace.go:171","msg":"trace[21014559] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"144.12156ms","start":"2026-02-26T03:51:43.343728Z","end":"2026-02-26T03:51:43.487849Z","steps":["trace[21014559] 'process raft request' (duration: 99.723691ms)","trace[21014559] 'compare' (duration: 43.811633ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:48.940018Z","caller":"traceutil/trace.go:171","msg":"trace[536785959] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"143.865143ms","start":"2026-02-26T03:51:48.796112Z","end":"2026-02-26T03:51:48.939977Z","steps":["trace[536785959] 'process raft request' (duration: 118.506961ms)","trace[536785959] 'compare' (duration: 25.103105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:49.201872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.978708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:51:49.202052Z","caller":"traceutil/trace.go:171","msg":"trace[105778485] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:24562; }","duration":"148.238515ms","start":"2026-02-26T03:51:49.053785Z","end":"2026-02-26T03:51:49.202023Z","steps":["trace[105778485] 'count revisions from in-memory index tree' (duration: 147.818703ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:49.488941Z","caller":"traceutil/trace.go:171","msg":"trace[2096856178] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"120.186418ms","start":"2026-02-26T03:51:49.368726Z","end":"2026-02-26T03:51:49.488912Z","steps":["trace[2096856178] 'process raft request' (duration: 119.975843ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:49.696814Z","caller":"traceutil/trace.go:171","msg":"trace[458595840] linearizableReadLoop","detail":"{readStateIndex:25424; appliedIndex:25423; }","duration":"182.457363ms","start":"2026-02-26T03:51:49.514327Z","end":"2026-02-26T03:51:49.696784Z","steps":["trace[458595840] 'read index received' (duration: 117.34884ms)","trace[458595840] 'applied index is now lower than readState.Index' (duration: 65.107043ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:51:49.696867Z","caller":"traceutil/trace.go:171","msg":"trace[93031546] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"186.269248ms","start":"2026-02-26T03:51:49.510574Z","end":"2026-02-26T03:51:49.696843Z","steps":["trace[93031546] 'process raft request' (duration: 121.161394ms)","trace[93031546] 'compare' (duration: 64.798514ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:51:49.697099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.765012ms","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-02-26T03:51:49.697142Z","caller":"traceutil/trace.go:171","msg":"trace[1335412873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24564; }","duration":"182.828384ms","start":"2026-02-26T03:51:49.514302Z","end":"2026-02-26T03:51:49.69713Z","steps":["trace[1335412873] 'agreement among raft nodes before linearized reading' (duration: 182.619318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:49.697271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.094421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-26T03:51:49.697318Z","caller":"traceutil/trace.go:171","msg":"trace[1721543762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24564; }","duration":"178.184364ms","start":"2026-02-26T03:51:49.519123Z","end":"2026-02-26T03:51:49.697308Z","steps":["trace[1721543762] 'agreement among raft nodes before linearized reading' (duration: 178.107942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:51:49.697326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.611397ms","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-02-26T03:51:49.697442Z","caller":"traceutil/trace.go:171","msg":"trace[1880713199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24564; }","duration":"182.788542ms","start":"2026-02-26T03:51:49.514634Z","end":"2026-02-26T03:51:49.697422Z","steps":["trace[1880713199] 'agreement among raft nodes before linearized reading' (duration: 182.405371ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:49.827836Z","caller":"traceutil/trace.go:171","msg":"trace[428902323] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"126.000948ms","start":"2026-02-26T03:51:49.701804Z","end":"2026-02-26T03:51:49.827805Z","steps":["trace[428902323] 'process raft request' (duration: 125.936257ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:51:49.827885Z","caller":"traceutil/trace.go:171","msg":"trace[1440494091] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"126.0379ms","start":"2026-02-26T03:51:49.701809Z","end":"2026-02-26T03:51:49.827847Z","steps":["trace[1440494091] 'process raft request' (duration: 121.546656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:55:03.432712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.687457ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T03:55:04.255711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23605} {"level":"info","ts":"2026-02-26T03:55:04.389769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23605,"took":"130.296377ms","hash":524242584,"current-db-size-bytes":50094080,"current-db-size":"50 MB","current-db-size-in-use-bytes":34820096,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-02-26T03:55:04.389829Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":524242584,"revision":23605,"compact-revision":20506} {"level":"info","ts":"2026-02-26T04:00:04.262833Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26533} {"level":"info","ts":"2026-02-26T04:00:04.415124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26533,"took":"148.18858ms","hash":1362939945,"current-db-size-bytes":50094080,"current-db-size":"50 MB","current-db-size-in-use-bytes":27848704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T04:00:04.415199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1362939945,"revision":26533,"compact-revision":23605} {"level":"info","ts":"2026-02-26T04:00:46.309552Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T04:00:46.317997Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-02-26T04:00:46.318123Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-02-26T04:01:40.892835Z","caller":"traceutil/trace.go:171","msg":"trace[680986696] linearizableReadLoop","detail":"{readStateIndex:30414; appliedIndex:30413; }","duration":"155.388659ms","start":"2026-02-26T04:01:40.737423Z","end":"2026-02-26T04:01:40.892812Z","steps":["trace[680986696] 'read index received' (duration: 155.191392ms)","trace[680986696] 'applied index is now lower than readState.Index' (duration: 196.247µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T04:01:40.892904Z","caller":"traceutil/trace.go:171","msg":"trace[1259342020] transaction","detail":"{read_only:false; response_revision:29418; number_of_response:1; }","duration":"195.201119ms","start":"2026-02-26T04:01:40.697672Z","end":"2026-02-26T04:01:40.892873Z","steps":["trace[1259342020] 'process raft request' (duration: 194.990423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T04:01:40.893086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.678906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T04:01:40.893125Z","caller":"traceutil/trace.go:171","msg":"trace[591609246] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:29418; }","duration":"155.776819ms","start":"2026-02-26T04:01:40.737336Z","end":"2026-02-26T04:01:40.893113Z","steps":["trace[591609246] 'agreement among raft nodes before linearized reading' (duration: 155.604234ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T04:04:08.964802Z","caller":"traceutil/trace.go:171","msg":"trace[1020003970] transaction","detail":"{read_only:false; response_revision:30427; number_of_response:1; }","duration":"198.733307ms","start":"2026-02-26T04:04:08.766036Z","end":"2026-02-26T04:04:08.96477Z","steps":["trace[1020003970] 'process raft request' (duration: 198.574093ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T04:04:08.965538Z","caller":"traceutil/trace.go:171","msg":"trace[820427423] transaction","detail":"{read_only:false; response_revision:30428; number_of_response:1; }","duration":"195.088435ms","start":"2026-02-26T04:04:08.770422Z","end":"2026-02-26T04:04:08.96551Z","steps":["trace[820427423] 'process raft request' (duration: 194.922421ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T04:04:08.965723Z","caller":"traceutil/trace.go:171","msg":"trace[650064976] transaction","detail":"{read_only:false; response_revision:30429; number_of_response:1; }","duration":"139.651183ms","start":"2026-02-26T04:04:08.826047Z","end":"2026-02-26T04:04:08.965698Z","steps":["trace[650064976] 'process raft request' (duration: 139.420296ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T04:05:04.269332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28691} {"level":"info","ts":"2026-02-26T04:05:04.386219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28691,"took":"112.544312ms","hash":2596753687,"current-db-size-bytes":50094080,"current-db-size":"50 MB","current-db-size-in-use-bytes":29081600,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-26T04:05:04.386271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2596753687,"revision":28691,"compact-revision":26533} {"level":"info","ts":"2026-02-26T04:10:04.276237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30850} {"level":"info","ts":"2026-02-26T04:10:04.414378Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30850,"took":"134.586755ms","hash":1912038800,"current-db-size-bytes":50094080,"current-db-size":"50 MB","current-db-size-in-use-bytes":27860992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T04:10:04.414461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1912038800,"revision":30850,"compact-revision":28691} {"level":"info","ts":"2026-02-26T04:15:04.282478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33006} {"level":"info","ts":"2026-02-26T04:15:04.404591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33006,"took":"117.996944ms","hash":1380523546,"current-db-size-bytes":50094080,"current-db-size":"50 MB","current-db-size-in-use-bytes":27865088,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-26T04:15:04.404644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1380523546,"revision":33006,"compact-revision":30850}