{"level":"warn","ts":"2026-06-02T21:28:37.62911Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:28:37.629234Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-06-02T21:28:37.629327Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:28:37.629341Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-02T21:28:37.629363Z","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-06-02T21:28:37.630067Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-06-02T21:28:37.630151Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-06-02T21:28:37.632626Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.222703ms"} {"level":"info","ts":"2026-06-02T21:28:37.6374Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-06-02T21:28:37.637526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T21:28:37.637562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-06-02T21:28:37.637587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T21:28:37.637611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-06-02T21:28:37.637667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-06-02T21:28:37.642527Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T21:28:37.644629Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T21:28:37.648025Z","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-06-02T21:28:37.652038Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T21:28:37.652349Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T21:28:37.652552Z","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-06-02T21:28:37.652658Z","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-06-02T21:28:37.652677Z","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-06-02T21:28:37.653814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-06-02T21:28:37.654074Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-02T21:28:37.657042Z","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-06-02T21:28:37.657139Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-02T21:28:37.657176Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-02T21:28:37.657352Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T21:28:37.657385Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T21:28:38.138676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T21:28:38.138762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T21:28:38.138804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-06-02T21:28:38.138889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-06-02T21:28:38.13891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-02T21:28:38.138928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-06-02T21:28:38.138944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-02T21:28:38.139996Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:28:38.140778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T21:28:38.140949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:28:38.141054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:28:38.141259Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:28:38.141412Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:28:38.14147Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:28:38.14409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T21:28:38.144895Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-06-02T21:28:38.15543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T21:28:38.155465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T21:29:22.376161Z","caller":"traceutil/trace.go:171","msg":"trace[323275368] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"146.635161ms","start":"2026-06-02T21:29:22.229501Z","end":"2026-06-02T21:29:22.376136Z","steps":["trace[323275368] 'process raft request' (duration: 145.975733ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:29:22.592726Z","caller":"traceutil/trace.go:171","msg":"trace[1368363000] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"170.85667ms","start":"2026-06-02T21:29:22.421837Z","end":"2026-06-02T21:29:22.592694Z","steps":["trace[1368363000] 'read index received' (duration: 170.683935ms)","trace[1368363000] 'applied index is now lower than readState.Index' (duration: 171.735µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:29:22.592785Z","caller":"traceutil/trace.go:171","msg":"trace[986075374] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"200.273744ms","start":"2026-06-02T21:29:22.392467Z","end":"2026-06-02T21:29:22.592741Z","steps":["trace[986075374] 'process raft request' (duration: 200.064179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:29:22.592936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.057946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:29:22.593113Z","caller":"traceutil/trace.go:171","msg":"trace[1364151933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"171.299072ms","start":"2026-06-02T21:29:22.421796Z","end":"2026-06-02T21:29:22.593095Z","steps":["trace[1364151933] 'agreement among raft nodes before linearized reading' (duration: 171.044525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:29:22.60294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.196925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T21:29:22.602988Z","caller":"traceutil/trace.go:171","msg":"trace[683516081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"143.300568ms","start":"2026-06-02T21:29:22.459676Z","end":"2026-06-02T21:29:22.602976Z","steps":["trace[683516081] 'agreement among raft nodes before linearized reading' (duration: 143.139453ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:29:22.603013Z","caller":"traceutil/trace.go:171","msg":"trace[99697624] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"159.344268ms","start":"2026-06-02T21:29:22.443641Z","end":"2026-06-02T21:29:22.602985Z","steps":["trace[99697624] 'process raft request' (duration: 159.117412ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:29:22.782945Z","caller":"traceutil/trace.go:171","msg":"trace[1174755377] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"169.288736ms","start":"2026-06-02T21:29:22.613625Z","end":"2026-06-02T21:29:22.782913Z","steps":["trace[1174755377] 'read index received' (duration: 119.231742ms)","trace[1174755377] 'applied index is now lower than readState.Index' (duration: 50.055204ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:29:22.783162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.451871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:29:22.783113Z","caller":"traceutil/trace.go:171","msg":"trace[1744434805] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"174.048509ms","start":"2026-06-02T21:29:22.609002Z","end":"2026-06-02T21:29:22.78305Z","steps":["trace[1744434805] 'process raft request' (duration: 123.820051ms)","trace[1744434805] 'compare' (duration: 49.92738ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:29:22.783252Z","caller":"traceutil/trace.go:171","msg":"trace[1728670848] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:678; }","duration":"169.636946ms","start":"2026-06-02T21:29:22.613569Z","end":"2026-06-02T21:29:22.783206Z","steps":["trace[1728670848] 'agreement among raft nodes before linearized reading' (duration: 169.458521ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:29:23.298645Z","caller":"traceutil/trace.go:171","msg":"trace[57807249] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"126.226719ms","start":"2026-06-02T21:29:23.172379Z","end":"2026-06-02T21:29:23.298605Z","steps":["trace[57807249] 'process raft request' (duration: 125.349825ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:29:37.941379Z","caller":"traceutil/trace.go:171","msg":"trace[1407421165] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"193.445774ms","start":"2026-06-02T21:29:37.747904Z","end":"2026-06-02T21:29:37.94135Z","steps":["trace[1407421165] 'process raft request' (duration: 193.229788ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:30:56.118564Z","caller":"traceutil/trace.go:171","msg":"trace[659400976] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"190.889251ms","start":"2026-06-02T21:30:55.927652Z","end":"2026-06-02T21:30:56.118541Z","steps":["trace[659400976] 'read index received' (duration: 190.779828ms)","trace[659400976] 'applied index is now lower than readState.Index' (duration: 107.863µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:30:56.118714Z","caller":"traceutil/trace.go:171","msg":"trace[918358476] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"200.454621ms","start":"2026-06-02T21:30:55.918198Z","end":"2026-06-02T21:30:56.118653Z","steps":["trace[918358476] 'process raft request' (duration: 200.181484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:30:56.118822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.155549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-02T21:30:56.119003Z","caller":"traceutil/trace.go:171","msg":"trace[2043295214] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1869; }","duration":"191.404815ms","start":"2026-06-02T21:30:55.927581Z","end":"2026-06-02T21:30:56.118986Z","steps":["trace[2043295214] 'agreement among raft nodes before linearized reading' (duration: 191.093027ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:30:56.217117Z","caller":"traceutil/trace.go:171","msg":"trace[913719690] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"120.61058ms","start":"2026-06-02T21:30:56.09648Z","end":"2026-06-02T21:30:56.217091Z","steps":["trace[913719690] 'process raft request' (duration: 120.520927ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:30:56.21717Z","caller":"traceutil/trace.go:171","msg":"trace[768925749] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"248.65053ms","start":"2026-06-02T21:30:55.968467Z","end":"2026-06-02T21:30:56.217118Z","steps":["trace[768925749] 'process raft request' (duration: 202.137588ms)","trace[768925749] 'compare' (duration: 46.254775ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:30:56.217402Z","caller":"traceutil/trace.go:171","msg":"trace[683412801] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"101.46471ms","start":"2026-06-02T21:30:56.115917Z","end":"2026-06-02T21:30:56.217382Z","steps":["trace[683412801] 'process raft request' (duration: 101.133271ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:30:56.489789Z","caller":"traceutil/trace.go:171","msg":"trace[615804657] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"108.389024ms","start":"2026-06-02T21:30:56.381368Z","end":"2026-06-02T21:30:56.489758Z","steps":["trace[615804657] 'read index received' (duration: 79.48149ms)","trace[615804657] 'applied index is now lower than readState.Index' (duration: 28.905784ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:30:56.489989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.601121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-02T21:30:56.490067Z","caller":"traceutil/trace.go:171","msg":"trace[590427672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1874; }","duration":"108.727174ms","start":"2026-06-02T21:30:56.38132Z","end":"2026-06-02T21:30:56.490047Z","steps":["trace[590427672] 'agreement among raft nodes before linearized reading' (duration: 108.531839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:30:56.49009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.505898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T21:30:56.490133Z","caller":"traceutil/trace.go:171","msg":"trace[343268172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1874; }","duration":"103.588961ms","start":"2026-06-02T21:30:56.386532Z","end":"2026-06-02T21:30:56.490121Z","steps":["trace[343268172] 'agreement among raft nodes before linearized reading' (duration: 103.403585ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:40.612991Z","caller":"traceutil/trace.go:171","msg":"trace[564022370] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"121.64724ms","start":"2026-06-02T21:31:40.491317Z","end":"2026-06-02T21:31:40.612964Z","steps":["trace[564022370] 'process raft request' (duration: 121.470205ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:41.371201Z","caller":"traceutil/trace.go:171","msg":"trace[494493349] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"112.664992ms","start":"2026-06-02T21:31:41.258493Z","end":"2026-06-02T21:31:41.371158Z","steps":["trace[494493349] 'process raft request' (duration: 112.426296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:46.581059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.407838ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T21:31:46.581379Z","caller":"traceutil/trace.go:171","msg":"trace[1748000122] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"156.596576ms","start":"2026-06-02T21:31:46.424756Z","end":"2026-06-02T21:31:46.581352Z","steps":["trace[1748000122] 'read index received' (duration: 69.742µs)","trace[1748000122] 'applied index is now lower than readState.Index' (duration: 156.524544ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:46.581401Z","caller":"traceutil/trace.go:171","msg":"trace[1534254403] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"150.860892ms","start":"2026-06-02T21:31:46.430526Z","end":"2026-06-02T21:31:46.581387Z","steps":["trace[1534254403] 'process raft request' (duration: 150.807271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:46.581661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.903864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:31:46.581666Z","caller":"traceutil/trace.go:171","msg":"trace[477864958] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"151.81133ms","start":"2026-06-02T21:31:46.429807Z","end":"2026-06-02T21:31:46.581619Z","steps":["trace[477864958] 'process raft request' (duration: 151.377297ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:46.581743Z","caller":"traceutil/trace.go:171","msg":"trace[904649611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2201; }","duration":"157.061669ms","start":"2026-06-02T21:31:46.424669Z","end":"2026-06-02T21:31:46.58173Z","steps":["trace[904649611] 'agreement among raft nodes before linearized reading' (duration: 156.941685ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:32:36.208177Z","caller":"traceutil/trace.go:171","msg":"trace[1383147887] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"104.573579ms","start":"2026-06-02T21:32:36.103572Z","end":"2026-06-02T21:32:36.208145Z","steps":["trace[1383147887] 'process raft request' (duration: 104.411044ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:35:25.920425Z","caller":"traceutil/trace.go:171","msg":"trace[504219858] transaction","detail":"{read_only:false; response_revision:3761; number_of_response:1; }","duration":"106.94236ms","start":"2026-06-02T21:35:25.813445Z","end":"2026-06-02T21:35:25.920387Z","steps":["trace[504219858] 'process raft request' (duration: 106.60874ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:00.068865Z","caller":"traceutil/trace.go:171","msg":"trace[1714744344] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"284.280902ms","start":"2026-06-02T21:36:59.784536Z","end":"2026-06-02T21:37:00.068817Z","steps":["trace[1714744344] 'process raft request' (duration: 258.705179ms)","trace[1714744344] 'compare' (duration: 25.13243ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:37:00.125304Z","caller":"traceutil/trace.go:171","msg":"trace[849548559] linearizableReadLoop","detail":"{readStateIndex:5174; appliedIndex:5172; }","duration":"235.713088ms","start":"2026-06-02T21:36:59.889557Z","end":"2026-06-02T21:37:00.12527Z","steps":["trace[849548559] 'read index received' (duration: 153.690352ms)","trace[849548559] 'applied index is now lower than readState.Index' (duration: 82.021626ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:37:00.125339Z","caller":"traceutil/trace.go:171","msg":"trace[1384854117] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"339.731527ms","start":"2026-06-02T21:36:59.785578Z","end":"2026-06-02T21:37:00.125309Z","steps":["trace[1384854117] 'process raft request' (duration: 339.519851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:00.125515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.948014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9bf1d490-4e2c-4610-afb9-14013a735816\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-02T21:37:00.125557Z","caller":"traceutil/trace.go:171","msg":"trace[2018216453] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9bf1d490-4e2c-4610-afb9-14013a735816; range_end:; response_count:1; response_revision:4983; }","duration":"236.061088ms","start":"2026-06-02T21:36:59.889483Z","end":"2026-06-02T21:37:00.125544Z","steps":["trace[2018216453] 'agreement among raft nodes before linearized reading' (duration: 235.897753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:00.125713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.308719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:37:00.125842Z","caller":"traceutil/trace.go:171","msg":"trace[1139790434] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4983; }","duration":"155.531244ms","start":"2026-06-02T21:36:59.97028Z","end":"2026-06-02T21:37:00.125811Z","steps":["trace[1139790434] 'agreement among raft nodes before linearized reading' (duration: 155.276157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:00.125924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.094493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728699"} {"level":"info","ts":"2026-06-02T21:37:00.125951Z","caller":"traceutil/trace.go:171","msg":"trace[41804201] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4983; }","duration":"131.171005ms","start":"2026-06-02T21:36:59.994774Z","end":"2026-06-02T21:37:00.125945Z","steps":["trace[41804201] 'agreement among raft nodes before linearized reading' (duration: 130.934248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:00.126027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:36:59.785544Z","time spent":"339.922313ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:37:02.201164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.629128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T21:37:02.201159Z","caller":"traceutil/trace.go:171","msg":"trace[1415817224] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"121.764714ms","start":"2026-06-02T21:37:02.079358Z","end":"2026-06-02T21:37:02.201123Z","steps":["trace[1415817224] 'process raft request' (duration: 121.350282ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:02.201272Z","caller":"traceutil/trace.go:171","msg":"trace[659966257] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5021; }","duration":"145.806002ms","start":"2026-06-02T21:37:02.055443Z","end":"2026-06-02T21:37:02.201249Z","steps":["trace[659966257] 'range keys from in-memory index tree' (duration: 145.402722ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:12.671163Z","caller":"traceutil/trace.go:171","msg":"trace[1910416623] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"134.085556ms","start":"2026-06-02T21:37:12.537028Z","end":"2026-06-02T21:37:12.671114Z","steps":["trace[1910416623] 'process raft request' (duration: 125.196199ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:12.671127Z","caller":"traceutil/trace.go:171","msg":"trace[1996649656] linearizableReadLoop","detail":"{readStateIndex:5438; appliedIndex:5435; }","duration":"132.357408ms","start":"2026-06-02T21:37:12.538732Z","end":"2026-06-02T21:37:12.67109Z","steps":["trace[1996649656] 'read index received' (duration: 48.472101ms)","trace[1996649656] 'applied index is now lower than readState.Index' (duration: 83.884157ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:37:12.671323Z","caller":"traceutil/trace.go:171","msg":"trace[1735599573] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"133.656443ms","start":"2026-06-02T21:37:12.537656Z","end":"2026-06-02T21:37:12.671313Z","steps":["trace[1735599573] 'process raft request' (duration: 133.354555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:12.671579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.327099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129772"} {"level":"warn","ts":"2026-06-02T21:37:12.671583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.537507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-02T21:37:12.671579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.80805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T21:37:12.671603Z","caller":"traceutil/trace.go:171","msg":"trace[864374745] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5238; }","duration":"131.377161ms","start":"2026-06-02T21:37:12.540217Z","end":"2026-06-02T21:37:12.671594Z","steps":["trace[864374745] 'agreement among raft nodes before linearized reading' (duration: 131.215207ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:12.671611Z","caller":"traceutil/trace.go:171","msg":"trace[435019489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5238; }","duration":"130.588778ms","start":"2026-06-02T21:37:12.541013Z","end":"2026-06-02T21:37:12.671602Z","steps":["trace[435019489] 'agreement among raft nodes before linearized reading' (duration: 130.512756ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:12.671624Z","caller":"traceutil/trace.go:171","msg":"trace[2116884542] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5238; }","duration":"132.903693ms","start":"2026-06-02T21:37:12.538706Z","end":"2026-06-02T21:37:12.671609Z","steps":["trace[2116884542] 'agreement among raft nodes before linearized reading' (duration: 132.695677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:12.671733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.797117ms","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-06-02T21:37:12.671833Z","caller":"traceutil/trace.go:171","msg":"trace[434374560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5238; }","duration":"124.930551ms","start":"2026-06-02T21:37:12.546879Z","end":"2026-06-02T21:37:12.67181Z","steps":["trace[434374560] 'agreement among raft nodes before linearized reading' (duration: 124.636113ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:13.251818Z","caller":"traceutil/trace.go:171","msg":"trace[1810221092] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"244.062601ms","start":"2026-06-02T21:37:13.007724Z","end":"2026-06-02T21:37:13.251787Z","steps":["trace[1810221092] 'process raft request' (duration: 243.865935ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:13.262928Z","caller":"traceutil/trace.go:171","msg":"trace[557746424] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"242.017673ms","start":"2026-06-02T21:37:13.020883Z","end":"2026-06-02T21:37:13.262901Z","steps":["trace[557746424] 'process raft request' (duration: 241.760806ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:13.263181Z","caller":"traceutil/trace.go:171","msg":"trace[868384984] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"143.777496ms","start":"2026-06-02T21:37:13.119386Z","end":"2026-06-02T21:37:13.263164Z","steps":["trace[868384984] 'process raft request' (duration: 143.454628ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:13.263577Z","caller":"traceutil/trace.go:171","msg":"trace[14784310] linearizableReadLoop","detail":"{readStateIndex:5450; appliedIndex:5448; }","duration":"217.899502ms","start":"2026-06-02T21:37:13.045636Z","end":"2026-06-02T21:37:13.263535Z","steps":["trace[14784310] 'read index received' (duration: 205.954328ms)","trace[14784310] 'applied index is now lower than readState.Index' (duration: 11.943044ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:37:13.263781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.115538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:37:13.26382Z","caller":"traceutil/trace.go:171","msg":"trace[2053732585] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:5252; }","duration":"218.232001ms","start":"2026-06-02T21:37:13.045574Z","end":"2026-06-02T21:37:13.263806Z","steps":["trace[2053732585] 'agreement among raft nodes before linearized reading' (duration: 218.113108ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:20.288338Z","caller":"traceutil/trace.go:171","msg":"trace[1077733944] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"183.838072ms","start":"2026-06-02T21:37:20.104475Z","end":"2026-06-02T21:37:20.288313Z","steps":["trace[1077733944] 'process raft request' (duration: 180.741425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:20.513363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.094323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:37:20.513738Z","caller":"traceutil/trace.go:171","msg":"trace[655415455] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"408.345099ms","start":"2026-06-02T21:37:20.105369Z","end":"2026-06-02T21:37:20.513714Z","steps":["trace[655415455] 'process raft request' (duration: 408.171304ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:20.513721Z","caller":"traceutil/trace.go:171","msg":"trace[1444226581] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"408.513974ms","start":"2026-06-02T21:37:20.105155Z","end":"2026-06-02T21:37:20.513669Z","steps":["trace[1444226581] 'process raft request' (duration: 305.001739ms)","trace[1444226581] 'compare' (duration: 102.923498ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:37:20.51387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:37:20.105342Z","time spent":"408.458012ms","remote":"127.0.0.1:60292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:37:20.513933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:37:20.105143Z","time spent":"408.678308ms","remote":"127.0.0.1:60178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1082,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:37:20.51404Z","caller":"traceutil/trace.go:171","msg":"trace[712023690] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"408.207525ms","start":"2026-06-02T21:37:20.105811Z","end":"2026-06-02T21:37:20.514019Z","steps":["trace[712023690] 'process raft request' (duration: 407.849216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:20.514186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:37:20.105785Z","time spent":"408.346459ms","remote":"127.0.0.1:60178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:37:20.514231Z","caller":"traceutil/trace.go:171","msg":"trace[1170495724] linearizableReadLoop","detail":"{readStateIndex:5610; appliedIndex:5604; }","duration":"400.726716ms","start":"2026-06-02T21:37:20.113476Z","end":"2026-06-02T21:37:20.514203Z","steps":["trace[1170495724] 'read index received' (duration: 171.818877ms)","trace[1170495724] 'applied index is now lower than readState.Index' (duration: 228.906679ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:37:20.514294Z","caller":"traceutil/trace.go:171","msg":"trace[1921427924] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"408.394581ms","start":"2026-06-02T21:37:20.105888Z","end":"2026-06-02T21:37:20.514283Z","steps":["trace[1921427924] 'process raft request' (duration: 408.031201ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:20.514328Z","caller":"traceutil/trace.go:171","msg":"trace[1320045360] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"407.815074ms","start":"2026-06-02T21:37:20.106474Z","end":"2026-06-02T21:37:20.514289Z","steps":["trace[1320045360] 'process raft request' (duration: 407.572967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:20.514519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:37:20.106464Z","time spent":"407.957969ms","remote":"127.0.0.1:60292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1394,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:37:20.51458Z","caller":"traceutil/trace.go:171","msg":"trace[194354964] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"238.084714ms","start":"2026-06-02T21:37:20.276469Z","end":"2026-06-02T21:37:20.514554Z","steps":["trace[194354964] 'process raft request' (duration: 237.638422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:20.514713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.694526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1265d503-6e04-4c1e-9746-bfcb80f34ac6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-02T21:37:20.514753Z","caller":"traceutil/trace.go:171","msg":"trace[1469568582] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1265d503-6e04-4c1e-9746-bfcb80f34ac6; range_end:; response_count:1; response_revision:5408; }","duration":"203.788078ms","start":"2026-06-02T21:37:20.310954Z","end":"2026-06-02T21:37:20.514742Z","steps":["trace[1469568582] 'agreement among raft nodes before linearized reading' (duration: 203.634934ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:20.514954Z","caller":"traceutil/trace.go:171","msg":"trace[498471294] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"230.442941ms","start":"2026-06-02T21:37:20.2845Z","end":"2026-06-02T21:37:20.514943Z","steps":["trace[498471294] 'process raft request' (duration: 229.657689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:20.515383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.878098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T21:37:20.515437Z","caller":"traceutil/trace.go:171","msg":"trace[210642446] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5408; }","duration":"401.985581ms","start":"2026-06-02T21:37:20.113438Z","end":"2026-06-02T21:37:20.515424Z","steps":["trace[210642446] 'agreement among raft nodes before linearized reading' (duration: 401.029745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:20.515523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:37:20.113428Z","time spent":"402.082724ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":45,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-02T21:37:20.516123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:37:20.10585Z","time spent":"408.477413ms","remote":"127.0.0.1:60178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:38:38.176628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3002} {"level":"info","ts":"2026-06-02T21:38:38.256685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3002,"took":"78.454937ms","hash":2244929264,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T21:38:38.256761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2244929264,"revision":3002,"compact-revision":-1} {"level":"info","ts":"2026-06-02T21:43:18.116223Z","caller":"traceutil/trace.go:171","msg":"trace[654115607] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"212.053306ms","start":"2026-06-02T21:43:17.904139Z","end":"2026-06-02T21:43:18.116193Z","steps":["trace[654115607] 'process raft request' (duration: 211.834209ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:43:18.239745Z","caller":"traceutil/trace.go:171","msg":"trace[512455273] transaction","detail":"{read_only:false; response_revision:8193; number_of_response:1; }","duration":"307.503911ms","start":"2026-06-02T21:43:17.932194Z","end":"2026-06-02T21:43:18.239698Z","steps":["trace[512455273] 'process raft request' (duration: 237.490694ms)","trace[512455273] 'compare' (duration: 69.483471ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:43:18.240002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:43:17.93218Z","time spent":"307.694615ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:43:18.240119Z","caller":"traceutil/trace.go:171","msg":"trace[922609336] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"305.619727ms","start":"2026-06-02T21:43:17.934466Z","end":"2026-06-02T21:43:18.240086Z","steps":["trace[922609336] 'process raft request' (duration: 305.400871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:43:18.240399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:43:17.934419Z","time spent":"305.793132ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:43:38.184829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6131} {"level":"info","ts":"2026-06-02T21:43:38.286131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6131,"took":"98.737358ms","hash":3787540810,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":23375872,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-02T21:43:38.286204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3787540810,"revision":6131,"compact-revision":3002} {"level":"info","ts":"2026-06-02T21:43:57.997305Z","caller":"traceutil/trace.go:171","msg":"trace[527884631] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"280.749855ms","start":"2026-06-02T21:43:57.716534Z","end":"2026-06-02T21:43:57.997283Z","steps":["trace[527884631] 'process raft request' (duration: 280.450727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:44:11.104891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.990596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:44:11.105071Z","caller":"traceutil/trace.go:171","msg":"trace[1322824035] linearizableReadLoop","detail":"{readStateIndex:8875; appliedIndex:8874; }","duration":"198.941076ms","start":"2026-06-02T21:44:10.906107Z","end":"2026-06-02T21:44:11.105048Z","steps":["trace[1322824035] 'read index received' (duration: 66.60679ms)","trace[1322824035] 'applied index is now lower than readState.Index' (duration: 132.332156ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:44:11.105086Z","caller":"traceutil/trace.go:171","msg":"trace[1767238869] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"247.093355ms","start":"2026-06-02T21:44:10.857959Z","end":"2026-06-02T21:44:11.105052Z","steps":["trace[1767238869] 'process raft request' (duration: 114.809801ms)","trace[1767238869] 'compare' (duration: 131.77903ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:44:11.10539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.249154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T21:44:11.105496Z","caller":"traceutil/trace.go:171","msg":"trace[143635289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8570; }","duration":"199.397768ms","start":"2026-06-02T21:44:10.906076Z","end":"2026-06-02T21:44:11.105474Z","steps":["trace[143635289] 'agreement among raft nodes before linearized reading' (duration: 199.073819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:44:11.145948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.779688ms","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-06-02T21:44:11.146044Z","caller":"traceutil/trace.go:171","msg":"trace[1367847835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8571; }","duration":"239.919682ms","start":"2026-06-02T21:44:10.906104Z","end":"2026-06-02T21:44:11.146024Z","steps":["trace[1367847835] 'agreement among raft nodes before linearized reading' (duration: 239.670855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:44:11.14606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.413955ms","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-06-02T21:44:11.146132Z","caller":"traceutil/trace.go:171","msg":"trace[1570116469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8571; }","duration":"207.519468ms","start":"2026-06-02T21:44:10.938592Z","end":"2026-06-02T21:44:11.146112Z","steps":["trace[1570116469] 'agreement among raft nodes before linearized reading' (duration: 207.273591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:44:11.146059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.382195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-02T21:44:11.14621Z","caller":"traceutil/trace.go:171","msg":"trace[98480149] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8571; }","duration":"212.571139ms","start":"2026-06-02T21:44:10.933619Z","end":"2026-06-02T21:44:11.14619Z","steps":["trace[98480149] 'agreement among raft nodes before linearized reading' (duration: 212.277721ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:44:11.252063Z","caller":"traceutil/trace.go:171","msg":"trace[1029066559] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"101.865565ms","start":"2026-06-02T21:44:11.150174Z","end":"2026-06-02T21:44:11.25204Z","steps":["trace[1029066559] 'process raft request' (duration: 101.820694ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:44:11.252455Z","caller":"traceutil/trace.go:171","msg":"trace[1526752811] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"102.669937ms","start":"2026-06-02T21:44:11.149761Z","end":"2026-06-02T21:44:11.252431Z","steps":["trace[1526752811] 'process raft request' (duration: 61.632519ms)","trace[1526752811] 'compare' (duration: 40.3968ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:44:11.252482Z","caller":"traceutil/trace.go:171","msg":"trace[823552888] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"102.373929ms","start":"2026-06-02T21:44:11.150073Z","end":"2026-06-02T21:44:11.252447Z","steps":["trace[823552888] 'process raft request' (duration: 101.876966ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:44:11.647996Z","caller":"traceutil/trace.go:171","msg":"trace[571057595] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"151.854917ms","start":"2026-06-02T21:44:11.496114Z","end":"2026-06-02T21:44:11.647969Z","steps":["trace[571057595] 'process raft request' (duration: 151.672322ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:44:11.663844Z","caller":"traceutil/trace.go:171","msg":"trace[2082371501] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8882; }","duration":"135.476684ms","start":"2026-06-02T21:44:11.528342Z","end":"2026-06-02T21:44:11.663819Z","steps":["trace[2082371501] 'read index received' (duration: 119.494453ms)","trace[2082371501] 'applied index is now lower than readState.Index' (duration: 15.980921ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:44:11.664246Z","caller":"traceutil/trace.go:171","msg":"trace[258883622] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"162.112415ms","start":"2026-06-02T21:44:11.502116Z","end":"2026-06-02T21:44:11.664228Z","steps":["trace[258883622] 'process raft request' (duration: 161.55345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:44:11.664531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.177354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:44:11.664573Z","caller":"traceutil/trace.go:171","msg":"trace[346920059] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8578; }","duration":"136.241116ms","start":"2026-06-02T21:44:11.528322Z","end":"2026-06-02T21:44:11.664563Z","steps":["trace[346920059] 'agreement among raft nodes before linearized reading' (duration: 136.136393ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:45:00.681367Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a7.wal"} {"level":"info","ts":"2026-06-02T21:46:13.911935Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T21:46:13.917334Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T21:46:13.917494Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T21:48:38.19422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8306} {"level":"info","ts":"2026-06-02T21:48:38.280643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8306,"took":"81.609275ms","hash":1363470344,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":25960448,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-02T21:48:38.280708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1363470344,"revision":8306,"compact-revision":6131} {"level":"warn","ts":"2026-06-02T21:48:52.760398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.944821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T21:48:52.760506Z","caller":"traceutil/trace.go:171","msg":"trace[2145228893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11067; }","duration":"124.080564ms","start":"2026-06-02T21:48:52.636405Z","end":"2026-06-02T21:48:52.760485Z","steps":["trace[2145228893] 'range keys from in-memory index tree' (duration: 123.812157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:48:52.760714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.035975ms","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-06-02T21:48:52.760871Z","caller":"traceutil/trace.go:171","msg":"trace[315573888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11067; }","duration":"128.070376ms","start":"2026-06-02T21:48:52.632669Z","end":"2026-06-02T21:48:52.76074Z","steps":["trace[315573888] 'range keys from in-memory index tree' (duration: 127.924082ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:53:38.201245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10820} {"level":"info","ts":"2026-06-02T21:53:38.279276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10820,"took":"74.657609ms","hash":631746504,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":27570176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T21:53:38.279362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":631746504,"revision":10820,"compact-revision":8306} {"level":"warn","ts":"2026-06-02T21:55:27.782657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.426033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:55:27.782776Z","caller":"traceutil/trace.go:171","msg":"trace[88327576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13722; }","duration":"359.573257ms","start":"2026-06-02T21:55:27.423167Z","end":"2026-06-02T21:55:27.78274Z","steps":["trace[88327576] 'range keys from in-memory index tree' (duration: 359.351591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:55:27.782828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:55:27.423147Z","time spent":"359.668039ms","remote":"127.0.0.1:59996","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-06-02T21:55:27.783173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.962606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-02T21:55:27.783219Z","caller":"traceutil/trace.go:171","msg":"trace[1855570538] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:13722; }","duration":"351.057428ms","start":"2026-06-02T21:55:27.432148Z","end":"2026-06-02T21:55:27.783206Z","steps":["trace[1855570538] 'range keys from in-memory index tree' (duration: 350.747659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:55:27.783253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:55:27.432099Z","time spent":"351.14599ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-02T21:55:27.783937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.684952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T21:55:27.783987Z","caller":"traceutil/trace.go:171","msg":"trace[1656033758] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:13722; }","duration":"166.773364ms","start":"2026-06-02T21:55:27.617198Z","end":"2026-06-02T21:55:27.783971Z","steps":["trace[1656033758] 'count revisions from in-memory index tree' (duration: 166.612459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:55:36.857438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.458197ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T21:55:36.908701Z","caller":"traceutil/trace.go:171","msg":"trace[859073261] linearizableReadLoop","detail":"{readStateIndex:14362; appliedIndex:14361; }","duration":"192.112392ms","start":"2026-06-02T21:55:36.716557Z","end":"2026-06-02T21:55:36.908669Z","steps":["trace[859073261] 'read index received' (duration: 191.857875ms)","trace[859073261] 'applied index is now lower than readState.Index' (duration: 252.577µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:55:36.908876Z","caller":"traceutil/trace.go:171","msg":"trace[2112649841] transaction","detail":"{read_only:false; response_revision:13889; number_of_response:1; }","duration":"320.102613ms","start":"2026-06-02T21:55:36.588698Z","end":"2026-06-02T21:55:36.9088Z","steps":["trace[2112649841] 'process raft request' (duration: 319.730103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:55:36.908915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.332409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T21:55:36.909106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:55:36.588669Z","time spent":"320.300248ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:55:36.909155Z","caller":"traceutil/trace.go:171","msg":"trace[942499456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13889; }","duration":"192.624107ms","start":"2026-06-02T21:55:36.716497Z","end":"2026-06-02T21:55:36.909121Z","steps":["trace[942499456] 'agreement among raft nodes before linearized reading' (duration: 192.341379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:56:35.378005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.512361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:56:35.378115Z","caller":"traceutil/trace.go:171","msg":"trace[1209626688] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14748; }","duration":"110.695057ms","start":"2026-06-02T21:56:35.267398Z","end":"2026-06-02T21:56:35.378093Z","steps":["trace[1209626688] 'range keys from in-memory index tree' (duration: 110.351317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:56:35.378174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.923711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-06-02T21:56:35.378028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.100049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:56:35.378231Z","caller":"traceutil/trace.go:171","msg":"trace[532780318] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14748; }","duration":"106.966022ms","start":"2026-06-02T21:56:35.271242Z","end":"2026-06-02T21:56:35.378208Z","steps":["trace[532780318] 'range keys from in-memory index tree' (duration: 106.781986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:56:35.378227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.927493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:56:35.378295Z","caller":"traceutil/trace.go:171","msg":"trace[65939746] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14748; }","duration":"110.379987ms","start":"2026-06-02T21:56:35.267873Z","end":"2026-06-02T21:56:35.378253Z","steps":["trace[65939746] 'range keys from in-memory index tree' (duration: 110.015577ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:56:35.378333Z","caller":"traceutil/trace.go:171","msg":"trace[1793668036] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14748; }","duration":"111.108978ms","start":"2026-06-02T21:56:35.267208Z","end":"2026-06-02T21:56:35.378317Z","steps":["trace[1793668036] 'range keys from in-memory index tree' (duration: 110.83165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:56:35.378001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.710433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-02T21:56:35.378477Z","caller":"traceutil/trace.go:171","msg":"trace[661001953] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:14748; }","duration":"103.184556ms","start":"2026-06-02T21:56:35.275225Z","end":"2026-06-02T21:56:35.37841Z","steps":["trace[661001953] 'range keys from in-memory index tree' (duration: 102.519948ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:57:09.70031Z","caller":"traceutil/trace.go:171","msg":"trace[942037273] transaction","detail":"{read_only:false; response_revision:14941; number_of_response:1; }","duration":"154.400279ms","start":"2026-06-02T21:57:09.545868Z","end":"2026-06-02T21:57:09.700268Z","steps":["trace[942037273] 'process raft request' (duration: 154.289046ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:57:09.881444Z","caller":"traceutil/trace.go:171","msg":"trace[143250708] linearizableReadLoop","detail":"{readStateIndex:15447; appliedIndex:15444; }","duration":"332.491109ms","start":"2026-06-02T21:57:09.548936Z","end":"2026-06-02T21:57:09.881427Z","steps":["trace[143250708] 'read index received' (duration: 151.285672ms)","trace[143250708] 'applied index is now lower than readState.Index' (duration: 181.204627ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:57:09.881532Z","caller":"traceutil/trace.go:171","msg":"trace[989059656] transaction","detail":"{read_only:false; response_revision:14943; number_of_response:1; }","duration":"332.628223ms","start":"2026-06-02T21:57:09.548897Z","end":"2026-06-02T21:57:09.881525Z","steps":["trace[989059656] 'process raft request' (duration: 332.463468ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:57:09.881516Z","caller":"traceutil/trace.go:171","msg":"trace[1692060185] transaction","detail":"{read_only:false; response_revision:14942; number_of_response:1; }","duration":"332.655503ms","start":"2026-06-02T21:57:09.548823Z","end":"2026-06-02T21:57:09.881478Z","steps":["trace[1692060185] 'process raft request' (duration: 267.860371ms)","trace[1692060185] 'compare' (duration: 64.484574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:57:09.881604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:57:09.548893Z","time spent":"332.653853ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:57:09.881662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:57:09.548797Z","time spent":"332.777547ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T21:57:09.881724Z","caller":"traceutil/trace.go:171","msg":"trace[268159446] transaction","detail":"{read_only:false; response_revision:14944; number_of_response:1; }","duration":"257.889004ms","start":"2026-06-02T21:57:09.62383Z","end":"2026-06-02T21:57:09.881719Z","steps":["trace[268159446] 'process raft request' (duration: 257.573984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:57:09.881828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.890341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T21:57:09.881849Z","caller":"traceutil/trace.go:171","msg":"trace[2054076128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14944; }","duration":"332.910251ms","start":"2026-06-02T21:57:09.548933Z","end":"2026-06-02T21:57:09.881843Z","steps":["trace[2054076128] 'agreement among raft nodes before linearized reading' (duration: 332.835499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:57:09.881863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:57:09.548914Z","time spent":"332.945382ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T21:57:09.882093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.37059ms","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-06-02T21:57:09.88218Z","caller":"traceutil/trace.go:171","msg":"trace[35178439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14944; }","duration":"251.501944ms","start":"2026-06-02T21:57:09.630657Z","end":"2026-06-02T21:57:09.882159Z","steps":["trace[35178439] 'agreement among raft nodes before linearized reading' (duration: 251.265187ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:58:38.207028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13004} {"level":"info","ts":"2026-06-02T21:58:38.283164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13004,"took":"73.125626ms","hash":319145732,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T21:58:38.283235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":319145732,"revision":13004,"compact-revision":10820} {"level":"info","ts":"2026-06-02T22:02:59.219044Z","caller":"traceutil/trace.go:171","msg":"trace[1161740057] transaction","detail":"{read_only:false; response_revision:17788; number_of_response:1; }","duration":"122.283359ms","start":"2026-06-02T22:02:59.096739Z","end":"2026-06-02T22:02:59.219022Z","steps":["trace[1161740057] 'process raft request' (duration: 122.138345ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:02:59.299678Z","caller":"traceutil/trace.go:171","msg":"trace[1748243898] linearizableReadLoop","detail":"{readStateIndex:18381; appliedIndex:18379; }","duration":"108.890482ms","start":"2026-06-02T22:02:59.190763Z","end":"2026-06-02T22:02:59.299654Z","steps":["trace[1748243898] 'read index received' (duration: 28.135351ms)","trace[1748243898] 'applied index is now lower than readState.Index' (duration: 80.754111ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:02:59.299772Z","caller":"traceutil/trace.go:171","msg":"trace[720365827] transaction","detail":"{read_only:false; response_revision:17789; number_of_response:1; }","duration":"126.790755ms","start":"2026-06-02T22:02:59.172943Z","end":"2026-06-02T22:02:59.299734Z","steps":["trace[720365827] 'process raft request' (duration: 83.655201ms)","trace[720365827] 'compare' (duration: 42.934118ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:02:59.299951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.15841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29673960-758n4\" ","response":"range_response_count:1 size:7041"} {"level":"info","ts":"2026-06-02T22:02:59.300038Z","caller":"traceutil/trace.go:171","msg":"trace[1775301756] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29673960-758n4; range_end:; response_count:1; response_revision:17789; }","duration":"109.287793ms","start":"2026-06-02T22:02:59.19073Z","end":"2026-06-02T22:02:59.300017Z","steps":["trace[1775301756] 'agreement among raft nodes before linearized reading' (duration: 109.028686ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:03:01.834954Z","caller":"traceutil/trace.go:171","msg":"trace[1872936198] transaction","detail":"{read_only:false; response_revision:17825; number_of_response:1; }","duration":"131.133478ms","start":"2026-06-02T22:03:01.703791Z","end":"2026-06-02T22:03:01.834925Z","steps":["trace[1872936198] 'process raft request' (duration: 68.022483ms)","trace[1872936198] 'compare' (duration: 62.5863ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:03:38.213103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15526} {"level":"info","ts":"2026-06-02T22:03:38.321934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15526,"took":"96.194245ms","hash":2721798910,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":34295808,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T22:03:38.321994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2721798910,"revision":15526,"compact-revision":13004} {"level":"info","ts":"2026-06-02T22:06:13.873921Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:06:13.881796Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-02T22:06:13.882033Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-02T22:08:15.516092Z","caller":"traceutil/trace.go:171","msg":"trace[714142487] linearizableReadLoop","detail":"{readStateIndex:20774; appliedIndex:20772; }","duration":"177.401095ms","start":"2026-06-02T22:08:15.338653Z","end":"2026-06-02T22:08:15.516054Z","steps":["trace[714142487] 'read index received' (duration: 27.40396ms)","trace[714142487] 'applied index is now lower than readState.Index' (duration: 149.995975ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:08:15.516122Z","caller":"traceutil/trace.go:171","msg":"trace[1517354940] transaction","detail":"{read_only:false; response_revision:20104; number_of_response:1; }","duration":"182.624272ms","start":"2026-06-02T22:08:15.333464Z","end":"2026-06-02T22:08:15.516088Z","steps":["trace[1517354940] 'process raft request' (duration: 158.566296ms)","trace[1517354940] 'compare' (duration: 23.831649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:08:15.516319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.650772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-02T22:08:15.516365Z","caller":"traceutil/trace.go:171","msg":"trace[62989318] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20104; }","duration":"177.744265ms","start":"2026-06-02T22:08:15.338605Z","end":"2026-06-02T22:08:15.516349Z","steps":["trace[62989318] 'agreement among raft nodes before linearized reading' (duration: 177.602621ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:08:15.8128Z","caller":"traceutil/trace.go:171","msg":"trace[285003979] transaction","detail":"{read_only:false; response_revision:20105; number_of_response:1; }","duration":"291.861122ms","start":"2026-06-02T22:08:15.520906Z","end":"2026-06-02T22:08:15.812767Z","steps":["trace[285003979] 'process raft request' (duration: 291.537303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:08:15.812955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.952732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:08:15.813069Z","caller":"traceutil/trace.go:171","msg":"trace[993057271] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:20105; }","duration":"256.110046ms","start":"2026-06-02T22:08:15.556936Z","end":"2026-06-02T22:08:15.813046Z","steps":["trace[993057271] 'agreement among raft nodes before linearized reading' (duration: 255.931641ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:08:15.813242Z","caller":"traceutil/trace.go:171","msg":"trace[1475335046] linearizableReadLoop","detail":"{readStateIndex:20775; appliedIndex:20774; }","duration":"255.666794ms","start":"2026-06-02T22:08:15.556981Z","end":"2026-06-02T22:08:15.812648Z","steps":["trace[1475335046] 'read index received' (duration: 255.431058ms)","trace[1475335046] 'applied index is now lower than readState.Index' (duration: 233.686µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:08:15.82244Z","caller":"traceutil/trace.go:171","msg":"trace[1672173785] transaction","detail":"{read_only:false; response_revision:20106; number_of_response:1; }","duration":"163.194387ms","start":"2026-06-02T22:08:15.659226Z","end":"2026-06-02T22:08:15.82242Z","steps":["trace[1672173785] 'process raft request' (duration: 162.880269ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:08:16.089882Z","caller":"traceutil/trace.go:171","msg":"trace[1513803392] transaction","detail":"{read_only:false; response_revision:20107; number_of_response:1; }","duration":"122.74913ms","start":"2026-06-02T22:08:15.967111Z","end":"2026-06-02T22:08:16.08986Z","steps":["trace[1513803392] 'process raft request' (duration: 122.595726ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:08:16.226165Z","caller":"traceutil/trace.go:171","msg":"trace[1474187421] linearizableReadLoop","detail":"{readStateIndex:20778; appliedIndex:20776; }","duration":"155.992994ms","start":"2026-06-02T22:08:16.070144Z","end":"2026-06-02T22:08:16.226137Z","steps":["trace[1474187421] 'read index received' (duration: 19.647592ms)","trace[1474187421] 'applied index is now lower than readState.Index' (duration: 136.344262ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:08:16.22624Z","caller":"traceutil/trace.go:171","msg":"trace[1610824052] transaction","detail":"{read_only:false; response_revision:20108; number_of_response:1; }","duration":"255.980024ms","start":"2026-06-02T22:08:15.970198Z","end":"2026-06-02T22:08:16.226178Z","steps":["trace[1610824052] 'process raft request' (duration: 206.299888ms)","trace[1610824052] 'compare' (duration: 49.497221ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:08:16.226381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.22497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:08:16.226456Z","caller":"traceutil/trace.go:171","msg":"trace[200074205] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:20108; }","duration":"156.359684ms","start":"2026-06-02T22:08:16.070079Z","end":"2026-06-02T22:08:16.226439Z","steps":["trace[200074205] 'agreement among raft nodes before linearized reading' (duration: 156.168219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:08:16.823044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.827345ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-02T22:08:16.82317Z","caller":"traceutil/trace.go:171","msg":"trace[217321593] linearizableReadLoop","detail":"{readStateIndex:20780; appliedIndex:20779; }","duration":"117.937005ms","start":"2026-06-02T22:08:16.70521Z","end":"2026-06-02T22:08:16.823147Z","steps":["trace[217321593] 'read index received' (duration: 90.493µs)","trace[217321593] 'applied index is now lower than readState.Index' (duration: 117.844362ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:08:16.823418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.210312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:08:16.823475Z","caller":"traceutil/trace.go:171","msg":"trace[1276223499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20109; }","duration":"118.325935ms","start":"2026-06-02T22:08:16.705134Z","end":"2026-06-02T22:08:16.82346Z","steps":["trace[1276223499] 'agreement among raft nodes before linearized reading' (duration: 118.170961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:08:16.823586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.372149ms","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-06-02T22:08:16.823694Z","caller":"traceutil/trace.go:171","msg":"trace[745726639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20109; }","duration":"101.506312ms","start":"2026-06-02T22:08:16.722167Z","end":"2026-06-02T22:08:16.823674Z","steps":["trace[745726639] 'agreement among raft nodes before linearized reading' (duration: 101.141182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:08:16.823608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.641639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:08:16.823857Z","caller":"traceutil/trace.go:171","msg":"trace[2143041354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20109; }","duration":"105.887716ms","start":"2026-06-02T22:08:16.717927Z","end":"2026-06-02T22:08:16.823815Z","steps":["trace[2143041354] 'agreement among raft nodes before linearized reading' (duration: 105.370522ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:08:38.219994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18152} {"level":"info","ts":"2026-06-02T22:08:38.336043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18152,"took":"112.557243ms","hash":2309055638,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":29569024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T22:08:38.336114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2309055638,"revision":18152,"compact-revision":15526} {"level":"info","ts":"2026-06-02T22:11:58.068066Z","caller":"traceutil/trace.go:171","msg":"trace[1144779292] transaction","detail":"{read_only:false; response_revision:22466; number_of_response:1; }","duration":"108.912391ms","start":"2026-06-02T22:11:57.95912Z","end":"2026-06-02T22:11:58.068032Z","steps":["trace[1144779292] 'process raft request' (duration: 108.728546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.041576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.332352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-06-02T22:12:14.041636Z","caller":"traceutil/trace.go:171","msg":"trace[2085374958] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22639; }","duration":"394.451205ms","start":"2026-06-02T22:12:13.647171Z","end":"2026-06-02T22:12:14.041623Z","steps":["trace[2085374958] 'range keys from in-memory index tree' (duration: 394.186387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.04166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:13.64715Z","time spent":"394.505006ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":649,"request content":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" "} {"level":"info","ts":"2026-06-02T22:12:14.48528Z","caller":"traceutil/trace.go:171","msg":"trace[61303150] linearizableReadLoop","detail":"{readStateIndex:23386; appliedIndex:23385; }","duration":"413.85301ms","start":"2026-06-02T22:12:14.071402Z","end":"2026-06-02T22:12:14.485255Z","steps":["trace[61303150] 'read index received' (duration: 412.826841ms)","trace[61303150] 'applied index is now lower than readState.Index' (duration: 1.024249ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:12:14.485365Z","caller":"traceutil/trace.go:171","msg":"trace[1082056955] transaction","detail":"{read_only:false; response_revision:22642; number_of_response:1; }","duration":"433.069239ms","start":"2026-06-02T22:12:14.052257Z","end":"2026-06-02T22:12:14.485326Z","steps":["trace[1082056955] 'process raft request' (duration: 432.076941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.485508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.087426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-02T22:12:14.485536Z","caller":"traceutil/trace.go:171","msg":"trace[1105873492] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22642; }","duration":"414.140177ms","start":"2026-06-02T22:12:14.071388Z","end":"2026-06-02T22:12:14.485528Z","steps":["trace[1105873492] 'agreement among raft nodes before linearized reading' (duration: 413.970953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.485573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:14.052244Z","time spent":"433.223034ms","remote":"127.0.0.1:60068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1057,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T22:12:14.485787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.669768ms","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-06-02T22:12:14.485833Z","caller":"traceutil/trace.go:171","msg":"trace[119429966] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:22642; }","duration":"379.758781ms","start":"2026-06-02T22:12:14.106063Z","end":"2026-06-02T22:12:14.485822Z","steps":["trace[119429966] 'agreement among raft nodes before linearized reading' (duration: 379.685519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.485854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:14.106033Z","time spent":"379.815072ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:12:14.486029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.930169ms","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-06-02T22:12:14.486115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:14.07138Z","time spent":"414.728585ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T22:12:14.486142Z","caller":"traceutil/trace.go:171","msg":"trace[1770842543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22642; }","duration":"331.055292ms","start":"2026-06-02T22:12:14.155048Z","end":"2026-06-02T22:12:14.486103Z","steps":["trace[1770842543] 'agreement among raft nodes before linearized reading' (duration: 330.792295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.486126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.39426ms","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-06-02T22:12:14.486171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.586864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"warn","ts":"2026-06-02T22:12:14.486202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:14.155035Z","time spent":"331.157315ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T22:12:14.486221Z","caller":"traceutil/trace.go:171","msg":"trace[718080041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22642; }","duration":"348.536534ms","start":"2026-06-02T22:12:14.137658Z","end":"2026-06-02T22:12:14.486195Z","steps":["trace[718080041] 'agreement among raft nodes before linearized reading' (duration: 348.221045ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:12:14.486261Z","caller":"traceutil/trace.go:171","msg":"trace[792358406] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:22642; }","duration":"164.703648ms","start":"2026-06-02T22:12:14.321534Z","end":"2026-06-02T22:12:14.486238Z","steps":["trace[792358406] 'agreement among raft nodes before linearized reading' (duration: 164.419409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.486269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:14.137647Z","time spent":"348.613027ms","remote":"127.0.0.1:60282","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-06-02T22:12:14.486223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.393986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-06-02T22:12:14.486421Z","caller":"traceutil/trace.go:171","msg":"trace[766636322] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:22642; }","duration":"355.654924ms","start":"2026-06-02T22:12:14.130715Z","end":"2026-06-02T22:12:14.486369Z","steps":["trace[766636322] 'agreement among raft nodes before linearized reading' (duration: 355.231721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:12:14.486478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:12:14.130684Z","time spent":"355.781038ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-06-02T22:13:38.225469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20222} {"level":"info","ts":"2026-06-02T22:13:38.326874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20222,"took":"98.403715ms","hash":1283838740,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":42082304,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-02T22:13:38.326949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1283838740,"revision":20222,"compact-revision":18152} {"level":"info","ts":"2026-06-02T22:15:09.278469Z","caller":"traceutil/trace.go:171","msg":"trace[2044640124] transaction","detail":"{read_only:false; response_revision:24450; number_of_response:1; }","duration":"112.712555ms","start":"2026-06-02T22:15:09.165729Z","end":"2026-06-02T22:15:09.278442Z","steps":["trace[2044640124] 'process raft request' (duration: 97.407826ms)","trace[2044640124] 'compare' (duration: 15.028631ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:09.547422Z","caller":"traceutil/trace.go:171","msg":"trace[1729233262] transaction","detail":"{read_only:false; response_revision:24451; number_of_response:1; }","duration":"261.827451ms","start":"2026-06-02T22:15:09.285566Z","end":"2026-06-02T22:15:09.547393Z","steps":["trace[1729233262] 'process raft request' (duration: 261.532873ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.547475Z","caller":"traceutil/trace.go:171","msg":"trace[251886949] linearizableReadLoop","detail":"{readStateIndex:25242; appliedIndex:25241; }","duration":"231.020597ms","start":"2026-06-02T22:15:09.31642Z","end":"2026-06-02T22:15:09.547441Z","steps":["trace[251886949] 'read index received' (duration: 230.608515ms)","trace[251886949] 'applied index is now lower than readState.Index' (duration: 406.822µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:09.54769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.248063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"warn","ts":"2026-06-02T22:15:09.547724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.551257ms","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-06-02T22:15:09.547736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.747517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:15:09.547768Z","caller":"traceutil/trace.go:171","msg":"trace[1723827086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24452; }","duration":"124.602578ms","start":"2026-06-02T22:15:09.423152Z","end":"2026-06-02T22:15:09.547755Z","steps":["trace[1723827086] 'agreement among raft nodes before linearized reading' (duration: 124.475635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:09.547771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.576023ms","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-06-02T22:15:09.547772Z","caller":"traceutil/trace.go:171","msg":"trace[1377458298] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:24452; }","duration":"231.370796ms","start":"2026-06-02T22:15:09.316379Z","end":"2026-06-02T22:15:09.54775Z","steps":["trace[1377458298] 'agreement among raft nodes before linearized reading' (duration: 231.179321ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.547785Z","caller":"traceutil/trace.go:171","msg":"trace[2121481051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24452; }","duration":"205.842411ms","start":"2026-06-02T22:15:09.341924Z","end":"2026-06-02T22:15:09.547766Z","steps":["trace[2121481051] 'agreement among raft nodes before linearized reading' (duration: 205.682235ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.547796Z","caller":"traceutil/trace.go:171","msg":"trace[840761798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24452; }","duration":"200.632554ms","start":"2026-06-02T22:15:09.347156Z","end":"2026-06-02T22:15:09.547789Z","steps":["trace[840761798] 'agreement among raft nodes before linearized reading' (duration: 200.559721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:09.547811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.742756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-06-02T22:15:09.547825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.679431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:15:09.54784Z","caller":"traceutil/trace.go:171","msg":"trace[1435670892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24452; }","duration":"123.798346ms","start":"2026-06-02T22:15:09.42403Z","end":"2026-06-02T22:15:09.547829Z","steps":["trace[1435670892] 'agreement among raft nodes before linearized reading' (duration: 123.667782ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.547866Z","caller":"traceutil/trace.go:171","msg":"trace[1535031049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24452; }","duration":"124.756283ms","start":"2026-06-02T22:15:09.423098Z","end":"2026-06-02T22:15:09.547854Z","steps":["trace[1535031049] 'agreement among raft nodes before linearized reading' (duration: 124.574497ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.755868Z","caller":"traceutil/trace.go:171","msg":"trace[1193826563] linearizableReadLoop","detail":"{readStateIndex:25249; appliedIndex:25243; }","duration":"182.733102ms","start":"2026-06-02T22:15:09.573112Z","end":"2026-06-02T22:15:09.755845Z","steps":["trace[1193826563] 'read index received' (duration: 112.296764ms)","trace[1193826563] 'applied index is now lower than readState.Index' (duration: 70.435268ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:09.756142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.014519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-06-02T22:15:09.756201Z","caller":"traceutil/trace.go:171","msg":"trace[191633264] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:24459; }","duration":"183.126992ms","start":"2026-06-02T22:15:09.573057Z","end":"2026-06-02T22:15:09.756184Z","steps":["trace[191633264] 'agreement among raft nodes before linearized reading' (duration: 182.887025ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.75662Z","caller":"traceutil/trace.go:171","msg":"trace[1674573627] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"202.394283ms","start":"2026-06-02T22:15:09.554205Z","end":"2026-06-02T22:15:09.756599Z","steps":["trace[1674573627] 'process raft request' (duration: 131.218924ms)","trace[1674573627] 'compare' (duration: 69.46023ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:09.756843Z","caller":"traceutil/trace.go:171","msg":"trace[1999282906] transaction","detail":"{read_only:false; response_revision:24454; number_of_response:1; }","duration":"201.976802ms","start":"2026-06-02T22:15:09.554852Z","end":"2026-06-02T22:15:09.756829Z","steps":["trace[1999282906] 'process raft request' (duration: 200.672966ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.757126Z","caller":"traceutil/trace.go:171","msg":"trace[590070028] transaction","detail":"{read_only:false; response_revision:24455; number_of_response:1; }","duration":"202.162937ms","start":"2026-06-02T22:15:09.554949Z","end":"2026-06-02T22:15:09.757112Z","steps":["trace[590070028] 'process raft request' (duration: 200.701966ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.757311Z","caller":"traceutil/trace.go:171","msg":"trace[1937088605] transaction","detail":"{read_only:false; response_revision:24456; number_of_response:1; }","duration":"201.985542ms","start":"2026-06-02T22:15:09.555312Z","end":"2026-06-02T22:15:09.757297Z","steps":["trace[1937088605] 'process raft request' (duration: 200.390908ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.757502Z","caller":"traceutil/trace.go:171","msg":"trace[2119609483] transaction","detail":"{read_only:false; response_revision:24457; number_of_response:1; }","duration":"202.147656ms","start":"2026-06-02T22:15:09.555339Z","end":"2026-06-02T22:15:09.757486Z","steps":["trace[2119609483] 'process raft request' (duration: 200.402188ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:09.757696Z","caller":"traceutil/trace.go:171","msg":"trace[1925671861] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"192.504275ms","start":"2026-06-02T22:15:09.565177Z","end":"2026-06-02T22:15:09.757681Z","steps":["trace[1925671861] 'process raft request' (duration: 190.593812ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:21.06954Z","caller":"traceutil/trace.go:171","msg":"trace[870765189] linearizableReadLoop","detail":"{readStateIndex:25346; appliedIndex:25345; }","duration":"226.298095ms","start":"2026-06-02T22:15:20.843221Z","end":"2026-06-02T22:15:21.069519Z","steps":["trace[870765189] 'read index received' (duration: 226.12561ms)","trace[870765189] 'applied index is now lower than readState.Index' (duration: 171.695µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:21.069642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.397237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:15:21.069628Z","caller":"traceutil/trace.go:171","msg":"trace[303200072] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"359.503685ms","start":"2026-06-02T22:15:20.710102Z","end":"2026-06-02T22:15:21.069606Z","steps":["trace[303200072] 'process raft request' (duration: 359.297599ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:21.069672Z","caller":"traceutil/trace.go:171","msg":"trace[1383931192] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24553; }","duration":"226.489849ms","start":"2026-06-02T22:15:20.843171Z","end":"2026-06-02T22:15:21.069661Z","steps":["trace[1383931192] 'agreement among raft nodes before linearized reading' (duration: 226.421818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:21.069863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:20.710077Z","time spent":"359.605738ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:26.189883Z","caller":"traceutil/trace.go:171","msg":"trace[2028197100] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"373.613341ms","start":"2026-06-02T22:15:25.816243Z","end":"2026-06-02T22:15:26.189856Z","steps":["trace[2028197100] 'process raft request' (duration: 373.430186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.19006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:25.816217Z","time spent":"373.748755ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:26.210075Z","caller":"traceutil/trace.go:171","msg":"trace[2006221926] linearizableReadLoop","detail":"{readStateIndex:25381; appliedIndex:25379; }","duration":"386.170184ms","start":"2026-06-02T22:15:25.823881Z","end":"2026-06-02T22:15:26.210051Z","steps":["trace[2006221926] 'read index received' (duration: 365.805222ms)","trace[2006221926] 'applied index is now lower than readState.Index' (duration: 20.363802ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:26.210122Z","caller":"traceutil/trace.go:171","msg":"trace[144411249] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"392.701157ms","start":"2026-06-02T22:15:25.817401Z","end":"2026-06-02T22:15:26.210102Z","steps":["trace[144411249] 'process raft request' (duration: 392.459951ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:26.210184Z","caller":"traceutil/trace.go:171","msg":"trace[1324354007] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"158.631184ms","start":"2026-06-02T22:15:26.051542Z","end":"2026-06-02T22:15:26.210173Z","steps":["trace[1324354007] 'process raft request' (duration: 158.46203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.210208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:25.817374Z","time spent":"392.78951ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:26.210517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.026935ms","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-06-02T22:15:26.210563Z","caller":"traceutil/trace.go:171","msg":"trace[2078937871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24588; }","duration":"378.093217ms","start":"2026-06-02T22:15:25.832458Z","end":"2026-06-02T22:15:26.210552Z","steps":["trace[2078937871] 'agreement among raft nodes before linearized reading' (duration: 377.929001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.210602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:25.832454Z","time spent":"378.138119ms","remote":"127.0.0.1:60282","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-06-02T22:15:26.210677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.506535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-02T22:15:26.210745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.853654ms","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-06-02T22:15:26.210776Z","caller":"traceutil/trace.go:171","msg":"trace[457061989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24588; }","duration":"386.915295ms","start":"2026-06-02T22:15:25.823852Z","end":"2026-06-02T22:15:26.210767Z","steps":["trace[457061989] 'agreement among raft nodes before linearized reading' (duration: 386.824612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.210811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:25.823842Z","time spent":"386.962927ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T22:15:26.210866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.623702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:15:26.210902Z","caller":"traceutil/trace.go:171","msg":"trace[1528406465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24588; }","duration":"378.670954ms","start":"2026-06-02T22:15:25.832223Z","end":"2026-06-02T22:15:26.210894Z","steps":["trace[1528406465] 'agreement among raft nodes before linearized reading' (duration: 378.574172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.21094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.589062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-06-02T22:15:26.210946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:25.832214Z","time spent":"378.723215ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T22:15:26.210975Z","caller":"traceutil/trace.go:171","msg":"trace[1203414525] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24588; }","duration":"148.653864ms","start":"2026-06-02T22:15:26.062312Z","end":"2026-06-02T22:15:26.210966Z","steps":["trace[1203414525] 'agreement among raft nodes before linearized reading' (duration: 148.573172ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:26.210769Z","caller":"traceutil/trace.go:171","msg":"trace[909755047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24588; }","duration":"380.625858ms","start":"2026-06-02T22:15:25.830115Z","end":"2026-06-02T22:15:26.210741Z","steps":["trace[909755047] 'agreement among raft nodes before linearized reading' (duration: 380.128413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.211066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:25.830107Z","time spent":"380.952778ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T22:15:26.211145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.824746ms","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-06-02T22:15:26.211204Z","caller":"traceutil/trace.go:171","msg":"trace[215617946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24588; }","duration":"379.890318ms","start":"2026-06-02T22:15:25.831296Z","end":"2026-06-02T22:15:26.211186Z","steps":["trace[215617946] 'agreement among raft nodes before linearized reading' (duration: 379.746654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.211244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:25.831288Z","time spent":"379.94835ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T22:15:26.373721Z","caller":"traceutil/trace.go:171","msg":"trace[1360977718] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"157.918055ms","start":"2026-06-02T22:15:26.215786Z","end":"2026-06-02T22:15:26.373704Z","steps":["trace[1360977718] 'process raft request' (duration: 157.843593ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:26.373864Z","caller":"traceutil/trace.go:171","msg":"trace[486426876] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"158.11418ms","start":"2026-06-02T22:15:26.21574Z","end":"2026-06-02T22:15:26.373854Z","steps":["trace[486426876] 'process raft request' (duration: 157.847763ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:26.3737Z","caller":"traceutil/trace.go:171","msg":"trace[1315970333] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"157.977227ms","start":"2026-06-02T22:15:26.215675Z","end":"2026-06-02T22:15:26.373653Z","steps":["trace[1315970333] 'process raft request' (duration: 100.474171ms)","trace[1315970333] 'compare' (duration: 57.347141ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:26.37401Z","caller":"traceutil/trace.go:171","msg":"trace[1571693467] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"156.779243ms","start":"2026-06-02T22:15:26.217203Z","end":"2026-06-02T22:15:26.373982Z","steps":["trace[1571693467] 'process raft request' (duration: 156.66225ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:26.374057Z","caller":"traceutil/trace.go:171","msg":"trace[158823118] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"157.852363ms","start":"2026-06-02T22:15:26.216191Z","end":"2026-06-02T22:15:26.374043Z","steps":["trace[158823118] 'process raft request' (duration: 157.474972ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:26.384796Z","caller":"traceutil/trace.go:171","msg":"trace[1167863320] linearizableReadLoop","detail":"{readStateIndex:25389; appliedIndex:25383; }","duration":"112.415497ms","start":"2026-06-02T22:15:26.272357Z","end":"2026-06-02T22:15:26.384772Z","steps":["trace[1167863320] 'read index received' (duration: 43.769609ms)","trace[1167863320] 'applied index is now lower than readState.Index' (duration: 68.644378ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:26.384876Z","caller":"traceutil/trace.go:171","msg":"trace[971611808] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"115.829633ms","start":"2026-06-02T22:15:26.269019Z","end":"2026-06-02T22:15:26.384849Z","steps":["trace[971611808] 'process raft request' (duration: 115.596727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:26.385017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.632903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-06-02T22:15:26.38508Z","caller":"traceutil/trace.go:171","msg":"trace[1353634842] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24595; }","duration":"112.753436ms","start":"2026-06-02T22:15:26.272312Z","end":"2026-06-02T22:15:26.385066Z","steps":["trace[1353634842] 'agreement among raft nodes before linearized reading' (duration: 112.557421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:27.42325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.940904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:27.423749Z","caller":"traceutil/trace.go:171","msg":"trace[902400839] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24596; }","duration":"122.48777ms","start":"2026-06-02T22:15:27.301236Z","end":"2026-06-02T22:15:27.423724Z","steps":["trace[902400839] 'count revisions from in-memory index tree' (duration: 121.842761ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:27.686042Z","caller":"traceutil/trace.go:171","msg":"trace[1093057440] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"276.521605ms","start":"2026-06-02T22:15:27.409494Z","end":"2026-06-02T22:15:27.686015Z","steps":["trace[1093057440] 'process raft request' (duration: 276.34556ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:27.922832Z","caller":"traceutil/trace.go:171","msg":"trace[598967322] linearizableReadLoop","detail":"{readStateIndex:25393; appliedIndex:25392; }","duration":"222.026134ms","start":"2026-06-02T22:15:27.700768Z","end":"2026-06-02T22:15:27.922794Z","steps":["trace[598967322] 'read index received' (duration: 183.321647ms)","trace[598967322] 'applied index is now lower than readState.Index' (duration: 38.702627ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:27.922951Z","caller":"traceutil/trace.go:171","msg":"trace[1296596609] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"229.992948ms","start":"2026-06-02T22:15:27.69293Z","end":"2026-06-02T22:15:27.922923Z","steps":["trace[1296596609] 'process raft request' (duration: 191.121586ms)","trace[1296596609] 'compare' (duration: 38.45545ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:27.923059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.264521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:27.923105Z","caller":"traceutil/trace.go:171","msg":"trace[1990731077] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24598; }","duration":"222.327403ms","start":"2026-06-02T22:15:27.70076Z","end":"2026-06-02T22:15:27.923087Z","steps":["trace[1990731077] 'agreement among raft nodes before linearized reading' (duration: 222.21904ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:28.40873Z","caller":"traceutil/trace.go:171","msg":"trace[1037999309] linearizableReadLoop","detail":"{readStateIndex:25396; appliedIndex:25394; }","duration":"154.633032ms","start":"2026-06-02T22:15:28.254077Z","end":"2026-06-02T22:15:28.40871Z","steps":["trace[1037999309] 'read index received' (duration: 132.098169ms)","trace[1037999309] 'applied index is now lower than readState.Index' (duration: 22.531773ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:28.409006Z","caller":"traceutil/trace.go:171","msg":"trace[254297608] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"193.262437ms","start":"2026-06-02T22:15:28.215713Z","end":"2026-06-02T22:15:28.408975Z","steps":["trace[254297608] 'process raft request' (duration: 192.944308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:28.409133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.141312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-02T22:15:28.409103Z","caller":"traceutil/trace.go:171","msg":"trace[142044681] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"193.639997ms","start":"2026-06-02T22:15:28.215395Z","end":"2026-06-02T22:15:28.409035Z","steps":["trace[142044681] 'process raft request' (duration: 170.875098ms)","trace[142044681] 'compare' (duration: 22.250295ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:28.409223Z","caller":"traceutil/trace.go:171","msg":"trace[135103738] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24601; }","duration":"183.261936ms","start":"2026-06-02T22:15:28.22594Z","end":"2026-06-02T22:15:28.409202Z","steps":["trace[135103738] 'agreement among raft nodes before linearized reading' (duration: 183.035549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:28.441006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.018908ms","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-06-02T22:15:28.441043Z","caller":"traceutil/trace.go:171","msg":"trace[160481360] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24602; }","duration":"184.09524ms","start":"2026-06-02T22:15:28.256938Z","end":"2026-06-02T22:15:28.441033Z","steps":["trace[160481360] 'agreement among raft nodes before linearized reading' (duration: 184.038229ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:28.590363Z","caller":"traceutil/trace.go:171","msg":"trace[1198838285] linearizableReadLoop","detail":"{readStateIndex:25398; appliedIndex:25397; }","duration":"149.516859ms","start":"2026-06-02T22:15:28.440823Z","end":"2026-06-02T22:15:28.590339Z","steps":["trace[1198838285] 'read index received' (duration: 136.672908ms)","trace[1198838285] 'applied index is now lower than readState.Index' (duration: 12.842671ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:28.590471Z","caller":"traceutil/trace.go:171","msg":"trace[1570378800] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"176.798045ms","start":"2026-06-02T22:15:28.413661Z","end":"2026-06-02T22:15:28.590459Z","steps":["trace[1570378800] 'process raft request' (duration: 163.675696ms)","trace[1570378800] 'compare' (duration: 12.7975ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:28.590621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.481773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:28.590656Z","caller":"traceutil/trace.go:171","msg":"trace[2126293036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24603; }","duration":"167.547925ms","start":"2026-06-02T22:15:28.4231Z","end":"2026-06-02T22:15:28.590647Z","steps":["trace[2126293036] 'agreement among raft nodes before linearized reading' (duration: 167.473493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:28.913399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.946681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:15:28.913805Z","caller":"traceutil/trace.go:171","msg":"trace[151518558] transaction","detail":"{read_only:false; response_revision:24604; number_of_response:1; }","duration":"468.819596ms","start":"2026-06-02T22:15:28.444955Z","end":"2026-06-02T22:15:28.913774Z","steps":["trace[151518558] 'process raft request' (duration: 292.394611ms)","trace[151518558] 'compare' (duration: 175.56355ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:28.913935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.444922Z","time spent":"468.942849ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:28.914157Z","caller":"traceutil/trace.go:171","msg":"trace[221672865] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"468.688852ms","start":"2026-06-02T22:15:28.44546Z","end":"2026-06-02T22:15:28.914149Z","steps":["trace[221672865] 'process raft request' (duration: 468.457656ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:28.914165Z","caller":"traceutil/trace.go:171","msg":"trace[656613655] linearizableReadLoop","detail":"{readStateIndex:25404; appliedIndex:25398; }","duration":"323.719581ms","start":"2026-06-02T22:15:28.590423Z","end":"2026-06-02T22:15:28.914143Z","steps":["trace[656613655] 'read index received' (duration: 146.902586ms)","trace[656613655] 'applied index is now lower than readState.Index' (duration: 176.815665ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:28.914209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.44545Z","time spent":"468.735933ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:28.91429Z","caller":"traceutil/trace.go:171","msg":"trace[304084225] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"377.231264ms","start":"2026-06-02T22:15:28.537043Z","end":"2026-06-02T22:15:28.914274Z","steps":["trace[304084225] 'process raft request' (duration: 377.016438ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:28.914326Z","caller":"traceutil/trace.go:171","msg":"trace[999239136] transaction","detail":"{read_only:false; response_revision:24608; number_of_response:1; }","duration":"468.686332ms","start":"2026-06-02T22:15:28.445596Z","end":"2026-06-02T22:15:28.914282Z","steps":["trace[999239136] 'process raft request' (duration: 468.414194ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:28.914399Z","caller":"traceutil/trace.go:171","msg":"trace[823436651] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"469.295679ms","start":"2026-06-02T22:15:28.445094Z","end":"2026-06-02T22:15:28.91439Z","steps":["trace[823436651] 'process raft request' (duration: 468.696012ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:28.914379Z","caller":"traceutil/trace.go:171","msg":"trace[1389725608] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"468.787965ms","start":"2026-06-02T22:15:28.445553Z","end":"2026-06-02T22:15:28.914341Z","steps":["trace[1389725608] 'process raft request' (duration: 468.413084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:28.914396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.64929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:15:28.914448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.445058Z","time spent":"469.367941ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:28.914428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.445585Z","time spent":"468.793236ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:28.914472Z","caller":"traceutil/trace.go:171","msg":"trace[1098054083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24609; }","duration":"322.770824ms","start":"2026-06-02T22:15:28.591683Z","end":"2026-06-02T22:15:28.914454Z","steps":["trace[1098054083] 'agreement among raft nodes before linearized reading' (duration: 322.63102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:28.914384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.53702Z","time spent":"377.310796ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:28.914518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.591662Z","time spent":"322.848386ms","remote":"127.0.0.1:60010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T22:15:28.914538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.445546Z","time spent":"468.905179ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:28.914648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.548856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-02T22:15:28.914731Z","caller":"traceutil/trace.go:171","msg":"trace[696588278] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24609; }","duration":"469.66877ms","start":"2026-06-02T22:15:28.445042Z","end":"2026-06-02T22:15:28.914711Z","steps":["trace[696588278] 'agreement among raft nodes before linearized reading' (duration: 469.402652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:28.91478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.445035Z","time spent":"469.736241ms","remote":"127.0.0.1:60190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-02T22:15:28.91515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.200899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-02T22:15:28.915422Z","caller":"traceutil/trace.go:171","msg":"trace[1166716061] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24609; }","duration":"301.488977ms","start":"2026-06-02T22:15:28.613734Z","end":"2026-06-02T22:15:28.915223Z","steps":["trace[1166716061] 'agreement among raft nodes before linearized reading' (duration: 300.706475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:28.915486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:28.613712Z","time spent":"301.758964ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-02T22:15:29.728755Z","caller":"traceutil/trace.go:171","msg":"trace[875332082] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"111.337416ms","start":"2026-06-02T22:15:29.617395Z","end":"2026-06-02T22:15:29.728733Z","steps":["trace[875332082] 'process raft request' (duration: 111.177592ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:29.921037Z","caller":"traceutil/trace.go:171","msg":"trace[1030258787] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"229.280789ms","start":"2026-06-02T22:15:29.691699Z","end":"2026-06-02T22:15:29.92098Z","steps":["trace[1030258787] 'process raft request' (duration: 134.420345ms)","trace[1030258787] 'compare' (duration: 94.737621ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:30.452824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.011339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:15:30.45294Z","caller":"traceutil/trace.go:171","msg":"trace[427613241] linearizableReadLoop","detail":"{readStateIndex:25411; appliedIndex:25410; }","duration":"136.039071ms","start":"2026-06-02T22:15:30.316885Z","end":"2026-06-02T22:15:30.452924Z","steps":["trace[427613241] 'read index received' (duration: 56.362µs)","trace[427613241] 'applied index is now lower than readState.Index' (duration: 135.981499ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:30.452998Z","caller":"traceutil/trace.go:171","msg":"trace[1587937281] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"193.113573ms","start":"2026-06-02T22:15:30.259845Z","end":"2026-06-02T22:15:30.452959Z","steps":["trace[1587937281] 'process raft request' (duration: 50.885869ms)","trace[1587937281] 'compare' (duration: 141.891925ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:30.453057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.155294ms","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-06-02T22:15:30.453147Z","caller":"traceutil/trace.go:171","msg":"trace[2023761623] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:24616; }","duration":"136.292137ms","start":"2026-06-02T22:15:30.316833Z","end":"2026-06-02T22:15:30.453125Z","steps":["trace[2023761623] 'agreement among raft nodes before linearized reading' (duration: 136.149484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:30.721271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.292958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:15:30.721355Z","caller":"traceutil/trace.go:171","msg":"trace[796525603] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"307.053062ms","start":"2026-06-02T22:15:30.414288Z","end":"2026-06-02T22:15:30.721341Z","steps":["trace[796525603] 'process raft request' (duration: 165.61906ms)","trace[796525603] 'compare' (duration: 141.195396ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:30.721398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:30.414264Z","time spent":"307.114214ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:30.7937Z","caller":"traceutil/trace.go:171","msg":"trace[654274334] linearizableReadLoop","detail":"{readStateIndex:25413; appliedIndex:25411; }","duration":"340.711218ms","start":"2026-06-02T22:15:30.452965Z","end":"2026-06-02T22:15:30.793676Z","steps":["trace[654274334] 'read index received' (duration: 126.913763ms)","trace[654274334] 'applied index is now lower than readState.Index' (duration: 213.796535ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:30.793767Z","caller":"traceutil/trace.go:171","msg":"trace[194359970] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"335.764319ms","start":"2026-06-02T22:15:30.457983Z","end":"2026-06-02T22:15:30.793748Z","steps":["trace[194359970] 'process raft request' (duration: 335.657736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:30.793836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.652977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:30.793867Z","caller":"traceutil/trace.go:171","msg":"trace[1198009222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24619; }","duration":"371.734049ms","start":"2026-06-02T22:15:30.422125Z","end":"2026-06-02T22:15:30.793859Z","steps":["trace[1198009222] 'agreement among raft nodes before linearized reading' (duration: 371.661267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:30.793878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:30.457959Z","time spent":"335.844491ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:30.793889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:30.422105Z","time spent":"371.779371ms","remote":"127.0.0.1:59996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T22:15:30.793903Z","caller":"traceutil/trace.go:171","msg":"trace[707272911] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"376.667338ms","start":"2026-06-02T22:15:30.417215Z","end":"2026-06-02T22:15:30.793883Z","steps":["trace[707272911] 'process raft request' (duration: 376.298447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:30.793977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:30.417196Z","time spent":"376.74091ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:30.79402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.896464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-02T22:15:30.794101Z","caller":"traceutil/trace.go:171","msg":"trace[1003553738] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24619; }","duration":"200.011257ms","start":"2026-06-02T22:15:30.594078Z","end":"2026-06-02T22:15:30.79409Z","steps":["trace[1003553738] 'agreement among raft nodes before linearized reading' (duration: 199.853023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:30.794025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.374646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:30.794154Z","caller":"traceutil/trace.go:171","msg":"trace[939298218] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24619; }","duration":"226.573492ms","start":"2026-06-02T22:15:30.567575Z","end":"2026-06-02T22:15:30.794148Z","steps":["trace[939298218] 'agreement among raft nodes before linearized reading' (duration: 226.354756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:30.795156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.506195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-02T22:15:30.795211Z","caller":"traceutil/trace.go:171","msg":"trace[1297133240] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24619; }","duration":"380.594048ms","start":"2026-06-02T22:15:30.414605Z","end":"2026-06-02T22:15:30.795199Z","steps":["trace[1297133240] 'agreement among raft nodes before linearized reading' (duration: 379.318223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:30.795245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:30.414593Z","time spent":"380.643119ms","remote":"127.0.0.1:60068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2696,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:15:31.181063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.782223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:15:31.181182Z","caller":"traceutil/trace.go:171","msg":"trace[1031261158] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"258.820258ms","start":"2026-06-02T22:15:30.922339Z","end":"2026-06-02T22:15:31.18116Z","steps":["trace[1031261158] 'process raft request' (duration: 117.84141ms)","trace[1031261158] 'compare' (duration: 140.503325ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:31.203628Z","caller":"traceutil/trace.go:171","msg":"trace[735318330] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"279.967852ms","start":"2026-06-02T22:15:30.923633Z","end":"2026-06-02T22:15:31.203601Z","steps":["trace[735318330] 'process raft request' (duration: 279.8994ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:31.203659Z","caller":"traceutil/trace.go:171","msg":"trace[162569514] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"280.443395ms","start":"2026-06-02T22:15:30.923172Z","end":"2026-06-02T22:15:31.203615Z","steps":["trace[162569514] 'process raft request' (duration: 280.117446ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:31.203894Z","caller":"traceutil/trace.go:171","msg":"trace[806869558] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"280.469065ms","start":"2026-06-02T22:15:30.923409Z","end":"2026-06-02T22:15:31.203878Z","steps":["trace[806869558] 'process raft request' (duration: 280.081615ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:31.203966Z","caller":"traceutil/trace.go:171","msg":"trace[214442074] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"280.252649ms","start":"2026-06-02T22:15:30.923698Z","end":"2026-06-02T22:15:31.203951Z","steps":["trace[214442074] 'process raft request' (duration: 279.867309ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:31.204192Z","caller":"traceutil/trace.go:171","msg":"trace[26333615] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"280.459656ms","start":"2026-06-02T22:15:30.92372Z","end":"2026-06-02T22:15:31.20418Z","steps":["trace[26333615] 'process raft request' (duration: 280.157467ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:31.333132Z","caller":"traceutil/trace.go:171","msg":"trace[57468506] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"148.863ms","start":"2026-06-02T22:15:31.184239Z","end":"2026-06-02T22:15:31.333102Z","steps":["trace[57468506] 'process raft request' (duration: 147.277846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.191434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.379825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:37.191527Z","caller":"traceutil/trace.go:171","msg":"trace[1876321850] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24666; }","duration":"107.503139ms","start":"2026-06-02T22:15:37.084003Z","end":"2026-06-02T22:15:37.191506Z","steps":["trace[1876321850] 'count revisions from in-memory index tree' (duration: 107.318364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.191512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.075334ms","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":"warn","ts":"2026-06-02T22:15:37.191529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.736591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-02T22:15:37.191588Z","caller":"traceutil/trace.go:171","msg":"trace[1372781829] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24666; }","duration":"150.195857ms","start":"2026-06-02T22:15:37.041372Z","end":"2026-06-02T22:15:37.191568Z","steps":["trace[1372781829] 'count revisions from in-memory index tree' (duration: 149.985472ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:37.191634Z","caller":"traceutil/trace.go:171","msg":"trace[1811015003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24666; }","duration":"266.902565ms","start":"2026-06-02T22:15:36.924699Z","end":"2026-06-02T22:15:37.191602Z","steps":["trace[1811015003] 'range keys from in-memory index tree' (duration: 266.418821ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:37.457336Z","caller":"traceutil/trace.go:171","msg":"trace[965084232] linearizableReadLoop","detail":"{readStateIndex:25465; appliedIndex:25464; }","duration":"247.1485ms","start":"2026-06-02T22:15:37.210163Z","end":"2026-06-02T22:15:37.457312Z","steps":["trace[965084232] 'read index received' (duration: 246.983256ms)","trace[965084232] 'applied index is now lower than readState.Index' (duration: 164.144µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:37.457416Z","caller":"traceutil/trace.go:171","msg":"trace[2017601322] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"261.162753ms","start":"2026-06-02T22:15:37.196229Z","end":"2026-06-02T22:15:37.457392Z","steps":["trace[2017601322] 'process raft request' (duration: 260.933237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.45755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.358215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:37.457639Z","caller":"traceutil/trace.go:171","msg":"trace[1975572148] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24667; }","duration":"247.49945ms","start":"2026-06-02T22:15:37.21012Z","end":"2026-06-02T22:15:37.45762Z","steps":["trace[1975572148] 'agreement among raft nodes before linearized reading' (duration: 247.320965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.989674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.58713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:15:37.989847Z","caller":"traceutil/trace.go:171","msg":"trace[1634365610] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"628.420026ms","start":"2026-06-02T22:15:37.361384Z","end":"2026-06-02T22:15:37.989804Z","steps":["trace[1634365610] 'process raft request' (duration: 342.61167ms)","trace[1634365610] 'compare' (duration: 285.183299ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:37.989957Z","caller":"traceutil/trace.go:171","msg":"trace[923394167] linearizableReadLoop","detail":"{readStateIndex:25466; appliedIndex:25465; }","duration":"532.521864ms","start":"2026-06-02T22:15:37.457401Z","end":"2026-06-02T22:15:37.989922Z","steps":["trace[923394167] 'read index received' (duration: 246.611795ms)","trace[923394167] 'applied index is now lower than readState.Index' (duration: 285.907298ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:37.990023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.361363Z","time spent":"628.55695ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:37.990205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.115989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-02T22:15:37.990237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.682291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:15:37.990405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.916716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-02T22:15:37.990423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.272482ms","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-06-02T22:15:37.990468Z","caller":"traceutil/trace.go:171","msg":"trace[548862113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24669; }","duration":"761.993578ms","start":"2026-06-02T22:15:37.228457Z","end":"2026-06-02T22:15:37.990451Z","steps":["trace[548862113] 'agreement among raft nodes before linearized reading' (duration: 761.838994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.990237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.932397ms","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-06-02T22:15:37.990575Z","caller":"traceutil/trace.go:171","msg":"trace[574353472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24669; }","duration":"764.883979ms","start":"2026-06-02T22:15:37.225668Z","end":"2026-06-02T22:15:37.990552Z","steps":["trace[574353472] 'agreement among raft nodes before linearized reading' (duration: 764.391574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.990251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.616857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:15:37.990637Z","caller":"traceutil/trace.go:171","msg":"trace[1628877074] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24669; }","duration":"621.03768ms","start":"2026-06-02T22:15:37.369584Z","end":"2026-06-02T22:15:37.990622Z","steps":["trace[1628877074] 'agreement among raft nodes before linearized reading' (duration: 620.600377ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:37.990652Z","caller":"traceutil/trace.go:171","msg":"trace[769501982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24669; }","duration":"761.34169ms","start":"2026-06-02T22:15:37.229252Z","end":"2026-06-02T22:15:37.990594Z","steps":["trace[769501982] 'agreement among raft nodes before linearized reading' (duration: 760.752023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.990691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.369569Z","time spent":"621.106241ms","remote":"127.0.0.1:59144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:15:37.990342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.592881ms","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":"warn","ts":"2026-06-02T22:15:37.990753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.22924Z","time spent":"761.482004ms","remote":"127.0.0.1:60282","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":"info","ts":"2026-06-02T22:15:37.990817Z","caller":"traceutil/trace.go:171","msg":"trace[1273389955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24669; }","duration":"765.047533ms","start":"2026-06-02T22:15:37.2257Z","end":"2026-06-02T22:15:37.990748Z","steps":["trace[1273389955] 'agreement among raft nodes before linearized reading' (duration: 764.399674ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:37.990285Z","caller":"traceutil/trace.go:171","msg":"trace[1535680410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24669; }","duration":"763.223153ms","start":"2026-06-02T22:15:37.227038Z","end":"2026-06-02T22:15:37.990262Z","steps":["trace[1535680410] 'agreement among raft nodes before linearized reading' (duration: 762.961535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.990885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.225679Z","time spent":"765.190078ms","remote":"127.0.0.1:60282","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-06-02T22:15:37.990906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.227025Z","time spent":"763.86001ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T22:15:37.990589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.228446Z","time spent":"762.125202ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T22:15:37.990642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.225646Z","time spent":"764.984142ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T22:15:37.991331Z","caller":"traceutil/trace.go:171","msg":"trace[1162681107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24669; }","duration":"567.862897ms","start":"2026-06-02T22:15:37.422513Z","end":"2026-06-02T22:15:37.990376Z","steps":["trace[1162681107] 'agreement among raft nodes before linearized reading' (duration: 567.65845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:37.991434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.422502Z","time spent":"568.915146ms","remote":"127.0.0.1:60010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T22:15:38.446023Z","caller":"traceutil/trace.go:171","msg":"trace[855916644] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"453.173116ms","start":"2026-06-02T22:15:37.992825Z","end":"2026-06-02T22:15:38.445998Z","steps":["trace[855916644] 'process raft request' (duration: 453.021251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:38.446195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.9928Z","time spent":"453.31877ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:38.57435Z","caller":"traceutil/trace.go:171","msg":"trace[217701433] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"580.12701ms","start":"2026-06-02T22:15:37.994198Z","end":"2026-06-02T22:15:38.574325Z","steps":["trace[217701433] 'process raft request' (duration: 569.161333ms)","trace[217701433] 'compare' (duration: 10.803713ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:38.574493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.994189Z","time spent":"580.225853ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:38.574594Z","caller":"traceutil/trace.go:171","msg":"trace[1183344936] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"580.157411ms","start":"2026-06-02T22:15:37.994412Z","end":"2026-06-02T22:15:38.57457Z","steps":["trace[1183344936] 'process raft request' (duration: 579.877643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:38.574764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.994406Z","time spent":"580.249544ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:38.574849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.805855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:38.574777Z","caller":"traceutil/trace.go:171","msg":"trace[415037780] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"579.600266ms","start":"2026-06-02T22:15:37.995168Z","end":"2026-06-02T22:15:38.574768Z","steps":["trace[415037780] 'process raft request' (duration: 579.310378ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:38.574885Z","caller":"traceutil/trace.go:171","msg":"trace[573153240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24675; }","duration":"150.862337ms","start":"2026-06-02T22:15:38.424014Z","end":"2026-06-02T22:15:38.574876Z","steps":["trace[573153240] 'agreement among raft nodes before linearized reading' (duration: 150.786225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:38.574912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.995161Z","time spent":"579.724709ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:38.574632Z","caller":"traceutil/trace.go:171","msg":"trace[508854521] linearizableReadLoop","detail":"{readStateIndex:25473; appliedIndex:25467; }","duration":"150.582468ms","start":"2026-06-02T22:15:38.424032Z","end":"2026-06-02T22:15:38.574614Z","steps":["trace[508854521] 'read index received' (duration: 21.764801ms)","trace[508854521] 'applied index is now lower than readState.Index' (duration: 128.816907ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:38.575032Z","caller":"traceutil/trace.go:171","msg":"trace[7947593] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"579.812242ms","start":"2026-06-02T22:15:37.995212Z","end":"2026-06-02T22:15:38.575024Z","steps":["trace[7947593] 'process raft request' (duration: 579.346459ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:38.575055Z","caller":"traceutil/trace.go:171","msg":"trace[1396614486] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"579.143582ms","start":"2026-06-02T22:15:37.995901Z","end":"2026-06-02T22:15:38.575045Z","steps":["trace[1396614486] 'process raft request' (duration: 578.68297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:38.575102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.995893Z","time spent":"579.185073ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:38.57511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:37.995196Z","time spent":"579.868263ms","remote":"127.0.0.1:60282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:15:45.138905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.930409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cae63133-228f-41a9-87bb-9bea253f98e0\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-02T22:15:45.139001Z","caller":"traceutil/trace.go:171","msg":"trace[1465029700] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cae63133-228f-41a9-87bb-9bea253f98e0; range_end:; response_count:1; response_revision:24784; }","duration":"255.059913ms","start":"2026-06-02T22:15:44.88392Z","end":"2026-06-02T22:15:45.13898Z","steps":["trace[1465029700] 'range keys from in-memory index tree' (duration: 254.787824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:45.139271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.716615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:45.139311Z","caller":"traceutil/trace.go:171","msg":"trace[789365729] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24784; }","duration":"253.776686ms","start":"2026-06-02T22:15:44.885523Z","end":"2026-06-02T22:15:45.1393Z","steps":["trace[789365729] 'range keys from in-memory index tree' (duration: 253.680673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:45.596596Z","caller":"traceutil/trace.go:171","msg":"trace[1299609098] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"103.377893ms","start":"2026-06-02T22:15:45.493193Z","end":"2026-06-02T22:15:45.596571Z","steps":["trace[1299609098] 'process raft request' (duration: 103.220749ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:45.899459Z","caller":"traceutil/trace.go:171","msg":"trace[1023742907] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"155.991311ms","start":"2026-06-02T22:15:45.743445Z","end":"2026-06-02T22:15:45.899436Z","steps":["trace[1023742907] 'process raft request' (duration: 155.813796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:46.173557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.050894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:15:46.173768Z","caller":"traceutil/trace.go:171","msg":"trace[1770642495] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"153.623694ms","start":"2026-06-02T22:15:46.020126Z","end":"2026-06-02T22:15:46.17375Z","steps":["trace[1770642495] 'process raft request' (duration: 153.538802ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:46.17377Z","caller":"traceutil/trace.go:171","msg":"trace[1083248480] transaction","detail":"{read_only:false; response_revision:24787; number_of_response:1; }","duration":"253.809458ms","start":"2026-06-02T22:15:45.919929Z","end":"2026-06-02T22:15:46.173739Z","steps":["trace[1083248480] 'process raft request' (duration: 92.434446ms)","trace[1083248480] 'compare' (duration: 160.886428ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:46.173845Z","caller":"traceutil/trace.go:171","msg":"trace[451421388] linearizableReadLoop","detail":"{readStateIndex:25586; appliedIndex:25585; }","duration":"249.567728ms","start":"2026-06-02T22:15:45.924246Z","end":"2026-06-02T22:15:46.173814Z","steps":["trace[451421388] 'read index received' (duration: 88.031001ms)","trace[451421388] 'applied index is now lower than readState.Index' (duration: 161.532987ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:46.174142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.859197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-06-02T22:15:46.174198Z","caller":"traceutil/trace.go:171","msg":"trace[355745566] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24788; }","duration":"249.9762ms","start":"2026-06-02T22:15:45.924203Z","end":"2026-06-02T22:15:46.174179Z","steps":["trace[355745566] 'agreement among raft nodes before linearized reading' (duration: 249.735203ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:46.211979Z","caller":"traceutil/trace.go:171","msg":"trace[2140090891] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"189.377568ms","start":"2026-06-02T22:15:46.022561Z","end":"2026-06-02T22:15:46.211939Z","steps":["trace[2140090891] 'process raft request' (duration: 189.217743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:46.212113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.864778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b5636c26e39859\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-06-02T22:15:46.212173Z","caller":"traceutil/trace.go:171","msg":"trace[140747241] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b5636c26e39859; range_end:; response_count:1; response_revision:24789; }","duration":"102.980071ms","start":"2026-06-02T22:15:46.109178Z","end":"2026-06-02T22:15:46.212158Z","steps":["trace[140747241] 'agreement among raft nodes before linearized reading' (duration: 102.760285ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:46.349824Z","caller":"traceutil/trace.go:171","msg":"trace[139595030] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"144.451287ms","start":"2026-06-02T22:15:46.205358Z","end":"2026-06-02T22:15:46.349809Z","steps":["trace[139595030] 'process raft request' (duration: 144.354494ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:46.349932Z","caller":"traceutil/trace.go:171","msg":"trace[1885752400] linearizableReadLoop","detail":"{readStateIndex:25589; appliedIndex:25589; }","duration":"137.994466ms","start":"2026-06-02T22:15:46.211906Z","end":"2026-06-02T22:15:46.3499Z","steps":["trace[1885752400] 'read index received' (duration: 137.976285ms)","trace[1885752400] 'applied index is now lower than readState.Index' (duration: 15.491µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:46.350116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.6221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b5636c2a9f730d\" ","response":"range_response_count:1 size:736"} {"level":"warn","ts":"2026-06-02T22:15:46.350169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.699772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-06-02T22:15:46.350194Z","caller":"traceutil/trace.go:171","msg":"trace[2131251832] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b5636c2a9f730d; range_end:; response_count:1; response_revision:24790; }","duration":"171.729173ms","start":"2026-06-02T22:15:46.178443Z","end":"2026-06-02T22:15:46.350172Z","steps":["trace[2131251832] 'agreement among raft nodes before linearized reading' (duration: 171.490457ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:46.350212Z","caller":"traceutil/trace.go:171","msg":"trace[1455633674] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24790; }","duration":"171.779875ms","start":"2026-06-02T22:15:46.178416Z","end":"2026-06-02T22:15:46.350196Z","steps":["trace[1455633674] 'agreement among raft nodes before linearized reading' (duration: 171.59821ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:46.351461Z","caller":"traceutil/trace.go:171","msg":"trace[1146189424] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"137.11966ms","start":"2026-06-02T22:15:46.214323Z","end":"2026-06-02T22:15:46.351442Z","steps":["trace[1146189424] 'process raft request' (duration: 136.944906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:51.17892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.839866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:15:51.179037Z","caller":"traceutil/trace.go:171","msg":"trace[1863036018] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24855; }","duration":"192.019381ms","start":"2026-06-02T22:15:50.98699Z","end":"2026-06-02T22:15:51.179009Z","steps":["trace[1863036018] 'count revisions from in-memory index tree' (duration: 191.732584ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:51.588742Z","caller":"traceutil/trace.go:171","msg":"trace[1930498352] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"121.929964ms","start":"2026-06-02T22:15:51.466761Z","end":"2026-06-02T22:15:51.588691Z","steps":["trace[1930498352] 'process raft request' (duration: 77.470825ms)","trace[1930498352] 'compare' (duration: 44.057538ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:10.878245Z","caller":"traceutil/trace.go:171","msg":"trace[1410633581] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"131.353598ms","start":"2026-06-02T22:16:10.746869Z","end":"2026-06-02T22:16:10.878223Z","steps":["trace[1410633581] 'process raft request' (duration: 131.221165ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:10.920396Z","caller":"traceutil/trace.go:171","msg":"trace[1735237070] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"173.267755ms","start":"2026-06-02T22:16:10.747111Z","end":"2026-06-02T22:16:10.920379Z","steps":["trace[1735237070] 'process raft request' (duration: 173.209933ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:10.920436Z","caller":"traceutil/trace.go:171","msg":"trace[2142458305] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"173.322556ms","start":"2026-06-02T22:16:10.747095Z","end":"2026-06-02T22:16:10.920418Z","steps":["trace[2142458305] 'process raft request' (duration: 173.081039ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:10.920811Z","caller":"traceutil/trace.go:171","msg":"trace[1522707272] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"173.670876ms","start":"2026-06-02T22:16:10.747132Z","end":"2026-06-02T22:16:10.920803Z","steps":["trace[1522707272] 'process raft request' (duration: 173.215673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:10.920966Z","caller":"traceutil/trace.go:171","msg":"trace[1118764181] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"173.78734ms","start":"2026-06-02T22:16:10.74717Z","end":"2026-06-02T22:16:10.920958Z","steps":["trace[1118764181] 'process raft request' (duration: 173.525913ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:11.120397Z","caller":"traceutil/trace.go:171","msg":"trace[282298998] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"194.564074ms","start":"2026-06-02T22:16:10.925813Z","end":"2026-06-02T22:16:11.120377Z","steps":["trace[282298998] 'process raft request' (duration: 142.304836ms)","trace[282298998] 'compare' (duration: 51.951509ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:11.136318Z","caller":"traceutil/trace.go:171","msg":"trace[1418865634] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"209.495043ms","start":"2026-06-02T22:16:10.926804Z","end":"2026-06-02T22:16:11.136299Z","steps":["trace[1418865634] 'process raft request' (duration: 209.388669ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:11.411644Z","caller":"traceutil/trace.go:171","msg":"trace[660756339] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"119.964809ms","start":"2026-06-02T22:16:11.291659Z","end":"2026-06-02T22:16:11.411624Z","steps":["trace[660756339] 'process raft request' (duration: 119.849706ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:11.523109Z","caller":"traceutil/trace.go:171","msg":"trace[238542042] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"101.46276ms","start":"2026-06-02T22:16:11.421605Z","end":"2026-06-02T22:16:11.523067Z","steps":["trace[238542042] 'process raft request' (duration: 101.250763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:11.676671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.048271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-06-02T22:16:11.676758Z","caller":"traceutil/trace.go:171","msg":"trace[686827254] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25022; }","duration":"115.358989ms","start":"2026-06-02T22:16:11.561378Z","end":"2026-06-02T22:16:11.676737Z","steps":["trace[686827254] 'range keys from in-memory index tree' (duration: 114.895616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:11.676783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.352997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:16:11.676884Z","caller":"traceutil/trace.go:171","msg":"trace[1903083976] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25022; }","duration":"116.509561ms","start":"2026-06-02T22:16:11.560356Z","end":"2026-06-02T22:16:11.676866Z","steps":["trace[1903083976] 'range keys from in-memory index tree' (duration: 116.003037ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:12.890008Z","caller":"traceutil/trace.go:171","msg":"trace[113452206] linearizableReadLoop","detail":"{readStateIndex:25832; appliedIndex:25831; }","duration":"125.631078ms","start":"2026-06-02T22:16:12.764316Z","end":"2026-06-02T22:16:12.889947Z","steps":["trace[113452206] 'read index received' (duration: 54.282224ms)","trace[113452206] 'applied index is now lower than readState.Index' (duration: 71.347014ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:16:12.890307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.948617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:16:12.890418Z","caller":"traceutil/trace.go:171","msg":"trace[1516954426] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25027; }","duration":"126.118992ms","start":"2026-06-02T22:16:12.764269Z","end":"2026-06-02T22:16:12.890388Z","steps":["trace[1516954426] 'agreement among raft nodes before linearized reading' (duration: 125.920417ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:12.890579Z","caller":"traceutil/trace.go:171","msg":"trace[306511057] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"141.439353ms","start":"2026-06-02T22:16:12.74908Z","end":"2026-06-02T22:16:12.890519Z","steps":["trace[306511057] 'process raft request' (duration: 69.553814ms)","trace[306511057] 'compare' (duration: 70.854929ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:13.161387Z","caller":"traceutil/trace.go:171","msg":"trace[123283135] linearizableReadLoop","detail":"{readStateIndex:25834; appliedIndex:25833; }","duration":"188.834953ms","start":"2026-06-02T22:16:12.972532Z","end":"2026-06-02T22:16:13.161367Z","steps":["trace[123283135] 'read index received' (duration: 188.689229ms)","trace[123283135] 'applied index is now lower than readState.Index' (duration: 144.394µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:13.161434Z","caller":"traceutil/trace.go:171","msg":"trace[1646814637] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"236.579513ms","start":"2026-06-02T22:16:12.924831Z","end":"2026-06-02T22:16:13.16141Z","steps":["trace[1646814637] 'process raft request' (duration: 236.384857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:13.16158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.775789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:16:13.16162Z","caller":"traceutil/trace.go:171","msg":"trace[1062520262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25029; }","duration":"236.85886ms","start":"2026-06-02T22:16:12.924748Z","end":"2026-06-02T22:16:13.161607Z","steps":["trace[1062520262] 'agreement among raft nodes before linearized reading' (duration: 236.708116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:13.161659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.82957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-02T22:16:13.161719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.604883ms","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-06-02T22:16:13.161752Z","caller":"traceutil/trace.go:171","msg":"trace[105330602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25029; }","duration":"232.662814ms","start":"2026-06-02T22:16:12.929079Z","end":"2026-06-02T22:16:13.161742Z","steps":["trace[105330602] 'agreement among raft nodes before linearized reading' (duration: 232.533829ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:13.161742Z","caller":"traceutil/trace.go:171","msg":"trace[1059507928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25029; }","duration":"236.966305ms","start":"2026-06-02T22:16:12.924755Z","end":"2026-06-02T22:16:13.161722Z","steps":["trace[1059507928] 'agreement among raft nodes before linearized reading' (duration: 236.698176ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:13.171233Z","caller":"traceutil/trace.go:171","msg":"trace[84308904] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"194.799811ms","start":"2026-06-02T22:16:12.976414Z","end":"2026-06-02T22:16:13.171214Z","steps":["trace[84308904] 'process raft request' (duration: 194.602215ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:14.868409Z","caller":"traceutil/trace.go:171","msg":"trace[47234684] linearizableReadLoop","detail":"{readStateIndex:25847; appliedIndex:25846; }","duration":"105.499023ms","start":"2026-06-02T22:16:14.762878Z","end":"2026-06-02T22:16:14.868377Z","steps":["trace[47234684] 'read index received' (duration: 36.069373ms)","trace[47234684] 'applied index is now lower than readState.Index' (duration: 69.42813ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:14.86862Z","caller":"traceutil/trace.go:171","msg":"trace[258119674] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"113.606621ms","start":"2026-06-02T22:16:14.754982Z","end":"2026-06-02T22:16:14.868588Z","steps":["trace[258119674] 'process raft request' (duration: 44.022987ms)","trace[258119674] 'compare' (duration: 69.205453ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:16:14.868794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.889754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-06-02T22:16:14.868877Z","caller":"traceutil/trace.go:171","msg":"trace[81632447] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25042; }","duration":"105.986946ms","start":"2026-06-02T22:16:14.762868Z","end":"2026-06-02T22:16:14.868855Z","steps":["trace[81632447] 'agreement among raft nodes before linearized reading' (duration: 105.714459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:29.875657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.781962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:16:29.875795Z","caller":"traceutil/trace.go:171","msg":"trace[462851002] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"227.871858ms","start":"2026-06-02T22:16:29.647896Z","end":"2026-06-02T22:16:29.875768Z","steps":["trace[462851002] 'process raft request' (duration: 75.87547ms)","trace[462851002] 'compare' (duration: 151.476303ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:29.882978Z","caller":"traceutil/trace.go:171","msg":"trace[1290744868] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"234.411491ms","start":"2026-06-02T22:16:29.648556Z","end":"2026-06-02T22:16:29.882967Z","steps":["trace[1290744868] 'process raft request' (duration: 234.282038ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:29.883103Z","caller":"traceutil/trace.go:171","msg":"trace[18322327] linearizableReadLoop","detail":"{readStateIndex:25970; appliedIndex:25967; }","duration":"232.870489ms","start":"2026-06-02T22:16:29.650212Z","end":"2026-06-02T22:16:29.883082Z","steps":["trace[18322327] 'read index received' (duration: 73.506284ms)","trace[18322327] 'applied index is now lower than readState.Index' (duration: 159.362884ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:29.883428Z","caller":"traceutil/trace.go:171","msg":"trace[1985187493] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"233.263251ms","start":"2026-06-02T22:16:29.650153Z","end":"2026-06-02T22:16:29.883416Z","steps":["trace[1985187493] 'process raft request' (duration: 232.769726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:29.883592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.360464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:16:29.883637Z","caller":"traceutil/trace.go:171","msg":"trace[2059438252] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25162; }","duration":"233.450487ms","start":"2026-06-02T22:16:29.650172Z","end":"2026-06-02T22:16:29.883623Z","steps":["trace[2059438252] 'agreement among raft nodes before linearized reading' (duration: 233.365364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:30.110382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.506809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:16:30.110479Z","caller":"traceutil/trace.go:171","msg":"trace[1251693445] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25971; }","duration":"159.324415ms","start":"2026-06-02T22:16:29.951137Z","end":"2026-06-02T22:16:30.110462Z","steps":["trace[1251693445] 'read index received' (duration: 56.60258ms)","trace[1251693445] 'applied index is now lower than readState.Index' (duration: 102.720355ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:30.110639Z","caller":"traceutil/trace.go:171","msg":"trace[1892337954] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"220.242535ms","start":"2026-06-02T22:16:29.890362Z","end":"2026-06-02T22:16:30.110604Z","steps":["trace[1892337954] 'process raft request' (duration: 117.409157ms)","trace[1892337954] 'compare' (duration: 102.118847ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:16:30.110972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.835338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-02T22:16:30.111021Z","caller":"traceutil/trace.go:171","msg":"trace[335497054] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25164; }","duration":"159.922011ms","start":"2026-06-02T22:16:29.951085Z","end":"2026-06-02T22:16:30.111007Z","steps":["trace[335497054] 'agreement among raft nodes before linearized reading' (duration: 159.509209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:39.250884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.022575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:16:39.251012Z","caller":"traceutil/trace.go:171","msg":"trace[1447889310] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"220.313266ms","start":"2026-06-02T22:16:39.030678Z","end":"2026-06-02T22:16:39.250991Z","steps":["trace[1447889310] 'process raft request' (duration: 103.086345ms)","trace[1447889310] 'compare' (duration: 116.639665ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:39.256812Z","caller":"traceutil/trace.go:171","msg":"trace[47838316] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"212.668772ms","start":"2026-06-02T22:16:39.044115Z","end":"2026-06-02T22:16:39.256784Z","steps":["trace[47838316] 'process raft request' (duration: 212.425434ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:39.512626Z","caller":"traceutil/trace.go:171","msg":"trace[510403289] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"251.665217ms","start":"2026-06-02T22:16:39.260916Z","end":"2026-06-02T22:16:39.512582Z","steps":["trace[510403289] 'process raft request' (duration: 183.38334ms)","trace[510403289] 'compare' (duration: 67.939218ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:39.519039Z","caller":"traceutil/trace.go:171","msg":"trace[29194575] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"173.740169ms","start":"2026-06-02T22:16:39.345275Z","end":"2026-06-02T22:16:39.519015Z","steps":["trace[29194575] 'process raft request' (duration: 173.545113ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:39.519276Z","caller":"traceutil/trace.go:171","msg":"trace[223280462] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"173.123152ms","start":"2026-06-02T22:16:39.346125Z","end":"2026-06-02T22:16:39.519248Z","steps":["trace[223280462] 'process raft request' (duration: 172.843584ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:39.519399Z","caller":"traceutil/trace.go:171","msg":"trace[338820905] linearizableReadLoop","detail":"{readStateIndex:26044; appliedIndex:26040; }","duration":"138.693064ms","start":"2026-06-02T22:16:39.38062Z","end":"2026-06-02T22:16:39.519313Z","steps":["trace[338820905] 'read index received' (duration: 63.698759ms)","trace[338820905] 'applied index is now lower than readState.Index' (duration: 74.992535ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:39.519425Z","caller":"traceutil/trace.go:171","msg":"trace[371048149] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"173.007567ms","start":"2026-06-02T22:16:39.346386Z","end":"2026-06-02T22:16:39.519394Z","steps":["trace[371048149] 'process raft request' (duration: 172.773411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:39.519724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.879643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:16:39.519772Z","caller":"traceutil/trace.go:171","msg":"trace[2125582618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25233; }","duration":"131.969095ms","start":"2026-06-02T22:16:39.387788Z","end":"2026-06-02T22:16:39.519757Z","steps":["trace[2125582618] 'agreement among raft nodes before linearized reading' (duration: 131.77743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:39.519772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.727213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-02T22:16:39.519771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.138707ms","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-06-02T22:16:39.519814Z","caller":"traceutil/trace.go:171","msg":"trace[1203245474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25233; }","duration":"129.842606ms","start":"2026-06-02T22:16:39.38996Z","end":"2026-06-02T22:16:39.519803Z","steps":["trace[1203245474] 'agreement among raft nodes before linearized reading' (duration: 129.674071ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:39.519846Z","caller":"traceutil/trace.go:171","msg":"trace[1450644558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25233; }","duration":"128.262331ms","start":"2026-06-02T22:16:39.391565Z","end":"2026-06-02T22:16:39.519827Z","steps":["trace[1450644558] 'agreement among raft nodes before linearized reading' (duration: 128.051855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:39.520042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.416716ms","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-06-02T22:16:39.520084Z","caller":"traceutil/trace.go:171","msg":"trace[1874385436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25233; }","duration":"139.511988ms","start":"2026-06-02T22:16:39.380561Z","end":"2026-06-02T22:16:39.520073Z","steps":["trace[1874385436] 'agreement among raft nodes before linearized reading' (duration: 138.965032ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:39.786678Z","caller":"traceutil/trace.go:171","msg":"trace[1674277887] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"262.843321ms","start":"2026-06-02T22:16:39.52381Z","end":"2026-06-02T22:16:39.786653Z","steps":["trace[1674277887] 'process raft request' (duration: 262.741358ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:39.78668Z","caller":"traceutil/trace.go:171","msg":"trace[1017279888] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"263.138539ms","start":"2026-06-02T22:16:39.523511Z","end":"2026-06-02T22:16:39.786649Z","steps":["trace[1017279888] 'process raft request' (duration: 246.649736ms)","trace[1017279888] 'compare' (duration: 16.017289ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:39.78691Z","caller":"traceutil/trace.go:171","msg":"trace[1998735000] linearizableReadLoop","detail":"{readStateIndex:26048; appliedIndex:26045; }","duration":"149.156709ms","start":"2026-06-02T22:16:39.637563Z","end":"2026-06-02T22:16:39.78672Z","steps":["trace[1998735000] 'read index received' (duration: 132.508002ms)","trace[1998735000] 'applied index is now lower than readState.Index' (duration: 16.647237ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:39.787175Z","caller":"traceutil/trace.go:171","msg":"trace[1191636021] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"263.147909ms","start":"2026-06-02T22:16:39.523989Z","end":"2026-06-02T22:16:39.787137Z","steps":["trace[1191636021] 'process raft request' (duration: 262.628895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:39.787336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.753296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-06-02T22:16:39.787458Z","caller":"traceutil/trace.go:171","msg":"trace[1738547378] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25237; }","duration":"149.9399ms","start":"2026-06-02T22:16:39.637497Z","end":"2026-06-02T22:16:39.787437Z","steps":["trace[1738547378] 'agreement among raft nodes before linearized reading' (duration: 149.633362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:39.787709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.717351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130115"} {"level":"info","ts":"2026-06-02T22:16:39.787814Z","caller":"traceutil/trace.go:171","msg":"trace[1791577869] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25237; }","duration":"135.869884ms","start":"2026-06-02T22:16:39.65193Z","end":"2026-06-02T22:16:39.7878Z","steps":["trace[1791577869] 'agreement among raft nodes before linearized reading' (duration: 135.304339ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:16:40.735745Z","caller":"traceutil/trace.go:171","msg":"trace[313112968] linearizableReadLoop","detail":"{readStateIndex:26051; appliedIndex:26050; }","duration":"104.581276ms","start":"2026-06-02T22:16:40.631139Z","end":"2026-06-02T22:16:40.73572Z","steps":["trace[313112968] 'read index received' (duration: 87.64844ms)","trace[313112968] 'applied index is now lower than readState.Index' (duration: 16.931986ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:16:40.735777Z","caller":"traceutil/trace.go:171","msg":"trace[1382226247] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"196.305884ms","start":"2026-06-02T22:16:40.539448Z","end":"2026-06-02T22:16:40.735753Z","steps":["trace[1382226247] 'process raft request' (duration: 179.45685ms)","trace[1382226247] 'compare' (duration: 16.719449ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:16:40.735957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.770993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:16:40.736013Z","caller":"traceutil/trace.go:171","msg":"trace[951348225] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25240; }","duration":"104.934298ms","start":"2026-06-02T22:16:40.631061Z","end":"2026-06-02T22:16:40.735996Z","steps":["trace[951348225] 'agreement among raft nodes before linearized reading' (duration: 104.741442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:16:46.178665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.39987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:16:46.178728Z","caller":"traceutil/trace.go:171","msg":"trace[997474719] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25282; }","duration":"141.514143ms","start":"2026-06-02T22:16:46.0372Z","end":"2026-06-02T22:16:46.178714Z","steps":["trace[997474719] 'count revisions from in-memory index tree' (duration: 141.299817ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:17.88068Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000699a.wal"} {"level":"info","ts":"2026-06-02T22:18:38.232293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23527} {"level":"info","ts":"2026-06-02T22:18:38.359147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23527,"took":"122.559242ms","hash":3138971652,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":37072896,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-02T22:18:38.359204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3138971652,"revision":23527,"compact-revision":20222} {"level":"warn","ts":"2026-06-02T22:20:21.996151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.426485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:20:21.996239Z","caller":"traceutil/trace.go:171","msg":"trace[219216069] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:27508; }","duration":"124.567089ms","start":"2026-06-02T22:20:21.871655Z","end":"2026-06-02T22:20:21.996222Z","steps":["trace[219216069] 'count revisions from in-memory index tree' (duration: 124.345901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:21.996333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.190132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:20:21.996444Z","caller":"traceutil/trace.go:171","msg":"trace[157257281] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:27508; }","duration":"148.314505ms","start":"2026-06-02T22:20:21.848106Z","end":"2026-06-02T22:20:21.996421Z","steps":["trace[157257281] 'count revisions from in-memory index tree' (duration: 148.093438ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:23:38.238526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26399} {"level":"info","ts":"2026-06-02T22:23:38.378263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26399,"took":"135.590601ms","hash":48250653,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":33513472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T22:23:38.37833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":48250653,"revision":26399,"compact-revision":23527} {"level":"info","ts":"2026-06-02T22:23:53.847763Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:23:53.852789Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-02T22:23:53.852909Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-02T22:28:38.245488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28973} {"level":"info","ts":"2026-06-02T22:28:38.37858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28973,"took":"128.602545ms","hash":2021183403,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31105024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:28:38.378659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2021183403,"revision":28973,"compact-revision":26399} {"level":"info","ts":"2026-06-02T22:33:38.252919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31197} {"level":"info","ts":"2026-06-02T22:33:38.377004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31197,"took":"119.897984ms","hash":3849259390,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:33:38.37708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3849259390,"revision":31197,"compact-revision":28973} {"level":"info","ts":"2026-06-02T22:38:38.258356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33460} {"level":"info","ts":"2026-06-02T22:38:38.406802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33460,"took":"143.081966ms","hash":2805310130,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":32612352,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-02T22:38:38.406861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2805310130,"revision":33460,"compact-revision":31197} {"level":"info","ts":"2026-06-02T22:43:38.264879Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35761} {"level":"info","ts":"2026-06-02T22:43:38.404056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35761,"took":"135.510693ms","hash":1730014111,"current-db-size-bytes":59215872,"current-db-size":"59 MB","current-db-size-in-use-bytes":32100352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T22:43:38.404115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1730014111,"revision":35761,"compact-revision":33460} {"level":"info","ts":"2026-06-02T22:45:17.46782Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:45:17.473747Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-02T22:45:17.473886Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}