{"level":"warn","ts":"2026-03-02T21:24:05.546874Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:24:05.546946Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90: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.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-02T21:24:05.547005Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:24:05.547016Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-03-02T21:24:05.547033Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T21:24:05.547633Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-03-02T21:24:05.547702Z","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.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-02T21:24:05.550748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.867501ms"} {"level":"info","ts":"2026-03-02T21:24:05.556424Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-03-02T21:24:05.556544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T21:24:05.556586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-03-02T21:24:05.556614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T21:24:05.556631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-03-02T21:24:05.55668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-03-02T21:24:05.562326Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T21:24:05.565406Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T21:24:05.566455Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-02T21:24:05.569334Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T21:24:05.569467Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T21:24:05.56954Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T21:24:05.569606Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T21:24:05.569658Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T21:24:05.570266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-03-02T21:24:05.570378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-03-02T21:24:05.57423Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T21:24:05.574326Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-03-02T21:24:05.574359Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-03-02T21:24:05.574637Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T21:24:05.574704Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T21:24:06.35738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T21:24:06.357462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T21:24:06.357519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-03-02T21:24:06.357546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-03-02T21:24:06.357563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-03-02T21:24:06.357585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-03-02T21:24:06.3576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-03-02T21:24:06.358622Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:06.359695Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:24:06.359691Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T21:24:06.35984Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:24:06.359905Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:06.360066Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:06.360112Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:06.360226Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T21:24:06.360315Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T21:24:06.363079Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-03-02T21:24:06.363286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T21:24:22.347906Z","caller":"traceutil/trace.go:171","msg":"trace[2113202758] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"160.731297ms","start":"2026-03-02T21:24:22.187127Z","end":"2026-03-02T21:24:22.347859Z","steps":["trace[2113202758] 'read index received' (duration: 125.739798ms)","trace[2113202758] 'applied index is now lower than readState.Index' (duration: 34.989729ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:24:22.347988Z","caller":"traceutil/trace.go:171","msg":"trace[87409643] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"251.513646ms","start":"2026-03-02T21:24:22.096437Z","end":"2026-03-02T21:24:22.347951Z","steps":["trace[87409643] 'process raft request' (duration: 216.547337ms)","trace[87409643] 'compare' (duration: 34.647563ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:24:22.348138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.978462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-03-02T21:24:22.34832Z","caller":"traceutil/trace.go:171","msg":"trace[1983178943] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:370; }","duration":"161.228127ms","start":"2026-03-02T21:24:22.187066Z","end":"2026-03-02T21:24:22.348294Z","steps":["trace[1983178943] 'agreement among raft nodes before linearized reading' (duration: 160.958971ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:24:42.488448Z","caller":"traceutil/trace.go:171","msg":"trace[1689312775] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"133.035378ms","start":"2026-03-02T21:24:42.35535Z","end":"2026-03-02T21:24:42.488386Z","steps":["trace[1689312775] 'read index received' (duration: 132.875435ms)","trace[1689312775] 'applied index is now lower than readState.Index' (duration: 158.673µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:24:42.488487Z","caller":"traceutil/trace.go:171","msg":"trace[2054014432] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"206.834205ms","start":"2026-03-02T21:24:42.281611Z","end":"2026-03-02T21:24:42.488445Z","steps":["trace[2054014432] 'process raft request' (duration: 206.639112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:24:42.488733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.351683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7c2mr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-02T21:24:42.488806Z","caller":"traceutil/trace.go:171","msg":"trace[738507003] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7c2mr; range_end:; response_count:1; response_revision:508; }","duration":"133.492406ms","start":"2026-03-02T21:24:42.355303Z","end":"2026-03-02T21:24:42.488795Z","steps":["trace[738507003] 'agreement among raft nodes before linearized reading' (duration: 133.270422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:25:02.859957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.069204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:25:02.860083Z","caller":"traceutil/trace.go:171","msg":"trace[2118451127] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"266.833009ms","start":"2026-03-02T21:25:02.59321Z","end":"2026-03-02T21:25:02.860043Z","steps":["trace[2118451127] 'process raft request' (duration: 107.049362ms)","trace[2118451127] 'compare' (duration: 158.967362ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:25:26.837662Z","caller":"traceutil/trace.go:171","msg":"trace[73544159] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"110.457905ms","start":"2026-03-02T21:25:26.727179Z","end":"2026-03-02T21:25:26.837637Z","steps":["trace[73544159] 'process raft request' (duration: 110.306882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:25:50.621035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.544626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1f3ad60e-9429-4e22-9b6d-f41bf43df2c7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-03-02T21:25:50.621132Z","caller":"traceutil/trace.go:171","msg":"trace[476425324] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1f3ad60e-9429-4e22-9b6d-f41bf43df2c7; range_end:; response_count:1; response_revision:1549; }","duration":"202.72944ms","start":"2026-03-02T21:25:50.418383Z","end":"2026-03-02T21:25:50.621112Z","steps":["trace[476425324] 'range keys from in-memory index tree' (duration: 202.305412ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:25:50.625289Z","caller":"traceutil/trace.go:171","msg":"trace[510045501] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"146.349567ms","start":"2026-03-02T21:25:50.478891Z","end":"2026-03-02T21:25:50.62524Z","steps":["trace[510045501] 'process raft request' (duration: 146.186984ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:25:51.017746Z","caller":"traceutil/trace.go:171","msg":"trace[980075879] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"188.990134ms","start":"2026-03-02T21:25:50.828726Z","end":"2026-03-02T21:25:51.017716Z","steps":["trace[980075879] 'process raft request' (duration: 171.787463ms)","trace[980075879] 'compare' (duration: 17.031008ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:25:51.018201Z","caller":"traceutil/trace.go:171","msg":"trace[983327288] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"113.252017ms","start":"2026-03-02T21:25:50.90492Z","end":"2026-03-02T21:25:51.018172Z","steps":["trace[983327288] 'process raft request' (duration: 112.755268ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:42.699323Z","caller":"traceutil/trace.go:171","msg":"trace[1431451867] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"129.505989ms","start":"2026-03-02T21:26:42.569784Z","end":"2026-03-02T21:26:42.69929Z","steps":["trace[1431451867] 'process raft request' (duration: 129.349836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:28:10.975635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.128579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-02T21:28:10.975736Z","caller":"traceutil/trace.go:171","msg":"trace[2014582446] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2481; }","duration":"128.286473ms","start":"2026-03-02T21:28:10.847426Z","end":"2026-03-02T21:28:10.975713Z","steps":["trace[2014582446] 'range keys from in-memory index tree' (duration: 127.933026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:32:00.156671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.533709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:32:00.156822Z","caller":"traceutil/trace.go:171","msg":"trace[863805150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4514; }","duration":"244.729252ms","start":"2026-03-02T21:31:59.912071Z","end":"2026-03-02T21:32:00.1568Z","steps":["trace[863805150] 'range keys from in-memory index tree' (duration: 244.431617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:32:00.156912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.488865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9ltw4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-02T21:32:00.157038Z","caller":"traceutil/trace.go:171","msg":"trace[608259020] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9ltw4; range_end:; response_count:1; response_revision:4514; }","duration":"225.671999ms","start":"2026-03-02T21:31:59.93134Z","end":"2026-03-02T21:32:00.157012Z","steps":["trace[608259020] 'range keys from in-memory index tree' (duration: 225.22169ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:32:11.930269Z","caller":"traceutil/trace.go:171","msg":"trace[1897572153] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"121.605998ms","start":"2026-03-02T21:32:11.808632Z","end":"2026-03-02T21:32:11.930238Z","steps":["trace[1897572153] 'process raft request' (duration: 121.487416ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:32:11.930273Z","caller":"traceutil/trace.go:171","msg":"trace[1039429754] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"122.389302ms","start":"2026-03-02T21:32:11.807827Z","end":"2026-03-02T21:32:11.930216Z","steps":["trace[1039429754] 'process raft request' (duration: 115.948683ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:32:11.930562Z","caller":"traceutil/trace.go:171","msg":"trace[723340421] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"120.423235ms","start":"2026-03-02T21:32:11.810123Z","end":"2026-03-02T21:32:11.930546Z","steps":["trace[723340421] 'process raft request' (duration: 120.069819ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:32:20.058767Z","caller":"traceutil/trace.go:171","msg":"trace[1652389981] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"147.669942ms","start":"2026-03-02T21:32:19.911068Z","end":"2026-03-02T21:32:20.058738Z","steps":["trace[1652389981] 'read index received' (duration: 132.390977ms)","trace[1652389981] 'applied index is now lower than readState.Index' (duration: 15.277565ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:32:20.058837Z","caller":"traceutil/trace.go:171","msg":"trace[171428718] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"205.399404ms","start":"2026-03-02T21:32:19.853397Z","end":"2026-03-02T21:32:20.058796Z","steps":["trace[171428718] 'process raft request' (duration: 190.12871ms)","trace[171428718] 'compare' (duration: 15.054019ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:32:20.058913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.827885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:32:20.058983Z","caller":"traceutil/trace.go:171","msg":"trace[900222017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4907; }","duration":"147.938187ms","start":"2026-03-02T21:32:19.911027Z","end":"2026-03-02T21:32:20.058965Z","steps":["trace[900222017] 'agreement among raft nodes before linearized reading' (duration: 147.823905ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:34:06.386049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2758} {"level":"info","ts":"2026-03-02T21:34:06.441376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2758,"took":"53.727356ms","hash":2678165561,"current-db-size-bytes":42496000,"current-db-size":"42 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-02T21:34:06.441446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2678165561,"revision":2758,"compact-revision":-1} {"level":"info","ts":"2026-03-02T21:38:22.525956Z","caller":"traceutil/trace.go:171","msg":"trace[2009026977] transaction","detail":"{read_only:false; response_revision:7542; number_of_response:1; }","duration":"231.177024ms","start":"2026-03-02T21:38:22.294754Z","end":"2026-03-02T21:38:22.525931Z","steps":["trace[2009026977] 'process raft request' (duration: 230.96448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:38:22.526168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.91835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9ltw4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-02T21:38:22.526233Z","caller":"traceutil/trace.go:171","msg":"trace[141803770] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9ltw4; range_end:; response_count:1; response_revision:7542; }","duration":"126.048022ms","start":"2026-03-02T21:38:22.400168Z","end":"2026-03-02T21:38:22.526216Z","steps":["trace[141803770] 'agreement among raft nodes before linearized reading' (duration: 125.835958ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:38:22.525904Z","caller":"traceutil/trace.go:171","msg":"trace[1579555746] linearizableReadLoop","detail":"{readStateIndex:7821; appliedIndex:7820; }","duration":"125.645704ms","start":"2026-03-02T21:38:22.400232Z","end":"2026-03-02T21:38:22.525877Z","steps":["trace[1579555746] 'read index received' (duration: 125.40727ms)","trace[1579555746] 'applied index is now lower than readState.Index' (duration: 234.874µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:38:34.230391Z","caller":"traceutil/trace.go:171","msg":"trace[1337280742] transaction","detail":"{read_only:false; response_revision:7597; number_of_response:1; }","duration":"232.54922ms","start":"2026-03-02T21:38:33.997807Z","end":"2026-03-02T21:38:34.230356Z","steps":["trace[1337280742] 'process raft request' (duration: 232.338956ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:38:34.367937Z","caller":"traceutil/trace.go:171","msg":"trace[816247234] linearizableReadLoop","detail":"{readStateIndex:7880; appliedIndex:7878; }","duration":"344.173222ms","start":"2026-03-02T21:38:34.023736Z","end":"2026-03-02T21:38:34.367909Z","steps":["trace[816247234] 'read index received' (duration: 206.380723ms)","trace[816247234] 'applied index is now lower than readState.Index' (duration: 137.791489ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:38:34.368086Z","caller":"traceutil/trace.go:171","msg":"trace[1951053797] transaction","detail":"{read_only:false; response_revision:7598; number_of_response:1; }","duration":"360.080669ms","start":"2026-03-02T21:38:34.007964Z","end":"2026-03-02T21:38:34.368045Z","steps":["trace[1951053797] 'process raft request' (duration: 309.977959ms)","trace[1951053797] 'compare' (duration: 49.849255ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:38:34.368276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:38:34.007949Z","time spent":"360.232352ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:38:34.368357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.614552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T21:38:34.3684Z","caller":"traceutil/trace.go:171","msg":"trace[1943620883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7598; }","duration":"344.737894ms","start":"2026-03-02T21:38:34.023651Z","end":"2026-03-02T21:38:34.368389Z","steps":["trace[1943620883] 'agreement among raft nodes before linearized reading' (duration: 344.385397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:38:34.368538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:38:34.023617Z","time spent":"344.906807ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T21:38:35.226863Z","caller":"traceutil/trace.go:171","msg":"trace[1258469150] transaction","detail":"{read_only:false; response_revision:7603; number_of_response:1; }","duration":"120.00236ms","start":"2026-03-02T21:38:35.106831Z","end":"2026-03-02T21:38:35.226833Z","steps":["trace[1258469150] 'process raft request' (duration: 119.793386ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:38:36.536605Z","caller":"traceutil/trace.go:171","msg":"trace[738109061] linearizableReadLoop","detail":"{readStateIndex:7890; appliedIndex:7889; }","duration":"151.244769ms","start":"2026-03-02T21:38:36.385275Z","end":"2026-03-02T21:38:36.53652Z","steps":["trace[738109061] 'read index received' (duration: 151.105916ms)","trace[738109061] 'applied index is now lower than readState.Index' (duration: 137.633µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:38:36.536739Z","caller":"traceutil/trace.go:171","msg":"trace[1836976716] transaction","detail":"{read_only:false; response_revision:7607; number_of_response:1; }","duration":"162.58366ms","start":"2026-03-02T21:38:36.374145Z","end":"2026-03-02T21:38:36.536729Z","steps":["trace[1836976716] 'process raft request' (duration: 162.228593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:38:36.537135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.84737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T21:38:36.537184Z","caller":"traceutil/trace.go:171","msg":"trace[1565505400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7607; }","duration":"151.945532ms","start":"2026-03-02T21:38:36.385225Z","end":"2026-03-02T21:38:36.537171Z","steps":["trace[1565505400] 'agreement among raft nodes before linearized reading' (duration: 151.788919ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:39:02.56463Z","caller":"traceutil/trace.go:171","msg":"trace[170774697] transaction","detail":"{read_only:false; response_revision:7787; number_of_response:1; }","duration":"218.90982ms","start":"2026-03-02T21:39:02.345687Z","end":"2026-03-02T21:39:02.564597Z","steps":["trace[170774697] 'process raft request' (duration: 218.681086ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:39:06.392926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5782} {"level":"info","ts":"2026-03-02T21:39:06.498141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5782,"took":"103.052098ms","hash":311426633,"current-db-size-bytes":43249664,"current-db-size":"43 MB","current-db-size-in-use-bytes":23040000,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-02T21:39:06.498217Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":311426633,"revision":5782,"compact-revision":2758} {"level":"info","ts":"2026-03-02T21:39:15.143288Z","caller":"traceutil/trace.go:171","msg":"trace[1614484948] transaction","detail":"{read_only:false; response_revision:7892; number_of_response:1; }","duration":"133.283456ms","start":"2026-03-02T21:39:15.009972Z","end":"2026-03-02T21:39:15.143256Z","steps":["trace[1614484948] 'process raft request' (duration: 133.079793ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:39:16.231375Z","caller":"traceutil/trace.go:171","msg":"trace[2122178539] transaction","detail":"{read_only:false; response_revision:7901; number_of_response:1; }","duration":"212.735427ms","start":"2026-03-02T21:39:16.018611Z","end":"2026-03-02T21:39:16.231347Z","steps":["trace[2122178539] 'process raft request' (duration: 212.560274ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:42:55.541825Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-02T21:42:55.546776Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-02T21:42:55.546867Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-02T21:44:06.402656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7805} {"level":"info","ts":"2026-03-02T21:44:06.465723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7805,"took":"60.510727ms","hash":1152946089,"current-db-size-bytes":43249664,"current-db-size":"43 MB","current-db-size-in-use-bytes":27156480,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-02T21:44:06.465788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1152946089,"revision":7805,"compact-revision":5782} {"level":"info","ts":"2026-03-02T21:49:06.410872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10296} {"level":"info","ts":"2026-03-02T21:49:06.48885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10296,"took":"74.335415ms","hash":1368773569,"current-db-size-bytes":43249664,"current-db-size":"43 MB","current-db-size-in-use-bytes":24662016,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-02T21:49:06.488919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1368773569,"revision":10296,"compact-revision":7805} {"level":"info","ts":"2026-03-02T21:50:06.246093Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000032d5.wal"} {"level":"info","ts":"2026-03-02T21:50:43.466818Z","caller":"traceutil/trace.go:171","msg":"trace[1210853922] linearizableReadLoop","detail":"{readStateIndex:13213; appliedIndex:13212; }","duration":"362.774547ms","start":"2026-03-02T21:50:43.10398Z","end":"2026-03-02T21:50:43.466754Z","steps":["trace[1210853922] 'read index received' (duration: 362.058514ms)","trace[1210853922] 'applied index is now lower than readState.Index' (duration: 714.473µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:50:43.467251Z","caller":"traceutil/trace.go:171","msg":"trace[563847414] transaction","detail":"{read_only:false; response_revision:12747; number_of_response:1; }","duration":"367.818511ms","start":"2026-03-02T21:50:43.099373Z","end":"2026-03-02T21:50:43.467191Z","steps":["trace[563847414] 'process raft request' (duration: 366.764062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:43.467389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.395569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-02T21:50:43.470326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:50:43.099339Z","time spent":"370.89384ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:50:43.47034Z","caller":"traceutil/trace.go:171","msg":"trace[1367469818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12747; }","duration":"366.399505ms","start":"2026-03-02T21:50:43.103924Z","end":"2026-03-02T21:50:43.470323Z","steps":["trace[1367469818] 'agreement among raft nodes before linearized reading' (duration: 363.286506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:43.470411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:50:43.103904Z","time spent":"366.480946ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T21:50:43.473287Z","caller":"traceutil/trace.go:171","msg":"trace[1864488943] transaction","detail":"{read_only:false; response_revision:12749; number_of_response:1; }","duration":"191.705042ms","start":"2026-03-02T21:50:43.281563Z","end":"2026-03-02T21:50:43.473268Z","steps":["trace[1864488943] 'process raft request' (duration: 191.62311ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:43.473317Z","caller":"traceutil/trace.go:171","msg":"trace[389748495] transaction","detail":"{read_only:false; response_revision:12750; number_of_response:1; }","duration":"113.489564ms","start":"2026-03-02T21:50:43.359787Z","end":"2026-03-02T21:50:43.473276Z","steps":["trace[389748495] 'process raft request' (duration: 113.424353ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:43.473438Z","caller":"traceutil/trace.go:171","msg":"trace[481355904] transaction","detail":"{read_only:false; response_revision:12748; number_of_response:1; }","duration":"193.649107ms","start":"2026-03-02T21:50:43.279783Z","end":"2026-03-02T21:50:43.473432Z","steps":["trace[481355904] 'process raft request' (duration: 193.320831ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:43.473511Z","caller":"traceutil/trace.go:171","msg":"trace[1710502198] transaction","detail":"{read_only:false; response_revision:12751; number_of_response:1; }","duration":"104.763412ms","start":"2026-03-02T21:50:43.36873Z","end":"2026-03-02T21:50:43.473494Z","steps":["trace[1710502198] 'process raft request' (duration: 104.518868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:43.473652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.624761ms","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-03-02T21:50:43.473677Z","caller":"traceutil/trace.go:171","msg":"trace[738571312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12751; }","duration":"365.710202ms","start":"2026-03-02T21:50:43.107961Z","end":"2026-03-02T21:50:43.473671Z","steps":["trace[738571312] 'agreement among raft nodes before linearized reading' (duration: 365.621391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:43.473692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:50:43.107952Z","time spent":"365.736483ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T21:50:53.016824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.003867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:50:53.016942Z","caller":"traceutil/trace.go:171","msg":"trace[870169542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12914; }","duration":"104.1746ms","start":"2026-03-02T21:50:52.912733Z","end":"2026-03-02T21:50:53.016908Z","steps":["trace[870169542] 'range keys from in-memory index tree' (duration: 103.891945ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:53.157137Z","caller":"traceutil/trace.go:171","msg":"trace[1906751436] transaction","detail":"{read_only:false; response_revision:12915; number_of_response:1; }","duration":"136.882199ms","start":"2026-03-02T21:50:53.0202Z","end":"2026-03-02T21:50:53.157083Z","steps":["trace[1906751436] 'process raft request' (duration: 136.637124ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:52:26.987611Z","caller":"traceutil/trace.go:171","msg":"trace[1765687373] transaction","detail":"{read_only:false; response_revision:13923; number_of_response:1; }","duration":"193.535365ms","start":"2026-03-02T21:52:26.794042Z","end":"2026-03-02T21:52:26.987577Z","steps":["trace[1765687373] 'process raft request' (duration: 193.29142ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:52:27.01816Z","caller":"traceutil/trace.go:171","msg":"trace[2010179917] linearizableReadLoop","detail":"{readStateIndex:14428; appliedIndex:14426; }","duration":"106.501024ms","start":"2026-03-02T21:52:26.911619Z","end":"2026-03-02T21:52:27.01812Z","steps":["trace[2010179917] 'read index received' (duration: 75.68022ms)","trace[2010179917] 'applied index is now lower than readState.Index' (duration: 30.819374ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:52:27.018551Z","caller":"traceutil/trace.go:171","msg":"trace[1735319307] transaction","detail":"{read_only:false; response_revision:13924; number_of_response:1; }","duration":"222.541895ms","start":"2026-03-02T21:52:26.795993Z","end":"2026-03-02T21:52:27.018534Z","steps":["trace[1735319307] 'process raft request' (duration: 221.951765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.01891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.492307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-02T21:52:27.019054Z","caller":"traceutil/trace.go:171","msg":"trace[1703563998] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13924; }","duration":"103.60719ms","start":"2026-03-02T21:52:26.915377Z","end":"2026-03-02T21:52:27.018984Z","steps":["trace[1703563998] 'agreement among raft nodes before linearized reading' (duration: 103.309884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.019174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.499992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:52:27.01923Z","caller":"traceutil/trace.go:171","msg":"trace[1317878005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13924; }","duration":"107.654545ms","start":"2026-03-02T21:52:26.911561Z","end":"2026-03-02T21:52:27.019216Z","steps":["trace[1317878005] 'agreement among raft nodes before linearized reading' (duration: 107.529643ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:54:06.418558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12175} {"level":"info","ts":"2026-03-02T21:54:06.494474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12175,"took":"73.225624ms","hash":1883662495,"current-db-size-bytes":43249664,"current-db-size":"43 MB","current-db-size-in-use-bytes":27856896,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-02T21:54:06.494533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1883662495,"revision":12175,"compact-revision":10296} {"level":"info","ts":"2026-03-02T21:58:16.944059Z","caller":"traceutil/trace.go:171","msg":"trace[1390488452] transaction","detail":"{read_only:false; response_revision:16388; number_of_response:1; }","duration":"114.300695ms","start":"2026-03-02T21:58:16.829739Z","end":"2026-03-02T21:58:16.94404Z","steps":["trace[1390488452] 'process raft request' (duration: 114.03925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:16.944341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.128928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-02T21:58:16.944373Z","caller":"traceutil/trace.go:171","msg":"trace[1296313034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16388; }","duration":"100.170019ms","start":"2026-03-02T21:58:16.844192Z","end":"2026-03-02T21:58:16.944362Z","steps":["trace[1296313034] 'agreement among raft nodes before linearized reading' (duration: 100.065037ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:58:17.168839Z","caller":"traceutil/trace.go:171","msg":"trace[905689017] linearizableReadLoop","detail":"{readStateIndex:16980; appliedIndex:16978; }","duration":"224.919985ms","start":"2026-03-02T21:58:16.943898Z","end":"2026-03-02T21:58:17.168818Z","steps":["trace[905689017] 'read index received' (duration: 222.861366ms)","trace[905689017] 'applied index is now lower than readState.Index' (duration: 2.057809ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:58:17.169093Z","caller":"traceutil/trace.go:171","msg":"trace[411086230] transaction","detail":"{read_only:false; response_revision:16389; number_of_response:1; }","duration":"324.173977ms","start":"2026-03-02T21:58:16.844909Z","end":"2026-03-02T21:58:17.169083Z","steps":["trace[411086230] 'process raft request' (duration: 321.824743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:17.169203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:58:16.844895Z","time spent":"324.226848ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:58:17.169316Z","caller":"traceutil/trace.go:171","msg":"trace[840772380] transaction","detail":"{read_only:false; response_revision:16390; number_of_response:1; }","duration":"256.251529ms","start":"2026-03-02T21:58:16.913058Z","end":"2026-03-02T21:58:17.169309Z","steps":["trace[840772380] 'process raft request' (duration: 255.632098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:17.169414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.694163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T21:58:17.169549Z","caller":"traceutil/trace.go:171","msg":"trace[1588500573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16390; }","duration":"259.856526ms","start":"2026-03-02T21:58:16.909647Z","end":"2026-03-02T21:58:17.169503Z","steps":["trace[1588500573] 'agreement among raft nodes before linearized reading' (duration: 259.647972ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:58:17.184993Z","caller":"traceutil/trace.go:171","msg":"trace[289928998] transaction","detail":"{read_only:false; response_revision:16391; number_of_response:1; }","duration":"236.97259ms","start":"2026-03-02T21:58:16.947973Z","end":"2026-03-02T21:58:17.184945Z","steps":["trace[289928998] 'process raft request' (duration: 236.567003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:17.185046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.153795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-02T21:58:17.185354Z","caller":"traceutil/trace.go:171","msg":"trace[1619867633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16393; }","duration":"154.495512ms","start":"2026-03-02T21:58:17.030825Z","end":"2026-03-02T21:58:17.185321Z","steps":["trace[1619867633] 'agreement among raft nodes before linearized reading' (duration: 154.032783ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:58:17.554845Z","caller":"traceutil/trace.go:171","msg":"trace[617868095] linearizableReadLoop","detail":"{readStateIndex:16986; appliedIndex:16985; }","duration":"134.428137ms","start":"2026-03-02T21:58:17.420394Z","end":"2026-03-02T21:58:17.554822Z","steps":["trace[617868095] 'read index received' (duration: 134.210253ms)","trace[617868095] 'applied index is now lower than readState.Index' (duration: 217.104µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:58:17.555299Z","caller":"traceutil/trace.go:171","msg":"trace[1525783545] transaction","detail":"{read_only:false; response_revision:16396; number_of_response:1; }","duration":"214.176394ms","start":"2026-03-02T21:58:17.341066Z","end":"2026-03-02T21:58:17.555243Z","steps":["trace[1525783545] 'process raft request' (duration: 213.598224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:17.555463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.930035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-03-02T21:58:17.555532Z","caller":"traceutil/trace.go:171","msg":"trace[869917266] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:16396; }","duration":"129.071677ms","start":"2026-03-02T21:58:17.426441Z","end":"2026-03-02T21:58:17.555513Z","steps":["trace[869917266] 'agreement among raft nodes before linearized reading' (duration: 128.751801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:17.555987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.885106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-02T21:58:17.556134Z","caller":"traceutil/trace.go:171","msg":"trace[1496217340] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:16396; }","duration":"135.740961ms","start":"2026-03-02T21:58:17.420337Z","end":"2026-03-02T21:58:17.556078Z","steps":["trace[1496217340] 'agreement among raft nodes before linearized reading' (duration: 134.756043ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:58:17.857655Z","caller":"traceutil/trace.go:171","msg":"trace[2024026455] transaction","detail":"{read_only:false; response_revision:16398; number_of_response:1; }","duration":"292.185389ms","start":"2026-03-02T21:58:17.565448Z","end":"2026-03-02T21:58:17.857634Z","steps":["trace[2024026455] 'process raft request' (duration: 292.054227ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:58:18.060894Z","caller":"traceutil/trace.go:171","msg":"trace[29580588] linearizableReadLoop","detail":"{readStateIndex:16990; appliedIndex:16989; }","duration":"119.865145ms","start":"2026-03-02T21:58:17.941002Z","end":"2026-03-02T21:58:18.060867Z","steps":["trace[29580588] 'read index received' (duration: 63.913982ms)","trace[29580588] 'applied index is now lower than readState.Index' (duration: 55.949993ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:58:18.060956Z","caller":"traceutil/trace.go:171","msg":"trace[1102850926] transaction","detail":"{read_only:false; response_revision:16400; number_of_response:1; }","duration":"122.390611ms","start":"2026-03-02T21:58:17.93854Z","end":"2026-03-02T21:58:18.060931Z","steps":["trace[1102850926] 'process raft request' (duration: 66.243404ms)","trace[1102850926] 'compare' (duration: 55.945894ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:58:18.061168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.154131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3297"} {"level":"info","ts":"2026-03-02T21:58:18.061261Z","caller":"traceutil/trace.go:171","msg":"trace[1691762299] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:16400; }","duration":"120.278443ms","start":"2026-03-02T21:58:17.940962Z","end":"2026-03-02T21:58:18.06124Z","steps":["trace[1691762299] 'agreement among raft nodes before linearized reading' (duration: 120.019997ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:59:06.429585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14517} {"level":"info","ts":"2026-03-02T21:59:06.531515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14517,"took":"97.185463ms","hash":3638361194,"current-db-size-bytes":44093440,"current-db-size":"44 MB","current-db-size-in-use-bytes":30064640,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-02T21:59:06.53159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3638361194,"revision":14517,"compact-revision":12175} {"level":"warn","ts":"2026-03-02T22:03:56.867639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.658578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:03:56.867867Z","caller":"traceutil/trace.go:171","msg":"trace[2115191261] transaction","detail":"{read_only:false; response_revision:18834; number_of_response:1; }","duration":"332.231606ms","start":"2026-03-02T22:03:56.535613Z","end":"2026-03-02T22:03:56.867845Z","steps":["trace[2115191261] 'process raft request' (duration: 219.214312ms)","trace[2115191261] 'compare' (duration: 112.160589ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:03:56.867987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:03:56.535583Z","time spent":"332.366808ms","remote":"127.0.0.1:40014","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-03-02T22:03:56.868403Z","caller":"traceutil/trace.go:171","msg":"trace[1202500530] transaction","detail":"{read_only:false; response_revision:18835; number_of_response:1; }","duration":"324.606775ms","start":"2026-03-02T22:03:56.543753Z","end":"2026-03-02T22:03:56.86836Z","steps":["trace[1202500530] 'process raft request' (duration: 324.027874ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:03:56.868562Z","caller":"traceutil/trace.go:171","msg":"trace[1881371838] transaction","detail":"{read_only:false; response_revision:18836; number_of_response:1; }","duration":"318.352468ms","start":"2026-03-02T22:03:56.550185Z","end":"2026-03-02T22:03:56.868538Z","steps":["trace[1881371838] 'process raft request' (duration: 318.060213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:03:56.868626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:03:56.543719Z","time spent":"324.795449ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:03:56.868685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:03:56.550171Z","time spent":"318.44863ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:04:06.440198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16953} {"level":"info","ts":"2026-03-02T22:04:06.548137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16953,"took":"104.592059ms","hash":3729623132,"current-db-size-bytes":45359104,"current-db-size":"45 MB","current-db-size-in-use-bytes":29573120,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-02T22:04:06.548204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3729623132,"revision":16953,"compact-revision":14517} {"level":"info","ts":"2026-03-02T22:04:40.672186Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-02T22:04:40.676672Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-03-02T22:04:40.676844Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-03-02T22:07:52.60753Z","caller":"traceutil/trace.go:171","msg":"trace[2003732653] transaction","detail":"{read_only:false; response_revision:21170; number_of_response:1; }","duration":"177.151509ms","start":"2026-03-02T22:07:52.430329Z","end":"2026-03-02T22:07:52.60748Z","steps":["trace[2003732653] 'process raft request' (duration: 119.260541ms)","trace[2003732653] 'compare' (duration: 57.655624ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:09:06.450504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18880} {"level":"info","ts":"2026-03-02T22:09:06.554679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18880,"took":"99.272549ms","hash":2833663023,"current-db-size-bytes":48771072,"current-db-size":"49 MB","current-db-size-in-use-bytes":38432768,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-03-02T22:09:06.554825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2833663023,"revision":18880,"compact-revision":16953} {"level":"info","ts":"2026-03-02T22:11:11.701653Z","caller":"traceutil/trace.go:171","msg":"trace[1345928318] linearizableReadLoop","detail":"{readStateIndex:23933; appliedIndex:23932; }","duration":"146.740072ms","start":"2026-03-02T22:11:11.554878Z","end":"2026-03-02T22:11:11.701618Z","steps":["trace[1345928318] 'read index received' (duration: 146.525608ms)","trace[1345928318] 'applied index is now lower than readState.Index' (duration: 213.114µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:11.702072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.167481ms","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-03-02T22:11:11.702118Z","caller":"traceutil/trace.go:171","msg":"trace[1439435891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23126; }","duration":"147.290484ms","start":"2026-03-02T22:11:11.554816Z","end":"2026-03-02T22:11:11.702107Z","steps":["trace[1439435891] 'agreement among raft nodes before linearized reading' (duration: 146.932386ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:11.702496Z","caller":"traceutil/trace.go:171","msg":"trace[1143101575] transaction","detail":"{read_only:false; response_revision:23126; number_of_response:1; }","duration":"164.736047ms","start":"2026-03-02T22:11:11.537747Z","end":"2026-03-02T22:11:11.702483Z","steps":["trace[1143101575] 'process raft request' (duration: 163.676257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:11.861439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.243732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:11.861697Z","caller":"traceutil/trace.go:171","msg":"trace[86195769] transaction","detail":"{read_only:false; response_revision:23127; number_of_response:1; }","duration":"152.481941ms","start":"2026-03-02T22:11:11.709171Z","end":"2026-03-02T22:11:11.861653Z","steps":["trace[86195769] 'compare' (duration: 147.008368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:16.23623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.645993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:16.236328Z","caller":"traceutil/trace.go:171","msg":"trace[788931522] transaction","detail":"{read_only:false; response_revision:23159; number_of_response:1; }","duration":"293.634368ms","start":"2026-03-02T22:11:15.942677Z","end":"2026-03-02T22:11:16.236312Z","steps":["trace[788931522] 'process raft request' (duration: 111.835633ms)","trace[788931522] 'compare' (duration: 181.462429ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:16.240966Z","caller":"traceutil/trace.go:171","msg":"trace[1310493179] transaction","detail":"{read_only:false; response_revision:23160; number_of_response:1; }","duration":"293.408575ms","start":"2026-03-02T22:11:15.947523Z","end":"2026-03-02T22:11:16.240931Z","steps":["trace[1310493179] 'process raft request' (duration: 293.182501ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:16.484622Z","caller":"traceutil/trace.go:171","msg":"trace[1500225907] linearizableReadLoop","detail":"{readStateIndex:23969; appliedIndex:23968; }","duration":"220.14284ms","start":"2026-03-02T22:11:16.264459Z","end":"2026-03-02T22:11:16.484602Z","steps":["trace[1500225907] 'read index received' (duration: 139.470718ms)","trace[1500225907] 'applied index is now lower than readState.Index' (duration: 80.671512ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:16.484857Z","caller":"traceutil/trace.go:171","msg":"trace[154998131] transaction","detail":"{read_only:false; response_revision:23161; number_of_response:1; }","duration":"221.054427ms","start":"2026-03-02T22:11:16.263791Z","end":"2026-03-02T22:11:16.484845Z","steps":["trace[154998131] 'process raft request' (duration: 140.251962ms)","trace[154998131] 'compare' (duration: 80.272165ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:16.485042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.571278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-02T22:11:16.485069Z","caller":"traceutil/trace.go:171","msg":"trace[159707409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23161; }","duration":"220.652679ms","start":"2026-03-02T22:11:16.26441Z","end":"2026-03-02T22:11:16.485063Z","steps":["trace[159707409] 'agreement among raft nodes before linearized reading' (duration: 220.560027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:16.485203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.68812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-02T22:11:16.485236Z","caller":"traceutil/trace.go:171","msg":"trace[481168814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23161; }","duration":"220.735411ms","start":"2026-03-02T22:11:16.264495Z","end":"2026-03-02T22:11:16.48523Z","steps":["trace[481168814] 'agreement among raft nodes before linearized reading' (duration: 220.6672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:16.728259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.50841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:16.72836Z","caller":"traceutil/trace.go:171","msg":"trace[963616952] transaction","detail":"{read_only:false; response_revision:23162; number_of_response:1; }","duration":"240.251935ms","start":"2026-03-02T22:11:16.488088Z","end":"2026-03-02T22:11:16.72834Z","steps":["trace[963616952] 'process raft request' (duration: 115.579452ms)","trace[963616952] 'compare' (duration: 124.127013ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:16.751585Z","caller":"traceutil/trace.go:171","msg":"trace[1650972177] transaction","detail":"{read_only:false; response_revision:23164; number_of_response:1; }","duration":"174.546701ms","start":"2026-03-02T22:11:16.577004Z","end":"2026-03-02T22:11:16.75155Z","steps":["trace[1650972177] 'process raft request' (duration: 174.433469ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:16.751938Z","caller":"traceutil/trace.go:171","msg":"trace[1256668085] transaction","detail":"{read_only:false; response_revision:23165; number_of_response:1; }","duration":"171.288659ms","start":"2026-03-02T22:11:16.580627Z","end":"2026-03-02T22:11:16.751916Z","steps":["trace[1256668085] 'process raft request' (duration: 170.851221ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:16.752038Z","caller":"traceutil/trace.go:171","msg":"trace[1129257574] transaction","detail":"{read_only:false; response_revision:23163; number_of_response:1; }","duration":"263.195681ms","start":"2026-03-02T22:11:16.488829Z","end":"2026-03-02T22:11:16.752024Z","steps":["trace[1129257574] 'process raft request' (duration: 262.406487ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:16.752204Z","caller":"traceutil/trace.go:171","msg":"trace[980508325] linearizableReadLoop","detail":"{readStateIndex:23974; appliedIndex:23969; }","duration":"158.755105ms","start":"2026-03-02T22:11:16.59344Z","end":"2026-03-02T22:11:16.752195Z","steps":["trace[980508325] 'read index received' (duration: 10.419493ms)","trace[980508325] 'applied index is now lower than readState.Index' (duration: 148.334672ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:16.752291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.845328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:16.75232Z","caller":"traceutil/trace.go:171","msg":"trace[1838256003] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:23166; }","duration":"158.909089ms","start":"2026-03-02T22:11:16.5934Z","end":"2026-03-02T22:11:16.752309Z","steps":["trace[1838256003] 'agreement among raft nodes before linearized reading' (duration: 158.831377ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:16.752721Z","caller":"traceutil/trace.go:171","msg":"trace[810836161] transaction","detail":"{read_only:false; response_revision:23166; number_of_response:1; }","duration":"170.364253ms","start":"2026-03-02T22:11:16.582342Z","end":"2026-03-02T22:11:16.752706Z","steps":["trace[810836161] 'process raft request' (duration: 169.490386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:17.167578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.362341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:17.167726Z","caller":"traceutil/trace.go:171","msg":"trace[427506055] linearizableReadLoop","detail":"{readStateIndex:23975; appliedIndex:23974; }","duration":"360.487234ms","start":"2026-03-02T22:11:16.807211Z","end":"2026-03-02T22:11:17.167698Z","steps":["trace[427506055] 'read index received' (duration: 56.690806ms)","trace[427506055] 'applied index is now lower than readState.Index' (duration: 303.791268ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:17.167868Z","caller":"traceutil/trace.go:171","msg":"trace[2032261180] transaction","detail":"{read_only:false; response_revision:23167; number_of_response:1; }","duration":"483.871422ms","start":"2026-03-02T22:11:16.683974Z","end":"2026-03-02T22:11:17.167846Z","steps":["trace[2032261180] 'process raft request' (duration: 180.074033ms)","trace[2032261180] 'compare' (duration: 303.115645ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:17.167951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:16.683947Z","time spent":"483.960243ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:17.168034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.5793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:17.168209Z","caller":"traceutil/trace.go:171","msg":"trace[1378533680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23167; }","duration":"255.775854ms","start":"2026-03-02T22:11:16.912381Z","end":"2026-03-02T22:11:17.168157Z","steps":["trace[1378533680] 'agreement among raft nodes before linearized reading' (duration: 255.513529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:17.168622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.412411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:11:17.168669Z","caller":"traceutil/trace.go:171","msg":"trace[1867905649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23167; }","duration":"361.465192ms","start":"2026-03-02T22:11:16.807195Z","end":"2026-03-02T22:11:17.16866Z","steps":["trace[1867905649] 'agreement among raft nodes before linearized reading' (duration: 361.210947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:17.168708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:16.807109Z","time spent":"361.579364ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T22:11:17.722661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.012206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:17.722761Z","caller":"traceutil/trace.go:171","msg":"trace[880391020] linearizableReadLoop","detail":"{readStateIndex:23979; appliedIndex:23978; }","duration":"362.923239ms","start":"2026-03-02T22:11:17.359821Z","end":"2026-03-02T22:11:17.722744Z","steps":["trace[880391020] 'read index received' (duration: 119.692799ms)","trace[880391020] 'applied index is now lower than readState.Index' (duration: 243.22934ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:17.722846Z","caller":"traceutil/trace.go:171","msg":"trace[347191140] transaction","detail":"{read_only:false; response_revision:23171; number_of_response:1; }","duration":"434.228947ms","start":"2026-03-02T22:11:17.288605Z","end":"2026-03-02T22:11:17.722834Z","steps":["trace[347191140] 'process raft request' (duration: 190.955696ms)","trace[347191140] 'compare' (duration: 242.880864ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:17.722912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:17.288586Z","time spent":"434.294669ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:17.72299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.867565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-03-02T22:11:17.723029Z","caller":"traceutil/trace.go:171","msg":"trace[406632785] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23171; }","duration":"291.936117ms","start":"2026-03-02T22:11:17.431082Z","end":"2026-03-02T22:11:17.723018Z","steps":["trace[406632785] 'agreement among raft nodes before linearized reading' (duration: 291.801955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:17.723219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.5644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-03-02T22:11:17.723249Z","caller":"traceutil/trace.go:171","msg":"trace[497090193] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:23171; }","duration":"371.670932ms","start":"2026-03-02T22:11:17.351568Z","end":"2026-03-02T22:11:17.723239Z","steps":["trace[497090193] 'agreement among raft nodes before linearized reading' (duration: 371.599821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:17.723268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:17.351542Z","time spent":"371.720523ms","remote":"127.0.0.1:39896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1872,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"warn","ts":"2026-03-02T22:11:18.006202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.825836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:18.006389Z","caller":"traceutil/trace.go:171","msg":"trace[564695797] transaction","detail":"{read_only:false; response_revision:23172; number_of_response:1; }","duration":"242.773182ms","start":"2026-03-02T22:11:17.763597Z","end":"2026-03-02T22:11:18.00637Z","steps":["trace[564695797] 'process raft request' (duration: 103.684531ms)","trace[564695797] 'compare' (duration: 138.720904ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:33.582503Z","caller":"traceutil/trace.go:171","msg":"trace[426394121] transaction","detail":"{read_only:false; response_revision:23285; number_of_response:1; }","duration":"124.384426ms","start":"2026-03-02T22:11:33.458075Z","end":"2026-03-02T22:11:33.582459Z","steps":["trace[426394121] 'process raft request' (duration: 104.940774ms)","trace[426394121] 'compare' (duration: 18.687698ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:39.219575Z","caller":"traceutil/trace.go:171","msg":"trace[1678676173] transaction","detail":"{read_only:false; response_revision:23320; number_of_response:1; }","duration":"306.63397ms","start":"2026-03-02T22:11:38.912884Z","end":"2026-03-02T22:11:39.219518Z","steps":["trace[1678676173] 'process raft request' (duration: 248.517519ms)","trace[1678676173] 'compare' (duration: 57.893987ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:39.219897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:38.912869Z","time spent":"306.910206ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:39.585553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.873116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:11:39.585659Z","caller":"traceutil/trace.go:171","msg":"trace[738806632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23320; }","duration":"247.996668ms","start":"2026-03-02T22:11:39.337628Z","end":"2026-03-02T22:11:39.585624Z","steps":["trace[738806632] 'range keys from in-memory index tree' (duration: 247.652192ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:39.585919Z","caller":"traceutil/trace.go:171","msg":"trace[845045961] transaction","detail":"{read_only:false; response_revision:23321; number_of_response:1; }","duration":"126.475986ms","start":"2026-03-02T22:11:39.459419Z","end":"2026-03-02T22:11:39.585895Z","steps":["trace[845045961] 'process raft request' (duration: 126.19133ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:39.897897Z","caller":"traceutil/trace.go:171","msg":"trace[1239171287] linearizableReadLoop","detail":"{readStateIndex:24135; appliedIndex:24134; }","duration":"197.339046ms","start":"2026-03-02T22:11:39.700537Z","end":"2026-03-02T22:11:39.897876Z","steps":["trace[1239171287] 'read index received' (duration: 197.124992ms)","trace[1239171287] 'applied index is now lower than readState.Index' (duration: 212.864µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:39.898323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.769073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-03-02T22:11:39.898421Z","caller":"traceutil/trace.go:171","msg":"trace[321408891] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:23323; }","duration":"197.887466ms","start":"2026-03-02T22:11:39.700499Z","end":"2026-03-02T22:11:39.898386Z","steps":["trace[321408891] 'agreement among raft nodes before linearized reading' (duration: 197.58575ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:39.898425Z","caller":"traceutil/trace.go:171","msg":"trace[122805649] transaction","detail":"{read_only:false; response_revision:23323; number_of_response:1; }","duration":"219.980108ms","start":"2026-03-02T22:11:39.678392Z","end":"2026-03-02T22:11:39.898372Z","steps":["trace[122805649] 'process raft request' (duration: 219.281505ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:40.394876Z","caller":"traceutil/trace.go:171","msg":"trace[1137582204] linearizableReadLoop","detail":"{readStateIndex:24138; appliedIndex:24137; }","duration":"123.786485ms","start":"2026-03-02T22:11:40.271045Z","end":"2026-03-02T22:11:40.394831Z","steps":["trace[1137582204] 'read index received' (duration: 123.333167ms)","trace[1137582204] 'applied index is now lower than readState.Index' (duration: 451.688µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:40.394971Z","caller":"traceutil/trace.go:171","msg":"trace[1622983082] transaction","detail":"{read_only:false; response_revision:23325; number_of_response:1; }","duration":"177.76745ms","start":"2026-03-02T22:11:40.217163Z","end":"2026-03-02T22:11:40.394931Z","steps":["trace[1622983082] 'process raft request' (duration: 177.393593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:40.395133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.030429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:40.395206Z","caller":"traceutil/trace.go:171","msg":"trace[1107586958] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:23325; }","duration":"124.188182ms","start":"2026-03-02T22:11:40.270993Z","end":"2026-03-02T22:11:40.395181Z","steps":["trace[1107586958] 'agreement among raft nodes before linearized reading' (duration: 123.999789ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:40.661306Z","caller":"traceutil/trace.go:171","msg":"trace[2018971668] transaction","detail":"{read_only:false; response_revision:23327; number_of_response:1; }","duration":"121.085464ms","start":"2026-03-02T22:11:40.540196Z","end":"2026-03-02T22:11:40.661282Z","steps":["trace[2018971668] 'process raft request' (duration: 120.933722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:40.661735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.221157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:40.661822Z","caller":"traceutil/trace.go:171","msg":"trace[1811276508] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:23327; }","duration":"129.39548ms","start":"2026-03-02T22:11:40.532406Z","end":"2026-03-02T22:11:40.661802Z","steps":["trace[1811276508] 'agreement among raft nodes before linearized reading' (duration: 129.254357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:40.661844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.957987ms","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-03-02T22:11:40.661996Z","caller":"traceutil/trace.go:171","msg":"trace[311518487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23327; }","duration":"161.105109ms","start":"2026-03-02T22:11:40.500833Z","end":"2026-03-02T22:11:40.661938Z","steps":["trace[311518487] 'agreement among raft nodes before linearized reading' (duration: 160.869035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:40.662047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.864569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-02T22:11:40.662179Z","caller":"traceutil/trace.go:171","msg":"trace[1065448426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23327; }","duration":"166.047512ms","start":"2026-03-02T22:11:40.496102Z","end":"2026-03-02T22:11:40.662149Z","steps":["trace[1065448426] 'agreement among raft nodes before linearized reading' (duration: 165.652435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:40.867147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.353559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:40.867533Z","caller":"traceutil/trace.go:171","msg":"trace[420123166] transaction","detail":"{read_only:false; response_revision:23328; number_of_response:1; }","duration":"201.769288ms","start":"2026-03-02T22:11:40.665721Z","end":"2026-03-02T22:11:40.867491Z","steps":["trace[420123166] 'process raft request' (duration: 56.980861ms)","trace[420123166] 'compare' (duration: 144.066334ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:40.867758Z","caller":"traceutil/trace.go:171","msg":"trace[973745250] transaction","detail":"{read_only:false; response_revision:23329; number_of_response:1; }","duration":"200.709918ms","start":"2026-03-02T22:11:40.667015Z","end":"2026-03-02T22:11:40.867725Z","steps":["trace[973745250] 'process raft request' (duration: 200.26216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:41.179173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.305865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:41.17927Z","caller":"traceutil/trace.go:171","msg":"trace[810363778] transaction","detail":"{read_only:false; response_revision:23330; number_of_response:1; }","duration":"266.461823ms","start":"2026-03-02T22:11:40.91279Z","end":"2026-03-02T22:11:41.179252Z","steps":["trace[810363778] 'process raft request' (duration: 127.011576ms)","trace[810363778] 'compare' (duration: 138.934887ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:41.228605Z","caller":"traceutil/trace.go:171","msg":"trace[846717391] transaction","detail":"{read_only:false; response_revision:23331; number_of_response:1; }","duration":"315.666959ms","start":"2026-03-02T22:11:40.91292Z","end":"2026-03-02T22:11:41.228587Z","steps":["trace[846717391] 'process raft request' (duration: 315.484586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:41.228722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:40.912908Z","time spent":"315.759011ms","remote":"127.0.0.1:40014","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-03-02T22:11:41.228774Z","caller":"traceutil/trace.go:171","msg":"trace[1387254164] linearizableReadLoop","detail":"{readStateIndex:24144; appliedIndex:24142; }","duration":"315.815822ms","start":"2026-03-02T22:11:40.91292Z","end":"2026-03-02T22:11:41.228735Z","steps":["trace[1387254164] 'read index received' (duration: 126.918203ms)","trace[1387254164] 'applied index is now lower than readState.Index' (duration: 188.895829ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:41.228798Z","caller":"traceutil/trace.go:171","msg":"trace[306767226] transaction","detail":"{read_only:false; response_revision:23332; number_of_response:1; }","duration":"315.602418ms","start":"2026-03-02T22:11:40.913167Z","end":"2026-03-02T22:11:41.22877Z","steps":["trace[306767226] 'process raft request' (duration: 315.387444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:41.22889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.972495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-02T22:11:41.228915Z","caller":"traceutil/trace.go:171","msg":"trace[2117039216] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23332; }","duration":"316.034696ms","start":"2026-03-02T22:11:40.912875Z","end":"2026-03-02T22:11:41.22891Z","steps":["trace[2117039216] 'agreement among raft nodes before linearized reading' (duration: 315.950984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:41.228931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:40.912852Z","time spent":"316.074567ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-02T22:11:41.228933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:40.913109Z","time spent":"315.741131ms","remote":"127.0.0.1:40014","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-03-02T22:11:41.229086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.393813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-02T22:11:41.229122Z","caller":"traceutil/trace.go:171","msg":"trace[568068425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23332; }","duration":"294.490295ms","start":"2026-03-02T22:11:40.934624Z","end":"2026-03-02T22:11:41.229114Z","steps":["trace[568068425] 'agreement among raft nodes before linearized reading' (duration: 294.26105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:41.534231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.890251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:41.53468Z","caller":"traceutil/trace.go:171","msg":"trace[28636322] transaction","detail":"{read_only:false; response_revision:23334; number_of_response:1; }","duration":"302.082416ms","start":"2026-03-02T22:11:41.232554Z","end":"2026-03-02T22:11:41.534636Z","steps":["trace[28636322] 'process raft request' (duration: 301.773041ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:41.5347Z","caller":"traceutil/trace.go:171","msg":"trace[1492629740] linearizableReadLoop","detail":"{readStateIndex:24147; appliedIndex:24145; }","duration":"249.731221ms","start":"2026-03-02T22:11:41.284951Z","end":"2026-03-02T22:11:41.534682Z","steps":["trace[1492629740] 'read index received' (duration: 18.346161ms)","trace[1492629740] 'applied index is now lower than readState.Index' (duration: 231.38354ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:41.53486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:41.232511Z","time spent":"302.244238ms","remote":"127.0.0.1:40014","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-03-02T22:11:41.534926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.972655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-03-02T22:11:41.53496Z","caller":"traceutil/trace.go:171","msg":"trace[1252003718] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23335; }","duration":"250.065767ms","start":"2026-03-02T22:11:41.284884Z","end":"2026-03-02T22:11:41.53495Z","steps":["trace[1252003718] 'agreement among raft nodes before linearized reading' (duration: 249.889334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:41.534977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.542957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:41.535022Z","caller":"traceutil/trace.go:171","msg":"trace[69071547] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23335; }","duration":"228.643089ms","start":"2026-03-02T22:11:41.306365Z","end":"2026-03-02T22:11:41.535008Z","steps":["trace[69071547] 'agreement among raft nodes before linearized reading' (duration: 228.574518ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:41.535075Z","caller":"traceutil/trace.go:171","msg":"trace[1372205724] transaction","detail":"{read_only:false; response_revision:23333; number_of_response:1; }","duration":"302.612355ms","start":"2026-03-02T22:11:41.232418Z","end":"2026-03-02T22:11:41.535031Z","steps":["trace[1372205724] 'process raft request' (duration: 70.862369ms)","trace[1372205724] 'compare' (duration: 230.656696ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:41.535285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:41.232402Z","time spent":"302.780268ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:46.615819Z","caller":"traceutil/trace.go:171","msg":"trace[662909667] transaction","detail":"{read_only:false; response_revision:23427; number_of_response:1; }","duration":"203.377598ms","start":"2026-03-02T22:11:46.412425Z","end":"2026-03-02T22:11:46.615803Z","steps":["trace[662909667] 'process raft request' (duration: 203.113554ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:46.61573Z","caller":"traceutil/trace.go:171","msg":"trace[435011100] linearizableReadLoop","detail":"{readStateIndex:24242; appliedIndex:24241; }","duration":"154.427516ms","start":"2026-03-02T22:11:46.461282Z","end":"2026-03-02T22:11:46.615709Z","steps":["trace[435011100] 'read index received' (duration: 154.229272ms)","trace[435011100] 'applied index is now lower than readState.Index' (duration: 197.324µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:46.616004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.69092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:46.616092Z","caller":"traceutil/trace.go:171","msg":"trace[1916484837] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:23427; }","duration":"154.842433ms","start":"2026-03-02T22:11:46.461232Z","end":"2026-03-02T22:11:46.616075Z","steps":["trace[1916484837] 'agreement among raft nodes before linearized reading' (duration: 154.66088ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:46.644707Z","caller":"traceutil/trace.go:171","msg":"trace[1134496724] transaction","detail":"{read_only:false; response_revision:23428; number_of_response:1; }","duration":"102.982578ms","start":"2026-03-02T22:11:46.541701Z","end":"2026-03-02T22:11:46.644683Z","steps":["trace[1134496724] 'process raft request' (duration: 102.765174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.208916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710898989433632114,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T22:11:47.283862Z","caller":"traceutil/trace.go:171","msg":"trace[701378154] transaction","detail":"{read_only:false; response_revision:23430; number_of_response:1; }","duration":"631.909348ms","start":"2026-03-02T22:11:46.65192Z","end":"2026-03-02T22:11:47.283829Z","steps":["trace[701378154] 'process raft request' (duration: 631.699834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.284305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:46.651894Z","time spent":"632.06227ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:47.314108Z","caller":"traceutil/trace.go:171","msg":"trace[28040213] linearizableReadLoop","detail":"{readStateIndex:24246; appliedIndex:24245; }","duration":"605.457775ms","start":"2026-03-02T22:11:46.708623Z","end":"2026-03-02T22:11:47.314081Z","steps":["trace[28040213] 'read index received' (duration: 575.382235ms)","trace[28040213] 'applied index is now lower than readState.Index' (duration: 30.07422ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:47.314378Z","caller":"traceutil/trace.go:171","msg":"trace[2001257388] transaction","detail":"{read_only:false; response_revision:23431; number_of_response:1; }","duration":"118.16734ms","start":"2026-03-02T22:11:47.196173Z","end":"2026-03-02T22:11:47.31434Z","steps":["trace[2001257388] 'process raft request' (duration: 117.662471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.314504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.71794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:47.314607Z","caller":"traceutil/trace.go:171","msg":"trace[430816077] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23432; }","duration":"605.983915ms","start":"2026-03-02T22:11:46.708576Z","end":"2026-03-02T22:11:47.31456Z","steps":["trace[430816077] 'agreement among raft nodes before linearized reading' (duration: 605.638099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.314767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:46.708554Z","time spent":"606.189809ms","remote":"127.0.0.1:40202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-03-02T22:11:47.423091Z","caller":"traceutil/trace.go:171","msg":"trace[2031469070] linearizableReadLoop","detail":"{readStateIndex:24248; appliedIndex:24247; }","duration":"108.881088ms","start":"2026-03-02T22:11:47.314188Z","end":"2026-03-02T22:11:47.423069Z","steps":["trace[2031469070] 'read index received' (duration: 108.630904ms)","trace[2031469070] 'applied index is now lower than readState.Index' (duration: 249.144µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:47.423161Z","caller":"traceutil/trace.go:171","msg":"trace[2015212921] transaction","detail":"{read_only:false; response_revision:23433; number_of_response:1; }","duration":"131.947668ms","start":"2026-03-02T22:11:47.291194Z","end":"2026-03-02T22:11:47.423142Z","steps":["trace[2015212921] 'process raft request' (duration: 131.671393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.423215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.527648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-02T22:11:47.423249Z","caller":"traceutil/trace.go:171","msg":"trace[899895146] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23433; }","duration":"220.612799ms","start":"2026-03-02T22:11:47.202627Z","end":"2026-03-02T22:11:47.42324Z","steps":["trace[899895146] 'agreement among raft nodes before linearized reading' (duration: 220.480377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.423356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.921535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:47.423388Z","caller":"traceutil/trace.go:171","msg":"trace[1209857833] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:23433; }","duration":"205.995317ms","start":"2026-03-02T22:11:47.217385Z","end":"2026-03-02T22:11:47.42338Z","steps":["trace[1209857833] 'agreement among raft nodes before linearized reading' (duration: 205.947016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.423387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.54774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-02T22:11:47.423428Z","caller":"traceutil/trace.go:171","msg":"trace[1202262367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23433; }","duration":"531.60511ms","start":"2026-03-02T22:11:46.891806Z","end":"2026-03-02T22:11:47.423411Z","steps":["trace[1202262367] 'agreement among raft nodes before linearized reading' (duration: 531.463958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.423459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:46.891792Z","time spent":"531.659381ms","remote":"127.0.0.1:40014","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":"warn","ts":"2026-03-02T22:11:47.423597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.806392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:47.42363Z","caller":"traceutil/trace.go:171","msg":"trace[1040499662] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23433; }","duration":"104.839212ms","start":"2026-03-02T22:11:47.318784Z","end":"2026-03-02T22:11:47.423624Z","steps":["trace[1040499662] 'agreement among raft nodes before linearized reading' (duration: 104.793442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.423235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.011498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:47.423714Z","caller":"traceutil/trace.go:171","msg":"trace[1454946277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23433; }","duration":"503.581479ms","start":"2026-03-02T22:11:46.920114Z","end":"2026-03-02T22:11:47.423695Z","steps":["trace[1454946277] 'agreement among raft nodes before linearized reading' (duration: 503.069299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.423602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.248812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-02T22:11:47.423797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:46.920088Z","time spent":"503.68706ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T22:11:47.423832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.539771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-03-02T22:11:47.423838Z","caller":"traceutil/trace.go:171","msg":"trace[1524594392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23433; }","duration":"532.472486ms","start":"2026-03-02T22:11:46.891346Z","end":"2026-03-02T22:11:47.423819Z","steps":["trace[1524594392] 'agreement among raft nodes before linearized reading' (duration: 532.203511ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:47.423874Z","caller":"traceutil/trace.go:171","msg":"trace[1020718710] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:23433; }","duration":"101.585712ms","start":"2026-03-02T22:11:47.322279Z","end":"2026-03-02T22:11:47.423865Z","steps":["trace[1020718710] 'agreement among raft nodes before linearized reading' (duration: 101.382388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.423904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:46.891302Z","time spent":"532.576478ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T22:11:56.161643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.863332ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T22:11:56.162427Z","caller":"traceutil/trace.go:171","msg":"trace[1257512393] transaction","detail":"{read_only:false; response_revision:23510; number_of_response:1; }","duration":"819.896369ms","start":"2026-03-02T22:11:55.342512Z","end":"2026-03-02T22:11:56.162408Z","steps":["trace[1257512393] 'process raft request' (duration: 819.255426ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:56.162446Z","caller":"traceutil/trace.go:171","msg":"trace[149940106] linearizableReadLoop","detail":"{readStateIndex:24329; appliedIndex:24325; }","duration":"705.200573ms","start":"2026-03-02T22:11:55.457221Z","end":"2026-03-02T22:11:56.162421Z","steps":["trace[149940106] 'read index received' (duration: 178.688908ms)","trace[149940106] 'applied index is now lower than readState.Index' (duration: 526.510835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:56.162531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.342493Z","time spent":"819.973021ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:56.162644Z","caller":"traceutil/trace.go:171","msg":"trace[1317045809] transaction","detail":"{read_only:false; response_revision:23512; number_of_response:1; }","duration":"708.979533ms","start":"2026-03-02T22:11:55.453655Z","end":"2026-03-02T22:11:56.162634Z","steps":["trace[1317045809] 'process raft request' (duration: 708.727448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.162701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.453635Z","time spent":"709.037695ms","remote":"127.0.0.1:40014","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-03-02T22:11:56.163033Z","caller":"traceutil/trace.go:171","msg":"trace[1498245781] transaction","detail":"{read_only:false; response_revision:23511; number_of_response:1; }","duration":"820.102393ms","start":"2026-03-02T22:11:55.34292Z","end":"2026-03-02T22:11:56.163023Z","steps":["trace[1498245781] 'process raft request' (duration: 819.334679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.163079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.839805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-02T22:11:56.163094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.342915Z","time spent":"820.147324ms","remote":"127.0.0.1:40014","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-03-02T22:11:56.16312Z","caller":"traceutil/trace.go:171","msg":"trace[457132184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23512; }","duration":"705.912397ms","start":"2026-03-02T22:11:55.457195Z","end":"2026-03-02T22:11:56.163107Z","steps":["trace[457132184] 'agreement among raft nodes before linearized reading' (duration: 705.56355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.163161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.457187Z","time spent":"705.966497ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T22:11:56.510366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.947865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:56.510576Z","caller":"traceutil/trace.go:171","msg":"trace[1547159862] transaction","detail":"{read_only:false; response_revision:23515; number_of_response:1; }","duration":"532.217791ms","start":"2026-03-02T22:11:55.978343Z","end":"2026-03-02T22:11:56.510561Z","steps":["trace[1547159862] 'process raft request' (duration: 532.15641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.510631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.978317Z","time spent":"532.286393ms","remote":"127.0.0.1:40014","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-03-02T22:11:56.510732Z","caller":"traceutil/trace.go:171","msg":"trace[571774092] transaction","detail":"{read_only:false; response_revision:23514; number_of_response:1; }","duration":"639.967569ms","start":"2026-03-02T22:11:55.870752Z","end":"2026-03-02T22:11:56.51072Z","steps":["trace[571774092] 'process raft request' (duration: 639.696704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.510776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.870725Z","time spent":"640.02605ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:56.510846Z","caller":"traceutil/trace.go:171","msg":"trace[1011820717] transaction","detail":"{read_only:false; response_revision:23513; number_of_response:1; }","duration":"827.047392ms","start":"2026-03-02T22:11:55.683791Z","end":"2026-03-02T22:11:56.510838Z","steps":["trace[1011820717] 'process raft request' (duration: 583.548217ms)","trace[1011820717] 'compare' (duration: 242.6876ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:56.510877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.68376Z","time spent":"827.099073ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:56.510884Z","caller":"traceutil/trace.go:171","msg":"trace[106063648] linearizableReadLoop","detail":"{readStateIndex:24332; appliedIndex:24329; }","duration":"348.349027ms","start":"2026-03-02T22:11:56.162504Z","end":"2026-03-02T22:11:56.510853Z","steps":["trace[106063648] 'read index received' (duration: 104.813602ms)","trace[106063648] 'applied index is now lower than readState.Index' (duration: 243.533535ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:56.511073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049195188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-02T22:11:56.511102Z","caller":"traceutil/trace.go:171","msg":"trace[246080314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23516; }","duration":"1.049260861s","start":"2026-03-02T22:11:55.461828Z","end":"2026-03-02T22:11:56.511088Z","steps":["trace[246080314] 'agreement among raft nodes before linearized reading' (duration: 1.049127198s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.511129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.46181Z","time spent":"1.049311931s","remote":"127.0.0.1:40014","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":"warn","ts":"2026-03-02T22:11:56.511262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.86002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:56.511329Z","caller":"traceutil/trace.go:171","msg":"trace[1877759777] transaction","detail":"{read_only:false; response_revision:23516; number_of_response:1; }","duration":"344.20431ms","start":"2026-03-02T22:11:56.167113Z","end":"2026-03-02T22:11:56.511317Z","steps":["trace[1877759777] 'process raft request' (duration: 343.420176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.511403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:56.167097Z","time spent":"344.274402ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:56.511338Z","caller":"traceutil/trace.go:171","msg":"trace[286761717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23516; }","duration":"599.970753ms","start":"2026-03-02T22:11:55.911349Z","end":"2026-03-02T22:11:56.51132Z","steps":["trace[286761717] 'agreement among raft nodes before linearized reading' (duration: 599.8189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.511612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.911334Z","time spent":"600.221477ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T22:11:56.511635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.743059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:11:56.511649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.130458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-02T22:11:56.513562Z","caller":"traceutil/trace.go:171","msg":"trace[1784101546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23516; }","duration":"898.050525ms","start":"2026-03-02T22:11:55.615454Z","end":"2026-03-02T22:11:56.513505Z","steps":["trace[1784101546] 'agreement among raft nodes before linearized reading' (duration: 895.787693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.51365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.61544Z","time spent":"898.176047ms","remote":"127.0.0.1:40014","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":"info","ts":"2026-03-02T22:11:56.514096Z","caller":"traceutil/trace.go:171","msg":"trace[2091472476] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:23516; }","duration":"925.954854ms","start":"2026-03-02T22:11:55.585777Z","end":"2026-03-02T22:11:56.511732Z","steps":["trace[2091472476] 'agreement among raft nodes before linearized reading' (duration: 925.677428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.51415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.585759Z","time spent":"928.373288ms","remote":"127.0.0.1:35560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-03-02T22:11:56.514727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.468904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-02T22:11:56.51478Z","caller":"traceutil/trace.go:171","msg":"trace[1597163503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23516; }","duration":"672.577045ms","start":"2026-03-02T22:11:55.842188Z","end":"2026-03-02T22:11:56.514765Z","steps":["trace[1597163503] 'agreement among raft nodes before linearized reading' (duration: 669.382866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.514835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.842167Z","time spent":"672.656077ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T22:11:56.516716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.811284ms","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-03-02T22:11:56.517565Z","caller":"traceutil/trace.go:171","msg":"trace[1574189824] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:23516; }","duration":"430.618369ms","start":"2026-03-02T22:11:56.08687Z","end":"2026-03-02T22:11:56.517489Z","steps":["trace[1574189824] 'agreement among raft nodes before linearized reading' (duration: 429.464318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.517638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:56.086853Z","time spent":"430.756031ms","remote":"127.0.0.1:39896","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":"warn","ts":"2026-03-02T22:11:56.518628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.02954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:11:56.518719Z","caller":"traceutil/trace.go:171","msg":"trace[1077408932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23516; }","duration":"770.178084ms","start":"2026-03-02T22:11:55.74851Z","end":"2026-03-02T22:11:56.518688Z","steps":["trace[1077408932] 'agreement among raft nodes before linearized reading' (duration: 769.697515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.518789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.748456Z","time spent":"770.301556ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T22:12:10.218992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.494387ms","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-03-02T22:12:10.219084Z","caller":"traceutil/trace.go:171","msg":"trace[356222367] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:23616; }","duration":"277.63551ms","start":"2026-03-02T22:12:09.941428Z","end":"2026-03-02T22:12:10.219063Z","steps":["trace[356222367] 'count revisions from in-memory index tree' (duration: 277.390316ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:10.429905Z","caller":"traceutil/trace.go:171","msg":"trace[19913030] transaction","detail":"{read_only:false; response_revision:23617; number_of_response:1; }","duration":"144.45714ms","start":"2026-03-02T22:12:10.28542Z","end":"2026-03-02T22:12:10.429877Z","steps":["trace[19913030] 'process raft request' (duration: 144.279887ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:10.434294Z","caller":"traceutil/trace.go:171","msg":"trace[108189431] transaction","detail":"{read_only:false; response_revision:23618; number_of_response:1; }","duration":"146.828225ms","start":"2026-03-02T22:12:10.287436Z","end":"2026-03-02T22:12:10.434265Z","steps":["trace[108189431] 'process raft request' (duration: 146.333036ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:10.54445Z","caller":"traceutil/trace.go:171","msg":"trace[1892949040] transaction","detail":"{read_only:false; response_revision:23619; number_of_response:1; }","duration":"110.062581ms","start":"2026-03-02T22:12:10.434355Z","end":"2026-03-02T22:12:10.544418Z","steps":["trace[1892949040] 'process raft request' (duration: 108.084764ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.557802Z","caller":"traceutil/trace.go:171","msg":"trace[1293148523] transaction","detail":"{read_only:false; response_revision:23673; number_of_response:1; }","duration":"104.409295ms","start":"2026-03-02T22:12:17.453369Z","end":"2026-03-02T22:12:17.557779Z","steps":["trace[1293148523] 'process raft request' (duration: 104.282463ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:26.94939Z","caller":"traceutil/trace.go:171","msg":"trace[307335596] transaction","detail":"{read_only:false; response_revision:23744; number_of_response:1; }","duration":"146.800065ms","start":"2026-03-02T22:12:26.802564Z","end":"2026-03-02T22:12:26.949364Z","steps":["trace[307335596] 'process raft request' (duration: 146.634662ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:40.994265Z","caller":"traceutil/trace.go:171","msg":"trace[1862658098] linearizableReadLoop","detail":"{readStateIndex:24661; appliedIndex:24660; }","duration":"191.807992ms","start":"2026-03-02T22:12:40.802424Z","end":"2026-03-02T22:12:40.994232Z","steps":["trace[1862658098] 'read index received' (duration: 191.617408ms)","trace[1862658098] 'applied index is now lower than readState.Index' (duration: 189.444µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:40.994316Z","caller":"traceutil/trace.go:171","msg":"trace[1785508103] transaction","detail":"{read_only:false; response_revision:23835; number_of_response:1; }","duration":"226.943437ms","start":"2026-03-02T22:12:40.767337Z","end":"2026-03-02T22:12:40.99428Z","steps":["trace[1785508103] 'process raft request' (duration: 226.711522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:40.994541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.601281ms","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-03-02T22:12:40.994578Z","caller":"traceutil/trace.go:171","msg":"trace[1890115095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23835; }","duration":"131.703473ms","start":"2026-03-02T22:12:40.862863Z","end":"2026-03-02T22:12:40.994566Z","steps":["trace[1890115095] 'agreement among raft nodes before linearized reading' (duration: 131.54685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:40.994638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.091467ms","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":"warn","ts":"2026-03-02T22:12:40.994716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.955643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-02T22:12:40.994829Z","caller":"traceutil/trace.go:171","msg":"trace[169097611] 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:23835; }","duration":"192.392833ms","start":"2026-03-02T22:12:40.802375Z","end":"2026-03-02T22:12:40.994768Z","steps":["trace[169097611] 'agreement among raft nodes before linearized reading' (duration: 191.998315ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:40.994909Z","caller":"traceutil/trace.go:171","msg":"trace[2003305529] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23835; }","duration":"113.191997ms","start":"2026-03-02T22:12:40.881661Z","end":"2026-03-02T22:12:40.994853Z","steps":["trace[2003305529] 'agreement among raft nodes before linearized reading' (duration: 112.82137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:41.193684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.7446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:12:41.193993Z","caller":"traceutil/trace.go:171","msg":"trace[1522614559] linearizableReadLoop","detail":"{readStateIndex:24667; appliedIndex:24661; }","duration":"141.408273ms","start":"2026-03-02T22:12:41.052567Z","end":"2026-03-02T22:12:41.193975Z","steps":["trace[1522614559] 'read index received' (duration: 14.234455ms)","trace[1522614559] 'applied index is now lower than readState.Index' (duration: 127.172598ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:41.194263Z","caller":"traceutil/trace.go:171","msg":"trace[1388187665] transaction","detail":"{read_only:false; response_revision:23836; number_of_response:1; }","duration":"196.055872ms","start":"2026-03-02T22:12:40.998194Z","end":"2026-03-02T22:12:41.19425Z","steps":["trace[1388187665] 'process raft request' (duration: 68.636498ms)","trace[1388187665] 'compare' (duration: 126.397414ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:41.194432Z","caller":"traceutil/trace.go:171","msg":"trace[1184081642] transaction","detail":"{read_only:false; response_revision:23837; number_of_response:1; }","duration":"188.727016ms","start":"2026-03-02T22:12:41.005697Z","end":"2026-03-02T22:12:41.194424Z","steps":["trace[1184081642] 'process raft request' (duration: 188.106343ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:41.194523Z","caller":"traceutil/trace.go:171","msg":"trace[1976295758] transaction","detail":"{read_only:false; response_revision:23838; number_of_response:1; }","duration":"172.081085ms","start":"2026-03-02T22:12:41.022435Z","end":"2026-03-02T22:12:41.194516Z","steps":["trace[1976295758] 'process raft request' (duration: 171.421372ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:41.194605Z","caller":"traceutil/trace.go:171","msg":"trace[846009901] transaction","detail":"{read_only:false; response_revision:23839; number_of_response:1; }","duration":"168.60667ms","start":"2026-03-02T22:12:41.025992Z","end":"2026-03-02T22:12:41.194599Z","steps":["trace[846009901] 'process raft request' (duration: 167.895506ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:41.194686Z","caller":"traceutil/trace.go:171","msg":"trace[1601201551] transaction","detail":"{read_only:false; response_revision:23840; number_of_response:1; }","duration":"166.374359ms","start":"2026-03-02T22:12:41.028306Z","end":"2026-03-02T22:12:41.19468Z","steps":["trace[1601201551] 'process raft request' (duration: 165.608074ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:41.194769Z","caller":"traceutil/trace.go:171","msg":"trace[1543658963] transaction","detail":"{read_only:false; response_revision:23841; number_of_response:1; }","duration":"144.853738ms","start":"2026-03-02T22:12:41.049909Z","end":"2026-03-02T22:12:41.194763Z","steps":["trace[1543658963] 'process raft request' (duration: 144.030572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:41.19494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.379692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-02T22:12:41.194968Z","caller":"traceutil/trace.go:171","msg":"trace[1400975471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23841; }","duration":"142.453063ms","start":"2026-03-02T22:12:41.052507Z","end":"2026-03-02T22:12:41.19496Z","steps":["trace[1400975471] 'agreement among raft nodes before linearized reading' (duration: 142.33831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:41.481563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.267878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:12:41.481934Z","caller":"traceutil/trace.go:171","msg":"trace[715181851] transaction","detail":"{read_only:false; response_revision:23843; number_of_response:1; }","duration":"280.395342ms","start":"2026-03-02T22:12:41.20152Z","end":"2026-03-02T22:12:41.481916Z","steps":["trace[715181851] 'process raft request' (duration: 280.253109ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:41.482144Z","caller":"traceutil/trace.go:171","msg":"trace[755950870] linearizableReadLoop","detail":"{readStateIndex:24670; appliedIndex:24667; }","duration":"247.60126ms","start":"2026-03-02T22:12:41.234501Z","end":"2026-03-02T22:12:41.482102Z","steps":["trace[755950870] 'read index received' (duration: 117.800354ms)","trace[755950870] 'applied index is now lower than readState.Index' (duration: 129.798136ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:41.482301Z","caller":"traceutil/trace.go:171","msg":"trace[789406293] transaction","detail":"{read_only:false; response_revision:23844; number_of_response:1; }","duration":"278.68213ms","start":"2026-03-02T22:12:41.203604Z","end":"2026-03-02T22:12:41.482286Z","steps":["trace[789406293] 'process raft request' (duration: 278.276202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:41.482498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.996788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-02T22:12:41.482588Z","caller":"traceutil/trace.go:171","msg":"trace[914806558] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:23844; }","duration":"248.1227ms","start":"2026-03-02T22:12:41.234451Z","end":"2026-03-02T22:12:41.482573Z","steps":["trace[914806558] 'agreement among raft nodes before linearized reading' (duration: 248.002788ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:41.482734Z","caller":"traceutil/trace.go:171","msg":"trace[924341611] transaction","detail":"{read_only:false; response_revision:23842; number_of_response:1; }","duration":"281.211867ms","start":"2026-03-02T22:12:41.201486Z","end":"2026-03-02T22:12:41.482698Z","steps":["trace[924341611] 'process raft request' (duration: 150.709147ms)","trace[924341611] 'compare' (duration: 128.386241ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:41.698392Z","caller":"traceutil/trace.go:171","msg":"trace[538114719] transaction","detail":"{read_only:false; response_revision:23845; number_of_response:1; }","duration":"133.414755ms","start":"2026-03-02T22:12:41.564958Z","end":"2026-03-02T22:12:41.698373Z","steps":["trace[538114719] 'process raft request' (duration: 120.470533ms)","trace[538114719] 'compare' (duration: 12.687496ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:43.396359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.85907ms","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-03-02T22:12:43.397118Z","caller":"traceutil/trace.go:171","msg":"trace[856139928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23849; }","duration":"195.694984ms","start":"2026-03-02T22:12:43.201395Z","end":"2026-03-02T22:12:43.39709Z","steps":["trace[856139928] 'range keys from in-memory index tree' (duration: 194.593655ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.396432Z","caller":"traceutil/trace.go:171","msg":"trace[1712036327] transaction","detail":"{read_only:false; response_revision:23850; number_of_response:1; }","duration":"192.20461ms","start":"2026-03-02T22:12:43.204202Z","end":"2026-03-02T22:12:43.396406Z","steps":["trace[1712036327] 'process raft request' (duration: 112.929403ms)","trace[1712036327] 'compare' (duration: 78.960561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:43.396554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.179875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-02T22:12:43.39734Z","caller":"traceutil/trace.go:171","msg":"trace[784197306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23849; }","duration":"195.99317ms","start":"2026-03-02T22:12:43.201285Z","end":"2026-03-02T22:12:43.397278Z","steps":["trace[784197306] 'range keys from in-memory index tree' (duration: 194.954941ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.653719Z","caller":"traceutil/trace.go:171","msg":"trace[702456749] transaction","detail":"{read_only:false; response_revision:23855; number_of_response:1; }","duration":"251.446083ms","start":"2026-03-02T22:12:43.402253Z","end":"2026-03-02T22:12:43.653699Z","steps":["trace[702456749] 'process raft request' (duration: 251.408532ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.654083Z","caller":"traceutil/trace.go:171","msg":"trace[1854877700] transaction","detail":"{read_only:false; response_revision:23851; number_of_response:1; }","duration":"448.507602ms","start":"2026-03-02T22:12:43.205562Z","end":"2026-03-02T22:12:43.65407Z","steps":["trace[1854877700] 'process raft request' (duration: 413.761505ms)","trace[1854877700] 'compare' (duration: 34.101785ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:43.654184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.205522Z","time spent":"448.596934ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:43.654181Z","caller":"traceutil/trace.go:171","msg":"trace[1866927803] linearizableReadLoop","detail":"{readStateIndex:24679; appliedIndex:24675; }","duration":"410.21028ms","start":"2026-03-02T22:12:43.243945Z","end":"2026-03-02T22:12:43.654155Z","steps":["trace[1866927803] 'read index received' (duration: 73.200873ms)","trace[1866927803] 'applied index is now lower than readState.Index' (duration: 337.006927ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:43.654353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.1186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:12:43.654346Z","caller":"traceutil/trace.go:171","msg":"trace[1321798665] transaction","detail":"{read_only:false; response_revision:23852; number_of_response:1; }","duration":"448.131626ms","start":"2026-03-02T22:12:43.206173Z","end":"2026-03-02T22:12:43.654304Z","steps":["trace[1321798665] 'process raft request' (duration: 447.373911ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.654318Z","caller":"traceutil/trace.go:171","msg":"trace[181586981] transaction","detail":"{read_only:false; response_revision:23853; number_of_response:1; }","duration":"445.772832ms","start":"2026-03-02T22:12:43.208483Z","end":"2026-03-02T22:12:43.654256Z","steps":["trace[181586981] 'process raft request' (duration: 445.106739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:43.654464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.206158Z","time spent":"448.247768ms","remote":"127.0.0.1:40014","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-03-02T22:12:43.654491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.567942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-03-02T22:12:43.654487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.066734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-02T22:12:43.654519Z","caller":"traceutil/trace.go:171","msg":"trace[1072695762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23855; }","duration":"166.590982ms","start":"2026-03-02T22:12:43.487919Z","end":"2026-03-02T22:12:43.65451Z","steps":["trace[1072695762] 'agreement among raft nodes before linearized reading' (duration: 166.514041ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.65452Z","caller":"traceutil/trace.go:171","msg":"trace[359635026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23855; }","duration":"165.122985ms","start":"2026-03-02T22:12:43.489389Z","end":"2026-03-02T22:12:43.654512Z","steps":["trace[359635026] 'agreement among raft nodes before linearized reading' (duration: 165.020464ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.654562Z","caller":"traceutil/trace.go:171","msg":"trace[1042826765] transaction","detail":"{read_only:false; response_revision:23854; number_of_response:1; }","duration":"252.793598ms","start":"2026-03-02T22:12:43.401721Z","end":"2026-03-02T22:12:43.654515Z","steps":["trace[1042826765] 'process raft request' (duration: 251.91097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:43.654616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.208452Z","time spent":"446.001325ms","remote":"127.0.0.1:40014","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-03-02T22:12:43.6547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.218936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-02T22:12:43.654388Z","caller":"traceutil/trace.go:171","msg":"trace[1115154755] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:23855; }","duration":"408.166452ms","start":"2026-03-02T22:12:43.246211Z","end":"2026-03-02T22:12:43.654377Z","steps":["trace[1115154755] 'agreement among raft nodes before linearized reading' (duration: 408.112341ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.654784Z","caller":"traceutil/trace.go:171","msg":"trace[1227290980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23855; }","duration":"165.315119ms","start":"2026-03-02T22:12:43.489442Z","end":"2026-03-02T22:12:43.654757Z","steps":["trace[1227290980] 'agreement among raft nodes before linearized reading' (duration: 164.968163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:43.65482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.246201Z","time spent":"408.60208ms","remote":"127.0.0.1:34992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true "} {"level":"warn","ts":"2026-03-02T22:12:43.654396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.443384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-03-02T22:12:43.654888Z","caller":"traceutil/trace.go:171","msg":"trace[1968989651] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23855; }","duration":"410.983194ms","start":"2026-03-02T22:12:43.243898Z","end":"2026-03-02T22:12:43.654881Z","steps":["trace[1968989651] 'agreement among raft nodes before linearized reading' (duration: 410.363043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:43.654915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.243882Z","time spent":"411.022105ms","remote":"127.0.0.1:40202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11859,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-03-02T22:12:43.841955Z","caller":"traceutil/trace.go:171","msg":"trace[554025077] transaction","detail":"{read_only:false; response_revision:23857; number_of_response:1; }","duration":"181.909848ms","start":"2026-03-02T22:12:43.660009Z","end":"2026-03-02T22:12:43.841919Z","steps":["trace[554025077] 'process raft request' (duration: 181.841646ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:43.841982Z","caller":"traceutil/trace.go:171","msg":"trace[261919430] transaction","detail":"{read_only:false; response_revision:23856; number_of_response:1; }","duration":"181.922268ms","start":"2026-03-02T22:12:43.660026Z","end":"2026-03-02T22:12:43.841948Z","steps":["trace[261919430] 'process raft request' (duration: 181.566342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:44.089907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.430799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:12:44.089982Z","caller":"traceutil/trace.go:171","msg":"trace[1067617894] linearizableReadLoop","detail":"{readStateIndex:24684; appliedIndex:24681; }","duration":"429.57679ms","start":"2026-03-02T22:12:43.660393Z","end":"2026-03-02T22:12:44.08997Z","steps":["trace[1067617894] 'read index received' (duration: 181.171024ms)","trace[1067617894] 'applied index is now lower than readState.Index' (duration: 248.405006ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:44.090142Z","caller":"traceutil/trace.go:171","msg":"trace[1568355285] transaction","detail":"{read_only:false; response_revision:23858; number_of_response:1; }","duration":"430.087739ms","start":"2026-03-02T22:12:43.660047Z","end":"2026-03-02T22:12:44.090135Z","steps":["trace[1568355285] 'process raft request' (duration: 284.363575ms)","trace[1568355285] 'compare' (duration: 145.324677ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:44.09018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.660023Z","time spent":"430.140681ms","remote":"127.0.0.1:40014","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-03-02T22:12:44.090369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.828337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-03-02T22:12:44.090524Z","caller":"traceutil/trace.go:171","msg":"trace[834017147] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23858; }","duration":"428.011031ms","start":"2026-03-02T22:12:43.662497Z","end":"2026-03-02T22:12:44.090508Z","steps":["trace[834017147] 'agreement among raft nodes before linearized reading' (duration: 427.705345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:44.090573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.662483Z","time spent":"428.080482ms","remote":"127.0.0.1:40202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11859,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-03-02T22:12:44.090448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.061219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-02T22:12:44.090642Z","caller":"traceutil/trace.go:171","msg":"trace[1725783919] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23858; }","duration":"430.246052ms","start":"2026-03-02T22:12:43.660378Z","end":"2026-03-02T22:12:44.090624Z","steps":["trace[1725783919] 'agreement among raft nodes before linearized reading' (duration: 429.984457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:44.090753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:43.66034Z","time spent":"430.370676ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-02T22:12:44.905857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.276923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:12:44.905937Z","caller":"traceutil/trace.go:171","msg":"trace[575947239] linearizableReadLoop","detail":"{readStateIndex:24686; appliedIndex:24685; }","duration":"239.070763ms","start":"2026-03-02T22:12:44.666855Z","end":"2026-03-02T22:12:44.905926Z","steps":["trace[575947239] 'read index received' (duration: 98.824211ms)","trace[575947239] 'applied index is now lower than readState.Index' (duration: 140.245602ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:44.906065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.214076ms","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-03-02T22:12:44.905996Z","caller":"traceutil/trace.go:171","msg":"trace[1203687743] transaction","detail":"{read_only:false; response_revision:23860; number_of_response:1; }","duration":"291.01334ms","start":"2026-03-02T22:12:44.614931Z","end":"2026-03-02T22:12:44.905944Z","steps":["trace[1203687743] 'process raft request' (duration: 150.578804ms)","trace[1203687743] 'compare' (duration: 139.313344ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:44.906092Z","caller":"traceutil/trace.go:171","msg":"trace[310169299] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:23860; }","duration":"239.286097ms","start":"2026-03-02T22:12:44.666798Z","end":"2026-03-02T22:12:44.906084Z","steps":["trace[310169299] 'agreement among raft nodes before linearized reading' (duration: 239.156895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:45.07663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.29082ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T22:12:45.07713Z","caller":"traceutil/trace.go:171","msg":"trace[1891951593] linearizableReadLoop","detail":"{readStateIndex:24688; appliedIndex:24686; }","duration":"165.932951ms","start":"2026-03-02T22:12:44.911155Z","end":"2026-03-02T22:12:45.077088Z","steps":["trace[1891951593] 'read index received' (duration: 9.201292ms)","trace[1891951593] 'applied index is now lower than readState.Index' (duration: 156.728879ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:45.077164Z","caller":"traceutil/trace.go:171","msg":"trace[285584706] transaction","detail":"{read_only:false; response_revision:23861; number_of_response:1; }","duration":"192.821001ms","start":"2026-03-02T22:12:44.88432Z","end":"2026-03-02T22:12:45.077141Z","steps":["trace[285584706] 'process raft request' (duration: 192.404352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:45.347506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.302146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:12:45.347654Z","caller":"traceutil/trace.go:171","msg":"trace[1568575880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23861; }","duration":"436.48789ms","start":"2026-03-02T22:12:44.911129Z","end":"2026-03-02T22:12:45.347617Z","steps":["trace[1568575880] 'agreement among raft nodes before linearized reading' (duration: 166.113305ms)","trace[1568575880] 'range keys from in-memory index tree' (duration: 270.173151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:45.34772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:44.911118Z","time spent":"436.586431ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T22:12:45.347772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.564656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:12:45.347872Z","caller":"traceutil/trace.go:171","msg":"trace[1772951448] linearizableReadLoop","detail":"{readStateIndex:24689; appliedIndex:24688; }","duration":"270.65469ms","start":"2026-03-02T22:12:45.077203Z","end":"2026-03-02T22:12:45.347858Z","steps":["trace[1772951448] 'read index received' (duration: 106.8539ms)","trace[1772951448] 'applied index is now lower than readState.Index' (duration: 163.80012ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:45.347881Z","caller":"traceutil/trace.go:171","msg":"trace[222862742] transaction","detail":"{read_only:false; response_revision:23862; number_of_response:1; }","duration":"306.896635ms","start":"2026-03-02T22:12:45.040968Z","end":"2026-03-02T22:12:45.347864Z","steps":["trace[222862742] 'process raft request' (duration: 143.151266ms)","trace[222862742] 'compare' (duration: 163.456384ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:45.347958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:45.040944Z","time spent":"306.975496ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:12:45.348028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.708093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-02T22:12:45.348055Z","caller":"traceutil/trace.go:171","msg":"trace[1418229303] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:23862; }","duration":"182.782033ms","start":"2026-03-02T22:12:45.165263Z","end":"2026-03-02T22:12:45.348045Z","steps":["trace[1418229303] 'agreement among raft nodes before linearized reading' (duration: 182.725102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:45.34797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.121692ms","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-03-02T22:12:45.348162Z","caller":"traceutil/trace.go:171","msg":"trace[760328941] 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:23862; }","duration":"400.345556ms","start":"2026-03-02T22:12:44.947804Z","end":"2026-03-02T22:12:45.348149Z","steps":["trace[760328941] 'agreement among raft nodes before linearized reading' (duration: 400.131572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:45.348198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:44.947791Z","time spent":"400.394597ms","remote":"127.0.0.1:35368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-02T22:12:45.348231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.891833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-02T22:12:45.348252Z","caller":"traceutil/trace.go:171","msg":"trace[1173176433] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:23862; }","duration":"148.949984ms","start":"2026-03-02T22:12:45.199296Z","end":"2026-03-02T22:12:45.348246Z","steps":["trace[1173176433] 'agreement among raft nodes before linearized reading' (duration: 148.896353ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:45.594317Z","caller":"traceutil/trace.go:171","msg":"trace[350593410] transaction","detail":"{read_only:false; response_revision:23863; number_of_response:1; }","duration":"190.62471ms","start":"2026-03-02T22:12:45.403675Z","end":"2026-03-02T22:12:45.594299Z","steps":["trace[350593410] 'process raft request' (duration: 190.490258ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:53.154141Z","caller":"traceutil/trace.go:171","msg":"trace[84810877] transaction","detail":"{read_only:false; response_revision:23916; number_of_response:1; }","duration":"207.050966ms","start":"2026-03-02T22:12:52.947069Z","end":"2026-03-02T22:12:53.15412Z","steps":["trace[84810877] 'process raft request' (duration: 206.907464ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:54.005728Z","caller":"traceutil/trace.go:171","msg":"trace[779827548] transaction","detail":"{read_only:false; response_revision:23922; number_of_response:1; }","duration":"273.564704ms","start":"2026-03-02T22:12:53.732127Z","end":"2026-03-02T22:12:54.005691Z","steps":["trace[779827548] 'process raft request' (duration: 273.33981ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:54.01729Z","caller":"traceutil/trace.go:171","msg":"trace[368177550] linearizableReadLoop","detail":"{readStateIndex:24751; appliedIndex:24750; }","duration":"130.624213ms","start":"2026-03-02T22:12:53.886636Z","end":"2026-03-02T22:12:54.01726Z","steps":["trace[368177550] 'read index received' (duration: 119.045527ms)","trace[368177550] 'applied index is now lower than readState.Index' (duration: 11.577816ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:54.017463Z","caller":"traceutil/trace.go:171","msg":"trace[1514117802] transaction","detail":"{read_only:false; response_revision:23923; number_of_response:1; }","duration":"284.975528ms","start":"2026-03-02T22:12:53.732443Z","end":"2026-03-02T22:12:54.017418Z","steps":["trace[1514117802] 'process raft request' (duration: 284.640432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:54.017683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.02273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-02T22:12:54.017699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.852117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:12:54.017742Z","caller":"traceutil/trace.go:171","msg":"trace[280865419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23923; }","duration":"131.144422ms","start":"2026-03-02T22:12:53.886583Z","end":"2026-03-02T22:12:54.017727Z","steps":["trace[280865419] 'agreement among raft nodes before linearized reading' (duration: 130.830857ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:54.017812Z","caller":"traceutil/trace.go:171","msg":"trace[1097543254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23923; }","duration":"131.02403ms","start":"2026-03-02T22:12:53.886731Z","end":"2026-03-02T22:12:54.017755Z","steps":["trace[1097543254] 'agreement among raft nodes before linearized reading' (duration: 130.734495ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:59.572646Z","caller":"traceutil/trace.go:171","msg":"trace[1864665218] transaction","detail":"{read_only:false; response_revision:23956; number_of_response:1; }","duration":"146.10533ms","start":"2026-03-02T22:12:59.426492Z","end":"2026-03-02T22:12:59.572597Z","steps":["trace[1864665218] 'process raft request' (duration: 145.824385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.018905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.52499ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-02T22:13:00.019493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:59.706111Z","time spent":"313.373466ms","remote":"127.0.0.1:39780","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T22:13:00.019589Z","caller":"traceutil/trace.go:171","msg":"trace[769151111] transaction","detail":"{read_only:false; response_revision:23958; number_of_response:1; }","duration":"281.91294ms","start":"2026-03-02T22:12:59.737656Z","end":"2026-03-02T22:13:00.019569Z","steps":["trace[769151111] 'process raft request' (duration: 281.348429ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.093175Z","caller":"traceutil/trace.go:171","msg":"trace[1540922577] transaction","detail":"{read_only:false; response_revision:23959; number_of_response:1; }","duration":"330.441724ms","start":"2026-03-02T22:12:59.762694Z","end":"2026-03-02T22:13:00.093135Z","steps":["trace[1540922577] 'process raft request' (duration: 330.228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.093373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:59.762685Z","time spent":"330.583286ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:00.093496Z","caller":"traceutil/trace.go:171","msg":"trace[60364947] linearizableReadLoop","detail":"{readStateIndex:24791; appliedIndex:24788; }","duration":"181.093803ms","start":"2026-03-02T22:12:59.912375Z","end":"2026-03-02T22:13:00.093468Z","steps":["trace[60364947] 'read index received' (duration: 180.575893ms)","trace[60364947] 'applied index is now lower than readState.Index' (duration: 516.95µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:00.093503Z","caller":"traceutil/trace.go:171","msg":"trace[998649438] transaction","detail":"{read_only:false; response_revision:23960; number_of_response:1; }","duration":"330.479935ms","start":"2026-03-02T22:12:59.762984Z","end":"2026-03-02T22:13:00.093463Z","steps":["trace[998649438] 'process raft request' (duration: 330.109918ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.093615Z","caller":"traceutil/trace.go:171","msg":"trace[1791188406] transaction","detail":"{read_only:false; response_revision:23961; number_of_response:1; }","duration":"328.541148ms","start":"2026-03-02T22:12:59.765018Z","end":"2026-03-02T22:13:00.093559Z","steps":["trace[1791188406] 'process raft request' (duration: 328.343365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.093708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:59.762978Z","time spent":"330.629337ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:00.093897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:59.765009Z","time spent":"328.756052ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:00.09394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.341207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:00.093994Z","caller":"traceutil/trace.go:171","msg":"trace[698312317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23961; }","duration":"181.630342ms","start":"2026-03-02T22:12:59.912351Z","end":"2026-03-02T22:13:00.093982Z","steps":["trace[698312317] 'agreement among raft nodes before linearized reading' (duration: 181.209394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.340048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.851475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:00.340526Z","caller":"traceutil/trace.go:171","msg":"trace[1367737050] linearizableReadLoop","detail":"{readStateIndex:24792; appliedIndex:24791; }","duration":"246.914578ms","start":"2026-03-02T22:13:00.093569Z","end":"2026-03-02T22:13:00.340483Z","steps":["trace[1367737050] 'read index received' (duration: 114.440391ms)","trace[1367737050] 'applied index is now lower than readState.Index' (duration: 132.471187ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:00.340819Z","caller":"traceutil/trace.go:171","msg":"trace[921953206] transaction","detail":"{read_only:false; response_revision:23962; number_of_response:1; }","duration":"320.034961ms","start":"2026-03-02T22:13:00.020691Z","end":"2026-03-02T22:13:00.340726Z","steps":["trace[921953206] 'process raft request' (duration: 187.35617ms)","trace[921953206] 'compare' (duration: 131.644752ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:00.340948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:00.020669Z","time spent":"320.219363ms","remote":"127.0.0.1:39780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:00.340936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.454352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-02T22:13:00.341019Z","caller":"traceutil/trace.go:171","msg":"trace[1466620511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23962; }","duration":"295.607974ms","start":"2026-03-02T22:13:00.04539Z","end":"2026-03-02T22:13:00.340998Z","steps":["trace[1466620511] 'agreement among raft nodes before linearized reading' (duration: 295.316829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.341028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.360939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:13:00.341008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.883304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-03-02T22:13:00.341088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.511191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-02T22:13:00.341151Z","caller":"traceutil/trace.go:171","msg":"trace[1579807498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23962; }","duration":"245.536732ms","start":"2026-03-02T22:13:00.095567Z","end":"2026-03-02T22:13:00.341104Z","steps":["trace[1579807498] 'agreement among raft nodes before linearized reading' (duration: 245.212417ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.341154Z","caller":"traceutil/trace.go:171","msg":"trace[281067193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23962; }","duration":"286.054408ms","start":"2026-03-02T22:13:00.055065Z","end":"2026-03-02T22:13:00.341119Z","steps":["trace[281067193] 'agreement among raft nodes before linearized reading' (duration: 285.508108ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.341173Z","caller":"traceutil/trace.go:171","msg":"trace[1279109311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23962; }","duration":"159.663833ms","start":"2026-03-02T22:13:00.181491Z","end":"2026-03-02T22:13:00.341155Z","steps":["trace[1279109311] 'agreement among raft nodes before linearized reading' (duration: 159.327027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.341166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.4836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-03-02T22:13:00.341244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.229931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-02T22:13:00.341296Z","caller":"traceutil/trace.go:171","msg":"trace[10318537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23962; }","duration":"289.721436ms","start":"2026-03-02T22:13:00.051554Z","end":"2026-03-02T22:13:00.341275Z","steps":["trace[10318537] 'agreement among raft nodes before linearized reading' (duration: 289.177836ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.34137Z","caller":"traceutil/trace.go:171","msg":"trace[1133133739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23962; }","duration":"307.374994ms","start":"2026-03-02T22:13:00.033978Z","end":"2026-03-02T22:13:00.341353Z","steps":["trace[1133133739] 'agreement among raft nodes before linearized reading' (duration: 306.736862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.341484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:00.033971Z","time spent":"307.497737ms","remote":"127.0.0.1:40014","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":"info","ts":"2026-03-02T22:13:00.553071Z","caller":"traceutil/trace.go:171","msg":"trace[843207291] transaction","detail":"{read_only:false; response_revision:23963; number_of_response:1; }","duration":"206.7312ms","start":"2026-03-02T22:13:00.34629Z","end":"2026-03-02T22:13:00.553022Z","steps":["trace[843207291] 'process raft request' (duration: 137.860657ms)","trace[843207291] 'compare' (duration: 68.565948ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:00.573387Z","caller":"traceutil/trace.go:171","msg":"trace[1618156609] transaction","detail":"{read_only:false; response_revision:23964; number_of_response:1; }","duration":"226.957957ms","start":"2026-03-02T22:13:00.346409Z","end":"2026-03-02T22:13:00.573367Z","steps":["trace[1618156609] 'process raft request' (duration: 226.783884ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.573738Z","caller":"traceutil/trace.go:171","msg":"trace[1153262597] transaction","detail":"{read_only:false; response_revision:23965; number_of_response:1; }","duration":"227.200721ms","start":"2026-03-02T22:13:00.346509Z","end":"2026-03-02T22:13:00.57371Z","steps":["trace[1153262597] 'process raft request' (duration: 226.827724ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.573978Z","caller":"traceutil/trace.go:171","msg":"trace[1070659169] transaction","detail":"{read_only:false; response_revision:23966; number_of_response:1; }","duration":"227.328395ms","start":"2026-03-02T22:13:00.34663Z","end":"2026-03-02T22:13:00.573959Z","steps":["trace[1070659169] 'process raft request' (duration: 226.978997ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:00.574131Z","caller":"traceutil/trace.go:171","msg":"trace[663474745] linearizableReadLoop","detail":"{readStateIndex:24796; appliedIndex:24792; }","duration":"226.205293ms","start":"2026-03-02T22:13:00.347915Z","end":"2026-03-02T22:13:00.574121Z","steps":["trace[663474745] 'read index received' (duration: 136.301377ms)","trace[663474745] 'applied index is now lower than readState.Index' (duration: 89.902866ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:00.574193Z","caller":"traceutil/trace.go:171","msg":"trace[701959393] transaction","detail":"{read_only:false; response_revision:23967; number_of_response:1; }","duration":"225.086983ms","start":"2026-03-02T22:13:00.349101Z","end":"2026-03-02T22:13:00.574188Z","steps":["trace[701959393] 'process raft request' (duration: 224.803028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.574373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.444808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-02T22:13:00.574402Z","caller":"traceutil/trace.go:171","msg":"trace[384068080] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:23967; }","duration":"226.504609ms","start":"2026-03-02T22:13:00.347889Z","end":"2026-03-02T22:13:00.574393Z","steps":["trace[384068080] 'agreement among raft nodes before linearized reading' (duration: 226.365336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:00.574364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.02021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-03-02T22:13:00.57446Z","caller":"traceutil/trace.go:171","msg":"trace[2063383530] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:23967; }","duration":"167.153453ms","start":"2026-03-02T22:13:00.407276Z","end":"2026-03-02T22:13:00.57443Z","steps":["trace[2063383530] 'agreement among raft nodes before linearized reading' (duration: 166.96822ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:01.364528Z","caller":"traceutil/trace.go:171","msg":"trace[1180245200] transaction","detail":"{read_only:false; response_revision:23969; number_of_response:1; }","duration":"105.693179ms","start":"2026-03-02T22:13:01.258796Z","end":"2026-03-02T22:13:01.364489Z","steps":["trace[1180245200] 'process raft request' (duration: 105.442044ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.569393Z","caller":"traceutil/trace.go:171","msg":"trace[1823853869] transaction","detail":"{read_only:false; response_revision:24038; number_of_response:1; }","duration":"155.775772ms","start":"2026-03-02T22:13:11.413591Z","end":"2026-03-02T22:13:11.569367Z","steps":["trace[1823853869] 'process raft request' (duration: 155.601648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.80145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.421612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:11.801634Z","caller":"traceutil/trace.go:171","msg":"trace[435846194] transaction","detail":"{read_only:false; response_revision:24039; number_of_response:1; }","duration":"183.460366ms","start":"2026-03-02T22:13:11.618147Z","end":"2026-03-02T22:13:11.801607Z","steps":["trace[435846194] 'process raft request' (duration: 69.776959ms)","trace[435846194] 'compare' (duration: 113.289279ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:11.843735Z","caller":"traceutil/trace.go:171","msg":"trace[554968489] transaction","detail":"{read_only:false; response_revision:24040; number_of_response:1; }","duration":"116.345827ms","start":"2026-03-02T22:13:11.727352Z","end":"2026-03-02T22:13:11.843698Z","steps":["trace[554968489] 'process raft request' (duration: 116.144293ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:12.2389Z","caller":"traceutil/trace.go:171","msg":"trace[1855366925] transaction","detail":"{read_only:false; response_revision:24042; number_of_response:1; }","duration":"177.979195ms","start":"2026-03-02T22:13:12.06089Z","end":"2026-03-02T22:13:12.23887Z","steps":["trace[1855366925] 'process raft request' (duration: 177.801381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.538852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.096605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:12.539141Z","caller":"traceutil/trace.go:171","msg":"trace[1385650942] transaction","detail":"{read_only:false; response_revision:24043; number_of_response:1; }","duration":"385.205234ms","start":"2026-03-02T22:13:12.153907Z","end":"2026-03-02T22:13:12.539113Z","steps":["trace[1385650942] 'process raft request' (duration: 175.766184ms)","trace[1385650942] 'compare' (duration: 208.841709ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:12.539177Z","caller":"traceutil/trace.go:171","msg":"trace[1001651439] linearizableReadLoop","detail":"{readStateIndex:24877; appliedIndex:24874; }","duration":"237.984702ms","start":"2026-03-02T22:13:12.301173Z","end":"2026-03-02T22:13:12.539158Z","steps":["trace[1001651439] 'read index received' (duration: 28.45467ms)","trace[1001651439] 'applied index is now lower than readState.Index' (duration: 209.528982ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:12.539215Z","caller":"traceutil/trace.go:171","msg":"trace[539798549] transaction","detail":"{read_only:false; response_revision:24044; number_of_response:1; }","duration":"375.223908ms","start":"2026-03-02T22:13:12.163963Z","end":"2026-03-02T22:13:12.539187Z","steps":["trace[539798549] 'process raft request' (duration: 375.020974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.539353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.163947Z","time spent":"375.33525ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:12.539673Z","caller":"traceutil/trace.go:171","msg":"trace[1420768555] transaction","detail":"{read_only:false; response_revision:24045; number_of_response:1; }","duration":"374.077797ms","start":"2026-03-02T22:13:12.165574Z","end":"2026-03-02T22:13:12.539651Z","steps":["trace[1420768555] 'process raft request' (duration: 373.540327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.539717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.153877Z","time spent":"385.328947ms","remote":"127.0.0.1:40014","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-03-02T22:13:12.539758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.165561Z","time spent":"374.157518ms","remote":"127.0.0.1:40014","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-03-02T22:13:12.539894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.720095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-03-02T22:13:12.539959Z","caller":"traceutil/trace.go:171","msg":"trace[354962696] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24045; }","duration":"238.824208ms","start":"2026-03-02T22:13:12.301122Z","end":"2026-03-02T22:13:12.539946Z","steps":["trace[354962696] 'agreement among raft nodes before linearized reading' (duration: 238.089654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.897044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.275819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:12.897232Z","caller":"traceutil/trace.go:171","msg":"trace[1288892095] linearizableReadLoop","detail":"{readStateIndex:24878; appliedIndex:24877; }","duration":"267.263588ms","start":"2026-03-02T22:13:12.629937Z","end":"2026-03-02T22:13:12.8972Z","steps":["trace[1288892095] 'read index received' (duration: 160.5771ms)","trace[1288892095] 'applied index is now lower than readState.Index' (duration: 106.683758ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:12.897383Z","caller":"traceutil/trace.go:171","msg":"trace[833835384] transaction","detail":"{read_only:false; response_revision:24046; number_of_response:1; }","duration":"282.617064ms","start":"2026-03-02T22:13:12.614732Z","end":"2026-03-02T22:13:12.897349Z","steps":["trace[833835384] 'process raft request' (duration: 175.876155ms)","trace[833835384] 'compare' (duration: 105.648008ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:12.897609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.643765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/manila-ssh-keys\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:12.897684Z","caller":"traceutil/trace.go:171","msg":"trace[748043820] range","detail":"{range_begin:/registry/secrets/openstack/manila-ssh-keys; range_end:; response_count:0; response_revision:24046; }","duration":"267.782168ms","start":"2026-03-02T22:13:12.629886Z","end":"2026-03-02T22:13:12.897668Z","steps":["trace[748043820] 'agreement among raft nodes before linearized reading' (duration: 267.626415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.9121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.358276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-02T22:13:12.912167Z","caller":"traceutil/trace.go:171","msg":"trace[1190911770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24047; }","duration":"247.461338ms","start":"2026-03-02T22:13:12.664687Z","end":"2026-03-02T22:13:12.912148Z","steps":["trace[1190911770] 'agreement among raft nodes before linearized reading' (duration: 247.207703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.912353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.819266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-02T22:13:12.912396Z","caller":"traceutil/trace.go:171","msg":"trace[676286366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24047; }","duration":"247.919877ms","start":"2026-03-02T22:13:12.664459Z","end":"2026-03-02T22:13:12.912379Z","steps":["trace[676286366] 'agreement among raft nodes before linearized reading' (duration: 247.820295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.912435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.482617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-02T22:13:12.91251Z","caller":"traceutil/trace.go:171","msg":"trace[81109937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24047; }","duration":"270.604619ms","start":"2026-03-02T22:13:12.641887Z","end":"2026-03-02T22:13:12.912492Z","steps":["trace[81109937] 'agreement among raft nodes before linearized reading' (duration: 270.375505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.912504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.961062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:13:12.912579Z","caller":"traceutil/trace.go:171","msg":"trace[934994279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24047; }","duration":"282.125035ms","start":"2026-03-02T22:13:12.630434Z","end":"2026-03-02T22:13:12.912559Z","steps":["trace[934994279] 'agreement among raft nodes before linearized reading' (duration: 281.843939ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:13.287949Z","caller":"traceutil/trace.go:171","msg":"trace[1934787156] transaction","detail":"{read_only:false; response_revision:24048; number_of_response:1; }","duration":"373.916743ms","start":"2026-03-02T22:13:12.914007Z","end":"2026-03-02T22:13:13.287924Z","steps":["trace[1934787156] 'process raft request' (duration: 373.76444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:13.288091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.913991Z","time spent":"374.025825ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4407,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T22:13:13.337515Z","caller":"traceutil/trace.go:171","msg":"trace[596673219] transaction","detail":"{read_only:false; response_revision:24049; number_of_response:1; }","duration":"421.680034ms","start":"2026-03-02T22:13:12.915811Z","end":"2026-03-02T22:13:13.337491Z","steps":["trace[596673219] 'process raft request' (duration: 421.48771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:13.337664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.915794Z","time spent":"421.791785ms","remote":"127.0.0.1:40014","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-03-02T22:13:13.337712Z","caller":"traceutil/trace.go:171","msg":"trace[1758900916] linearizableReadLoop","detail":"{readStateIndex:24884; appliedIndex:24879; }","duration":"252.251197ms","start":"2026-03-02T22:13:13.085446Z","end":"2026-03-02T22:13:13.337697Z","steps":["trace[1758900916] 'read index received' (duration: 202.332748ms)","trace[1758900916] 'applied index is now lower than readState.Index' (duration: 49.917789ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:13.337791Z","caller":"traceutil/trace.go:171","msg":"trace[729335885] transaction","detail":"{read_only:false; response_revision:24052; number_of_response:1; }","duration":"420.301277ms","start":"2026-03-02T22:13:12.917481Z","end":"2026-03-02T22:13:13.337782Z","steps":["trace[729335885] 'process raft request' (duration: 420.187765ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:13.337785Z","caller":"traceutil/trace.go:171","msg":"trace[301718137] transaction","detail":"{read_only:false; response_revision:24050; number_of_response:1; }","duration":"421.608222ms","start":"2026-03-02T22:13:12.916166Z","end":"2026-03-02T22:13:13.337775Z","steps":["trace[301718137] 'process raft request' (duration: 421.275986ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:13.337791Z","caller":"traceutil/trace.go:171","msg":"trace[406385789] transaction","detail":"{read_only:false; response_revision:24051; number_of_response:1; }","duration":"421.291507ms","start":"2026-03-02T22:13:12.91647Z","end":"2026-03-02T22:13:13.337762Z","steps":["trace[406385789] 'process raft request' (duration: 421.146544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:13.337843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.916147Z","time spent":"421.667554ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:13.33784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.917458Z","time spent":"420.352248ms","remote":"127.0.0.1:40014","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-03-02T22:13:13.337945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.486792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:13.33799Z","caller":"traceutil/trace.go:171","msg":"trace[938183848] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24052; }","duration":"252.580694ms","start":"2026-03-02T22:13:13.085398Z","end":"2026-03-02T22:13:13.337979Z","steps":["trace[938183848] 'agreement among raft nodes before linearized reading' (duration: 252.471952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:13.337972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.916461Z","time spent":"421.425469ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:13.633193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.121756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-03-02T22:13:13.633275Z","caller":"traceutil/trace.go:171","msg":"trace[1125670] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:24052; }","duration":"280.237499ms","start":"2026-03-02T22:13:13.353018Z","end":"2026-03-02T22:13:13.633256Z","steps":["trace[1125670] 'range keys from in-memory index tree' (duration: 279.938633ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:14.098038Z","caller":"traceutil/trace.go:171","msg":"trace[42230935] linearizableReadLoop","detail":"{readStateIndex:24886; appliedIndex:24885; }","duration":"308.207509ms","start":"2026-03-02T22:13:13.789798Z","end":"2026-03-02T22:13:14.098005Z","steps":["trace[42230935] 'read index received' (duration: 308.032926ms)","trace[42230935] 'applied index is now lower than readState.Index' (duration: 173.223µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:14.098105Z","caller":"traceutil/trace.go:171","msg":"trace[2051018525] transaction","detail":"{read_only:false; response_revision:24054; number_of_response:1; }","duration":"362.488751ms","start":"2026-03-02T22:13:13.735578Z","end":"2026-03-02T22:13:14.098067Z","steps":["trace[2051018525] 'process raft request' (duration: 362.238696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:14.098287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:13.735545Z","time spent":"362.642853ms","remote":"127.0.0.1:40048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:14.098331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.517956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-03-02T22:13:14.098391Z","caller":"traceutil/trace.go:171","msg":"trace[839851550] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:24054; }","duration":"308.652348ms","start":"2026-03-02T22:13:13.789724Z","end":"2026-03-02T22:13:14.098377Z","steps":["trace[839851550] 'agreement among raft nodes before linearized reading' (duration: 308.396013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:14.098425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:13.789696Z","time spent":"308.722169ms","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":180,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-02T22:13:14.448143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.366525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:14.448416Z","caller":"traceutil/trace.go:171","msg":"trace[1203939590] transaction","detail":"{read_only:false; response_revision:24056; number_of_response:1; }","duration":"200.265889ms","start":"2026-03-02T22:13:14.248119Z","end":"2026-03-02T22:13:14.448385Z","steps":["trace[1203939590] 'process raft request' (duration: 200.163247ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:14.448597Z","caller":"traceutil/trace.go:171","msg":"trace[67250396] linearizableReadLoop","detail":"{readStateIndex:24887; appliedIndex:24886; }","duration":"350.438477ms","start":"2026-03-02T22:13:14.098118Z","end":"2026-03-02T22:13:14.448557Z","steps":["trace[67250396] 'read index received' (duration: 173.879119ms)","trace[67250396] 'applied index is now lower than readState.Index' (duration: 176.556478ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:14.44871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.560294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:13:14.448805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.539864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:14.448846Z","caller":"traceutil/trace.go:171","msg":"trace[693018635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24056; }","duration":"535.694537ms","start":"2026-03-02T22:13:13.913139Z","end":"2026-03-02T22:13:14.448834Z","steps":["trace[693018635] 'agreement among raft nodes before linearized reading' (duration: 535.549334ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:14.448852Z","caller":"traceutil/trace.go:171","msg":"trace[1346225746] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24056; }","duration":"361.765937ms","start":"2026-03-02T22:13:14.08706Z","end":"2026-03-02T22:13:14.448826Z","steps":["trace[1346225746] 'agreement among raft nodes before linearized reading' (duration: 361.520252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:14.448891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:13.913106Z","time spent":"535.776058ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T22:13:14.448919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:14.087036Z","time spent":"361.865789ms","remote":"127.0.0.1:35080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-03-02T22:13:14.449366Z","caller":"traceutil/trace.go:171","msg":"trace[673504138] transaction","detail":"{read_only:false; response_revision:24055; number_of_response:1; }","duration":"641.841154ms","start":"2026-03-02T22:13:13.806535Z","end":"2026-03-02T22:13:14.448376Z","steps":["trace[673504138] 'process raft request' (duration: 465.097142ms)","trace[673504138] 'compare' (duration: 176.196332ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:14.449612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:13.806516Z","time spent":"642.967215ms","remote":"127.0.0.1:40014","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-03-02T22:13:14.450694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.541651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:182 size:1906776"} {"level":"info","ts":"2026-03-02T22:13:14.450792Z","caller":"traceutil/trace.go:171","msg":"trace[2006038221] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:182; response_revision:24056; }","duration":"449.707854ms","start":"2026-03-02T22:13:14.001062Z","end":"2026-03-02T22:13:14.45077Z","steps":["trace[2006038221] 'agreement among raft nodes before linearized reading' (duration: 447.529734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:14.450853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:14.001031Z","time spent":"449.807277ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":182,"response size":1906798,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-03-02T22:13:15.597085Z","caller":"traceutil/trace.go:171","msg":"trace[2031784103] transaction","detail":"{read_only:false; response_revision:24063; number_of_response:1; }","duration":"251.096806ms","start":"2026-03-02T22:13:15.345935Z","end":"2026-03-02T22:13:15.597031Z","steps":["trace[2031784103] 'process raft request' (duration: 167.286235ms)","trace[2031784103] 'compare' (duration: 83.171609ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:15.597141Z","caller":"traceutil/trace.go:171","msg":"trace[597312189] transaction","detail":"{read_only:false; response_revision:24064; number_of_response:1; }","duration":"251.042225ms","start":"2026-03-02T22:13:15.346072Z","end":"2026-03-02T22:13:15.597115Z","steps":["trace[597312189] 'process raft request' (duration: 250.828881ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:15.597151Z","caller":"traceutil/trace.go:171","msg":"trace[2018116220] transaction","detail":"{read_only:false; response_revision:24065; number_of_response:1; }","duration":"250.134409ms","start":"2026-03-02T22:13:15.346972Z","end":"2026-03-02T22:13:15.597106Z","steps":["trace[2018116220] 'process raft request' (duration: 250.062527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.938856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.479432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:15.939087Z","caller":"traceutil/trace.go:171","msg":"trace[883841054] linearizableReadLoop","detail":"{readStateIndex:24900; appliedIndex:24898; }","duration":"237.283448ms","start":"2026-03-02T22:13:15.70179Z","end":"2026-03-02T22:13:15.939073Z","steps":["trace[883841054] 'read index received' (duration: 107.613193ms)","trace[883841054] 'applied index is now lower than readState.Index' (duration: 129.669515ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:15.939156Z","caller":"traceutil/trace.go:171","msg":"trace[1747603815] transaction","detail":"{read_only:false; response_revision:24066; number_of_response:1; }","duration":"487.969938ms","start":"2026-03-02T22:13:15.451137Z","end":"2026-03-02T22:13:15.939107Z","steps":["trace[1747603815] 'process raft request' (duration: 358.16049ms)","trace[1747603815] 'compare' (duration: 129.37687ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:15.939279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.466891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:13:15.939315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.451101Z","time spent":"488.13302ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:15.939355Z","caller":"traceutil/trace.go:171","msg":"trace[94738279] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24067; }","duration":"237.588674ms","start":"2026-03-02T22:13:15.701746Z","end":"2026-03-02T22:13:15.939335Z","steps":["trace[94738279] 'agreement among raft nodes before linearized reading' (duration: 237.427031ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:15.939406Z","caller":"traceutil/trace.go:171","msg":"trace[236286153] transaction","detail":"{read_only:false; response_revision:24067; number_of_response:1; }","duration":"298.762404ms","start":"2026-03-02T22:13:15.640587Z","end":"2026-03-02T22:13:15.93935Z","steps":["trace[236286153] 'process raft request' (duration: 298.437867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:16.257953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.506514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/openstack/manila-api\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:16.258048Z","caller":"traceutil/trace.go:171","msg":"trace[745824357] range","detail":"{range_begin:/registry/poddisruptionbudgets/openstack/manila-api; range_end:; response_count:0; response_revision:24067; }","duration":"164.655896ms","start":"2026-03-02T22:13:16.09337Z","end":"2026-03-02T22:13:16.258026Z","steps":["trace[745824357] 'range keys from in-memory index tree' (duration: 164.417091ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:16.956667Z","caller":"traceutil/trace.go:171","msg":"trace[783307068] linearizableReadLoop","detail":"{readStateIndex:24960; appliedIndex:24959; }","duration":"207.069266ms","start":"2026-03-02T22:13:16.749566Z","end":"2026-03-02T22:13:16.956636Z","steps":["trace[783307068] 'read index received' (duration: 206.939374ms)","trace[783307068] 'applied index is now lower than readState.Index' (duration: 128.272µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:16.956796Z","caller":"traceutil/trace.go:171","msg":"trace[802338980] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"209.221557ms","start":"2026-03-02T22:13:16.747519Z","end":"2026-03-02T22:13:16.956741Z","steps":["trace[802338980] 'process raft request' (duration: 208.936272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:16.957112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.517314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2644"} {"level":"info","ts":"2026-03-02T22:13:16.957169Z","caller":"traceutil/trace.go:171","msg":"trace[1326586937] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:24125; }","duration":"207.604067ms","start":"2026-03-02T22:13:16.749548Z","end":"2026-03-02T22:13:16.957152Z","steps":["trace[1326586937] 'agreement among raft nodes before linearized reading' (duration: 207.24578ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.028387Z","caller":"traceutil/trace.go:171","msg":"trace[1760000600] transaction","detail":"{read_only:false; response_revision:24126; number_of_response:1; }","duration":"277.199213ms","start":"2026-03-02T22:13:16.751135Z","end":"2026-03-02T22:13:17.028334Z","steps":["trace[1760000600] 'process raft request' (duration: 276.763065ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.028951Z","caller":"traceutil/trace.go:171","msg":"trace[931489701] transaction","detail":"{read_only:false; response_revision:24127; number_of_response:1; }","duration":"277.705172ms","start":"2026-03-02T22:13:16.751192Z","end":"2026-03-02T22:13:17.028897Z","steps":["trace[931489701] 'process raft request' (duration: 276.906368ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.029096Z","caller":"traceutil/trace.go:171","msg":"trace[1653720357] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"277.681361ms","start":"2026-03-02T22:13:16.751373Z","end":"2026-03-02T22:13:17.029054Z","steps":["trace[1653720357] 'process raft request' (duration: 277.358335ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.029192Z","caller":"traceutil/trace.go:171","msg":"trace[1333802506] transaction","detail":"{read_only:false; response_revision:24129; number_of_response:1; }","duration":"249.523488ms","start":"2026-03-02T22:13:16.779601Z","end":"2026-03-02T22:13:17.029124Z","steps":["trace[1333802506] 'process raft request' (duration: 249.332504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:17.029375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.845494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-02T22:13:17.029392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.652323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-03-02T22:13:17.029432Z","caller":"traceutil/trace.go:171","msg":"trace[335443469] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24131; }","duration":"117.914416ms","start":"2026-03-02T22:13:16.911502Z","end":"2026-03-02T22:13:17.029416Z","steps":["trace[335443469] 'agreement among raft nodes before linearized reading' (duration: 117.719382ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.029469Z","caller":"traceutil/trace.go:171","msg":"trace[171992848] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:24131; }","duration":"268.773605ms","start":"2026-03-02T22:13:16.760674Z","end":"2026-03-02T22:13:17.029448Z","steps":["trace[171992848] 'agreement among raft nodes before linearized reading' (duration: 268.595762ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.029469Z","caller":"traceutil/trace.go:171","msg":"trace[879165625] transaction","detail":"{read_only:false; response_revision:24130; number_of_response:1; }","duration":"128.120046ms","start":"2026-03-02T22:13:16.901321Z","end":"2026-03-02T22:13:17.029441Z","steps":["trace[879165625] 'process raft request' (duration: 127.718108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:17.02944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.283552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:13:17.029505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.901916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:17.029587Z","caller":"traceutil/trace.go:171","msg":"trace[1380048837] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:24131; }","duration":"141.514046ms","start":"2026-03-02T22:13:16.888038Z","end":"2026-03-02T22:13:17.029552Z","steps":["trace[1380048837] 'agreement among raft nodes before linearized reading' (duration: 141.212851ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.029603Z","caller":"traceutil/trace.go:171","msg":"trace[1466146589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24131; }","duration":"118.012368ms","start":"2026-03-02T22:13:16.911568Z","end":"2026-03-02T22:13:17.029581Z","steps":["trace[1466146589] 'agreement among raft nodes before linearized reading' (duration: 117.867305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:17.031251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.673288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2209354"} {"level":"info","ts":"2026-03-02T22:13:17.034066Z","caller":"traceutil/trace.go:171","msg":"trace[190967966] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:24131; }","duration":"137.522261ms","start":"2026-03-02T22:13:16.896518Z","end":"2026-03-02T22:13:17.03404Z","steps":["trace[190967966] 'agreement among raft nodes before linearized reading' (duration: 132.862015ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.135291Z","caller":"traceutil/trace.go:171","msg":"trace[588968482] linearizableReadLoop","detail":"{readStateIndex:24968; appliedIndex:24966; }","duration":"106.130446ms","start":"2026-03-02T22:13:17.029123Z","end":"2026-03-02T22:13:17.135254Z","steps":["trace[588968482] 'read index received' (duration: 105.83476ms)","trace[588968482] 'applied index is now lower than readState.Index' (duration: 294.066µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:17.135452Z","caller":"traceutil/trace.go:171","msg":"trace[679169442] transaction","detail":"{read_only:false; response_revision:24132; number_of_response:1; }","duration":"173.358288ms","start":"2026-03-02T22:13:16.962052Z","end":"2026-03-02T22:13:17.135411Z","steps":["trace[679169442] 'process raft request' (duration: 172.865009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:17.135626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.697825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-03-02T22:13:17.135438Z","caller":"traceutil/trace.go:171","msg":"trace[1021692517] transaction","detail":"{read_only:false; response_revision:24133; number_of_response:1; }","duration":"168.923685ms","start":"2026-03-02T22:13:16.966459Z","end":"2026-03-02T22:13:17.135383Z","steps":["trace[1021692517] 'process raft request' (duration: 168.693021ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.13571Z","caller":"traceutil/trace.go:171","msg":"trace[1013116641] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:24133; }","duration":"173.826288ms","start":"2026-03-02T22:13:16.961856Z","end":"2026-03-02T22:13:17.135683Z","steps":["trace[1013116641] 'agreement among raft nodes before linearized reading' (duration: 173.605933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:17.135626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.508213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-03-02T22:13:17.135874Z","caller":"traceutil/trace.go:171","msg":"trace[161218340] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:24133; }","duration":"172.770178ms","start":"2026-03-02T22:13:16.963052Z","end":"2026-03-02T22:13:17.135822Z","steps":["trace[161218340] 'agreement among raft nodes before linearized reading' (duration: 172.36651ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.147204Z","caller":"traceutil/trace.go:171","msg":"trace[1497636967] transaction","detail":"{read_only:false; response_revision:24134; number_of_response:1; }","duration":"109.268545ms","start":"2026-03-02T22:13:17.037915Z","end":"2026-03-02T22:13:17.147183Z","steps":["trace[1497636967] 'process raft request' (duration: 109.03161ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.147324Z","caller":"traceutil/trace.go:171","msg":"trace[1743655917] transaction","detail":"{read_only:false; response_revision:24135; number_of_response:1; }","duration":"109.05056ms","start":"2026-03-02T22:13:17.038225Z","end":"2026-03-02T22:13:17.147276Z","steps":["trace[1743655917] 'process raft request' (duration: 108.878927ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.147422Z","caller":"traceutil/trace.go:171","msg":"trace[282743834] transaction","detail":"{read_only:false; response_revision:24136; number_of_response:1; }","duration":"107.293849ms","start":"2026-03-02T22:13:17.040079Z","end":"2026-03-02T22:13:17.147373Z","steps":["trace[282743834] 'process raft request' (duration: 107.085205ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.14757Z","caller":"traceutil/trace.go:171","msg":"trace[896935967] transaction","detail":"{read_only:false; response_revision:24138; number_of_response:1; }","duration":"107.267458ms","start":"2026-03-02T22:13:17.040269Z","end":"2026-03-02T22:13:17.147537Z","steps":["trace[896935967] 'process raft request' (duration: 107.040644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:17.147668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.655663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:17.147391Z","caller":"traceutil/trace.go:171","msg":"trace[1684525998] transaction","detail":"{read_only:false; response_revision:24137; number_of_response:1; }","duration":"107.145246ms","start":"2026-03-02T22:13:17.040221Z","end":"2026-03-02T22:13:17.147366Z","steps":["trace[1684525998] 'process raft request' (duration: 107.016084ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:17.147718Z","caller":"traceutil/trace.go:171","msg":"trace[1912868745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24138; }","duration":"116.722524ms","start":"2026-03-02T22:13:17.030983Z","end":"2026-03-02T22:13:17.147705Z","steps":["trace[1912868745] 'agreement among raft nodes before linearized reading' (duration: 116.616362ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:14:06.463562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21962} {"level":"info","ts":"2026-03-02T22:14:06.59818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21962,"took":"130.398297ms","hash":873540545,"current-db-size-bytes":50036736,"current-db-size":"50 MB","current-db-size-in-use-bytes":31744000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-02T22:14:06.59825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":873540545,"revision":21962,"compact-revision":18880} {"level":"info","ts":"2026-03-02T22:19:06.470064Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24625} {"level":"info","ts":"2026-03-02T22:19:06.599446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24625,"took":"125.488146ms","hash":2898581225,"current-db-size-bytes":50036736,"current-db-size":"50 MB","current-db-size-in-use-bytes":32460800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-02T22:19:06.599529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2898581225,"revision":24625,"compact-revision":21962} {"level":"info","ts":"2026-03-02T22:23:37.045647Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-02T22:23:37.050545Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-03-02T22:23:37.050644Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-03-02T22:24:06.485411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27156} {"level":"info","ts":"2026-03-02T22:24:06.653743Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27156,"took":"162.566645ms","hash":1398642070,"current-db-size-bytes":50036736,"current-db-size":"50 MB","current-db-size-in-use-bytes":27824128,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-02T22:24:06.653894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1398642070,"revision":27156,"compact-revision":24625} {"level":"info","ts":"2026-03-02T22:29:06.492686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29225} {"level":"info","ts":"2026-03-02T22:29:06.608652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29225,"took":"112.337731ms","hash":268585756,"current-db-size-bytes":50036736,"current-db-size":"50 MB","current-db-size-in-use-bytes":28667904,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-02T22:29:06.608725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":268585756,"revision":29225,"compact-revision":27156} {"level":"info","ts":"2026-03-02T22:34:06.502784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31342} {"level":"info","ts":"2026-03-02T22:34:06.671074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31342,"took":"155.538625ms","hash":3871131309,"current-db-size-bytes":51302400,"current-db-size":"51 MB","current-db-size-in-use-bytes":29417472,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-02T22:34:06.671149Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3871131309,"revision":31342,"compact-revision":29225} {"level":"info","ts":"2026-03-02T22:36:03.964099Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008a8d.wal"} {"level":"info","ts":"2026-03-02T22:39:06.512498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33495} {"level":"info","ts":"2026-03-02T22:39:06.654767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33495,"took":"137.303777ms","hash":46194414,"current-db-size-bytes":53276672,"current-db-size":"53 MB","current-db-size-in-use-bytes":29302784,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-02T22:39:06.654824Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":46194414,"revision":33495,"compact-revision":31342} {"level":"info","ts":"2026-03-02T22:44:06.518933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35574} {"level":"info","ts":"2026-03-02T22:44:06.642148Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35574,"took":"118.67304ms","hash":463406629,"current-db-size-bytes":53276672,"current-db-size":"53 MB","current-db-size-in-use-bytes":27766784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-02T22:44:06.642236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":463406629,"revision":35574,"compact-revision":33495}