{"level":"warn","ts":"2026-07-01T01:03:49.362363Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T01:03:49.362475Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-07-01T01:03:49.362549Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T01:03:49.362566Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-01T01:03:49.362585Z","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-07-01T01:03:49.363765Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-01T01:03:49.363855Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-07-01T01:03:49.36728Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.2192ms"} {"level":"info","ts":"2026-07-01T01:03:49.371732Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-01T01:03:49.371819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T01:03:49.371844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-01T01:03:49.371859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T01:03:49.37187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-01T01:03:49.371917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-01T01:03:49.376103Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T01:03:49.377587Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T01:03:49.379879Z","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-07-01T01:03:49.382582Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T01:03:49.382767Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T01:03:49.382859Z","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-07-01T01:03:49.382935Z","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-07-01T01:03:49.382948Z","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-07-01T01:03:49.384682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-01T01:03:49.385393Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-01T01:03:49.387238Z","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-07-01T01:03:49.387511Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T01:03:49.387558Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T01:03:49.387676Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-01T01:03:49.387747Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-01T01:03:49.472928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T01:03:49.472986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T01:03:49.473013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-01T01:03:49.473045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-01T01:03:49.473059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-01T01:03:49.473078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-01T01:03:49.473101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-01T01:03:49.47404Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T01:03:49.474935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T01:03:49.474945Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T01:03:49.475065Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T01:03:49.47549Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T01:03:49.47553Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T01:03:49.475603Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T01:03:49.475693Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T01:03:49.475753Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T01:03:49.478723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T01:03:49.479087Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-07-01T01:04:27.110234Z","caller":"traceutil/trace.go:171","msg":"trace[513115276] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"197.590854ms","start":"2026-07-01T01:04:26.912602Z","end":"2026-07-01T01:04:27.110193Z","steps":["trace[513115276] 'process raft request' (duration: 192.989836ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:27.110421Z","caller":"traceutil/trace.go:171","msg":"trace[415268939] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"191.905016ms","start":"2026-07-01T01:04:26.918488Z","end":"2026-07-01T01:04:27.110393Z","steps":["trace[415268939] 'process raft request' (duration: 191.791914ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:27.110457Z","caller":"traceutil/trace.go:171","msg":"trace[1449941928] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"192.021717ms","start":"2026-07-01T01:04:26.918406Z","end":"2026-07-01T01:04:27.110427Z","steps":["trace[1449941928] 'process raft request' (duration: 191.790753ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:27.110633Z","caller":"traceutil/trace.go:171","msg":"trace[1980920611] transaction","detail":"{read_only:false; number_of_response:1; response_revision:545; }","duration":"197.526013ms","start":"2026-07-01T01:04:26.913096Z","end":"2026-07-01T01:04:27.110622Z","steps":["trace[1980920611] 'process raft request' (duration: 197.031914ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:27.110851Z","caller":"traceutil/trace.go:171","msg":"trace[1484123680] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"191.66052ms","start":"2026-07-01T01:04:26.919153Z","end":"2026-07-01T01:04:27.110814Z","steps":["trace[1484123680] 'process raft request' (duration: 191.181192ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:27.110954Z","caller":"traceutil/trace.go:171","msg":"trace[1840336611] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"175.856298ms","start":"2026-07-01T01:04:26.935075Z","end":"2026-07-01T01:04:27.110931Z","steps":["trace[1840336611] 'process raft request' (duration: 175.733645ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:27.111055Z","caller":"traceutil/trace.go:171","msg":"trace[680263022] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"185.626845ms","start":"2026-07-01T01:04:26.925327Z","end":"2026-07-01T01:04:27.110954Z","steps":["trace[680263022] 'process raft request' (duration: 185.320899ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:27.111137Z","caller":"traceutil/trace.go:171","msg":"trace[2084092882] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:566; }","duration":"196.552514ms","start":"2026-07-01T01:04:26.914565Z","end":"2026-07-01T01:04:27.111117Z","steps":["trace[2084092882] 'read index received' (duration: 71.01685ms)","trace[2084092882] 'applied index is now lower than readState.Index' (duration: 125.533824ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:04:27.111365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.772729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-07-01T01:04:27.11145Z","caller":"traceutil/trace.go:171","msg":"trace[145429376] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:551; }","duration":"196.899902ms","start":"2026-07-01T01:04:26.914533Z","end":"2026-07-01T01:04:27.111433Z","steps":["trace[145429376] 'agreement among raft nodes before linearized reading' (duration: 196.634387ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:32.842544Z","caller":"traceutil/trace.go:171","msg":"trace[1910546489] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"182.962385ms","start":"2026-07-01T01:04:32.659544Z","end":"2026-07-01T01:04:32.842506Z","steps":["trace[1910546489] 'process raft request' (duration: 182.302242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:04:33.136861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.444649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T01:04:33.136978Z","caller":"traceutil/trace.go:171","msg":"trace[1424696473] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"280.890808ms","start":"2026-07-01T01:04:32.856069Z","end":"2026-07-01T01:04:33.136959Z","steps":["trace[1424696473] 'process raft request' (duration: 150.41108ms)","trace[1424696473] 'compare' (duration: 129.143613ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:04:33.144374Z","caller":"traceutil/trace.go:171","msg":"trace[762519079] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"236.945609ms","start":"2026-07-01T01:04:32.907403Z","end":"2026-07-01T01:04:33.144349Z","steps":["trace[762519079] 'process raft request' (duration: 236.890327ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:33.144515Z","caller":"traceutil/trace.go:171","msg":"trace[1597865834] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"242.437534ms","start":"2026-07-01T01:04:32.90204Z","end":"2026-07-01T01:04:33.144477Z","steps":["trace[1597865834] 'process raft request' (duration: 242.111807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:04:33.627647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.759634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T01:04:33.627771Z","caller":"traceutil/trace.go:171","msg":"trace[1180782772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"260.942738ms","start":"2026-07-01T01:04:33.366799Z","end":"2026-07-01T01:04:33.627742Z","steps":["trace[1180782772] 'range keys from in-memory index tree' (duration: 260.54004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:04:33.627808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.049617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-01T01:04:33.627844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.357377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T01:04:33.627912Z","caller":"traceutil/trace.go:171","msg":"trace[1615461315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"170.20749ms","start":"2026-07-01T01:04:33.457682Z","end":"2026-07-01T01:04:33.62789Z","steps":["trace[1615461315] 'range keys from in-memory index tree' (duration: 169.856074ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:04:33.627915Z","caller":"traceutil/trace.go:171","msg":"trace[1652373587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"140.47521ms","start":"2026-07-01T01:04:33.487423Z","end":"2026-07-01T01:04:33.627898Z","steps":["trace[1652373587] 'range keys from in-memory index tree' (duration: 140.201985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:04:48.038951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.143649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h8bvh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-01T01:04:48.039085Z","caller":"traceutil/trace.go:171","msg":"trace[2096433646] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h8bvh; range_end:; response_count:1; response_revision:798; }","duration":"180.327994ms","start":"2026-07-01T01:04:47.858734Z","end":"2026-07-01T01:04:48.039062Z","steps":["trace[2096433646] 'range keys from in-memory index tree' (duration: 179.929286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:04:48.039116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.43384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:04:48.039216Z","caller":"traceutil/trace.go:171","msg":"trace[423297532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"222.559192ms","start":"2026-07-01T01:04:47.816634Z","end":"2026-07-01T01:04:48.039193Z","steps":["trace[423297532] 'range keys from in-memory index tree' (duration: 222.310418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:07.132015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.921189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T01:06:07.132073Z","caller":"traceutil/trace.go:171","msg":"trace[481034565] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1881; }","duration":"138.047132ms","start":"2026-07-01T01:06:06.994013Z","end":"2026-07-01T01:06:07.132061Z","steps":["trace[481034565] 'range keys from in-memory index tree' (duration: 137.697875ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:18.94521Z","caller":"traceutil/trace.go:171","msg":"trace[620388407] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"109.161559ms","start":"2026-07-01T01:06:18.836028Z","end":"2026-07-01T01:06:18.94519Z","steps":["trace[620388407] 'process raft request' (duration: 109.025897ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:51.755022Z","caller":"traceutil/trace.go:171","msg":"trace[1311204513] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"171.608317ms","start":"2026-07-01T01:06:51.583354Z","end":"2026-07-01T01:06:51.754962Z","steps":["trace[1311204513] 'read index received' (duration: 171.467445ms)","trace[1311204513] 'applied index is now lower than readState.Index' (duration: 139.972µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:06:51.755084Z","caller":"traceutil/trace.go:171","msg":"trace[1550047768] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"205.145771ms","start":"2026-07-01T01:06:51.549903Z","end":"2026-07-01T01:06:51.755049Z","steps":["trace[1550047768] 'process raft request' (duration: 204.904866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:51.755217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.837922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T01:06:51.755282Z","caller":"traceutil/trace.go:171","msg":"trace[1933516472] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2171; }","duration":"171.936464ms","start":"2026-07-01T01:06:51.583327Z","end":"2026-07-01T01:06:51.755263Z","steps":["trace[1933516472] 'agreement among raft nodes before linearized reading' (duration: 171.797671ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:51.976151Z","caller":"traceutil/trace.go:171","msg":"trace[794258695] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"386.082454ms","start":"2026-07-01T01:06:51.590038Z","end":"2026-07-01T01:06:51.976121Z","steps":["trace[794258695] 'process raft request' (duration: 385.877219ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:51.976311Z","caller":"traceutil/trace.go:171","msg":"trace[1665484166] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2252; }","duration":"221.181813ms","start":"2026-07-01T01:06:51.755106Z","end":"2026-07-01T01:06:51.976287Z","steps":["trace[1665484166] 'read index received' (duration: 220.745614ms)","trace[1665484166] 'applied index is now lower than readState.Index' (duration: 435.259µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:06:51.976352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:06:51.590013Z","time spent":"386.222766ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:06:51.976579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.44829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-01T01:06:51.976611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.11352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:06:51.976608Z","caller":"traceutil/trace.go:171","msg":"trace[995966372] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"364.991866ms","start":"2026-07-01T01:06:51.611574Z","end":"2026-07-01T01:06:51.976566Z","steps":["trace[995966372] 'process raft request' (duration: 364.495567ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:51.976661Z","caller":"traceutil/trace.go:171","msg":"trace[1020543232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2173; }","duration":"160.187861ms","start":"2026-07-01T01:06:51.816458Z","end":"2026-07-01T01:06:51.976646Z","steps":["trace[1020543232] 'agreement among raft nodes before linearized reading' (duration: 160.1185ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:51.976674Z","caller":"traceutil/trace.go:171","msg":"trace[1152278493] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2173; }","duration":"360.574563ms","start":"2026-07-01T01:06:51.616065Z","end":"2026-07-01T01:06:51.97664Z","steps":["trace[1152278493] 'agreement among raft nodes before linearized reading' (duration: 360.441801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:51.976779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:06:51.616047Z","time spent":"360.714525ms","remote":"127.0.0.1:42056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-01T01:06:51.976793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:06:51.611549Z","time spent":"365.131888ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:06:52.414821Z","caller":"traceutil/trace.go:171","msg":"trace[1665084047] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"131.878698ms","start":"2026-07-01T01:06:52.282914Z","end":"2026-07-01T01:06:52.414793Z","steps":["trace[1665084047] 'process raft request' (duration: 131.686795ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:52.619937Z","caller":"traceutil/trace.go:171","msg":"trace[159729041] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"200.652985ms","start":"2026-07-01T01:06:52.419243Z","end":"2026-07-01T01:06:52.619896Z","steps":["trace[159729041] 'process raft request' (duration: 109.353193ms)","trace[159729041] 'compare' (duration: 91.012797ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:06:52.620161Z","caller":"traceutil/trace.go:171","msg":"trace[663850317] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2256; }","duration":"169.5797ms","start":"2026-07-01T01:06:52.450557Z","end":"2026-07-01T01:06:52.620137Z","steps":["trace[663850317] 'read index received' (duration: 78.059543ms)","trace[663850317] 'applied index is now lower than readState.Index' (duration: 91.519107ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:06:52.620262Z","caller":"traceutil/trace.go:171","msg":"trace[1614925618] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"200.641286ms","start":"2026-07-01T01:06:52.41958Z","end":"2026-07-01T01:06:52.620222Z","steps":["trace[1614925618] 'process raft request' (duration: 200.255038ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:52.620304Z","caller":"traceutil/trace.go:171","msg":"trace[1633559958] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"200.645146ms","start":"2026-07-01T01:06:52.419615Z","end":"2026-07-01T01:06:52.62026Z","steps":["trace[1633559958] 'process raft request' (duration: 200.419022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:52.620484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.879426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-01T01:06:52.620534Z","caller":"traceutil/trace.go:171","msg":"trace[1532675286] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"169.989407ms","start":"2026-07-01T01:06:52.450531Z","end":"2026-07-01T01:06:52.62052Z","steps":["trace[1532675286] 'agreement among raft nodes before linearized reading' (duration: 169.736783ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:57.936303Z","caller":"traceutil/trace.go:171","msg":"trace[1637081451] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2286; }","duration":"120.133575ms","start":"2026-07-01T01:06:57.816144Z","end":"2026-07-01T01:06:57.936278Z","steps":["trace[1637081451] 'read index received' (duration: 120.010723ms)","trace[1637081451] 'applied index is now lower than readState.Index' (duration: 121.892µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:06:57.936496Z","caller":"traceutil/trace.go:171","msg":"trace[50619643] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"152.138891ms","start":"2026-07-01T01:06:57.784325Z","end":"2026-07-01T01:06:57.936464Z","steps":["trace[50619643] 'process raft request' (duration: 151.800724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:57.936535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.35773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:06:57.936728Z","caller":"traceutil/trace.go:171","msg":"trace[1140189609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2204; }","duration":"120.565704ms","start":"2026-07-01T01:06:57.816125Z","end":"2026-07-01T01:06:57.93669Z","steps":["trace[1140189609] 'agreement among raft nodes before linearized reading' (duration: 120.33499ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:58.095166Z","caller":"traceutil/trace.go:171","msg":"trace[160127171] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"100.349133ms","start":"2026-07-01T01:06:57.994797Z","end":"2026-07-01T01:06:58.095146Z","steps":["trace[160127171] 'process raft request' (duration: 88.712974ms)","trace[160127171] 'compare' (duration: 11.423785ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:06:58.22852Z","caller":"traceutil/trace.go:171","msg":"trace[814494057] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2287; }","duration":"208.579015ms","start":"2026-07-01T01:06:58.01992Z","end":"2026-07-01T01:06:58.228499Z","steps":["trace[814494057] 'read index received' (duration: 63.670801ms)","trace[814494057] 'applied index is now lower than readState.Index' (duration: 144.907214ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:06:58.228553Z","caller":"traceutil/trace.go:171","msg":"trace[1483537842] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"232.596038ms","start":"2026-07-01T01:06:57.995928Z","end":"2026-07-01T01:06:58.228524Z","steps":["trace[1483537842] 'process raft request' (duration: 232.397874ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:06:58.228639Z","caller":"traceutil/trace.go:171","msg":"trace[469163808] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"185.976509ms","start":"2026-07-01T01:06:58.042651Z","end":"2026-07-01T01:06:58.228627Z","steps":["trace[469163808] 'process raft request' (duration: 185.799096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:58.228789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.842709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T01:06:58.228848Z","caller":"traceutil/trace.go:171","msg":"trace[1022257958] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2207; }","duration":"208.952572ms","start":"2026-07-01T01:06:58.019883Z","end":"2026-07-01T01:06:58.228835Z","steps":["trace[1022257958] 'agreement among raft nodes before linearized reading' (duration: 208.782649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:58.48873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.589171ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T01:06:58.488849Z","caller":"traceutil/trace.go:171","msg":"trace[811969918] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2290; }","duration":"260.23419ms","start":"2026-07-01T01:06:58.228592Z","end":"2026-07-01T01:06:58.488826Z","steps":["trace[811969918] 'read index received' (duration: 47.532197ms)","trace[811969918] 'applied index is now lower than readState.Index' (duration: 212.700043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:06:58.489024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.487385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T01:06:58.489081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.193685ms","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:7"} {"level":"info","ts":"2026-07-01T01:06:58.489148Z","caller":"traceutil/trace.go:171","msg":"trace[225846841] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2207; }","duration":"236.299007ms","start":"2026-07-01T01:06:58.252835Z","end":"2026-07-01T01:06:58.489134Z","steps":["trace[225846841] 'agreement among raft nodes before linearized reading' (duration: 236.176725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:58.489162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.118442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:06:58.489173Z","caller":"traceutil/trace.go:171","msg":"trace[1793960826] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2207; }","duration":"365.653738ms","start":"2026-07-01T01:06:58.123464Z","end":"2026-07-01T01:06:58.489118Z","steps":["trace[1793960826] 'agreement among raft nodes before linearized reading' (duration: 365.488425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:58.489369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:06:58.123442Z","time spent":"365.891203ms","remote":"127.0.0.1:47478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-07-01T01:06:58.489255Z","caller":"traceutil/trace.go:171","msg":"trace[2062607625] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2207; }","duration":"211.247344ms","start":"2026-07-01T01:06:58.277983Z","end":"2026-07-01T01:06:58.489231Z","steps":["trace[2062607625] 'agreement among raft nodes before linearized reading' (duration: 211.064981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:06:58.489155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.391518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:06:58.489694Z","caller":"traceutil/trace.go:171","msg":"trace[503437747] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2207; }","duration":"167.975429ms","start":"2026-07-01T01:06:58.321701Z","end":"2026-07-01T01:06:58.489677Z","steps":["trace[503437747] 'agreement among raft nodes before linearized reading' (duration: 167.318826ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:08:22.502437Z","caller":"traceutil/trace.go:171","msg":"trace[951687151] linearizableReadLoop","detail":"{readStateIndex:2962; appliedIndex:2961; }","duration":"124.54475ms","start":"2026-07-01T01:08:22.377862Z","end":"2026-07-01T01:08:22.502407Z","steps":["trace[951687151] 'read index received' (duration: 35.161752ms)","trace[951687151] 'applied index is now lower than readState.Index' (duration: 89.381148ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:08:22.502558Z","caller":"traceutil/trace.go:171","msg":"trace[1133373631] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"125.627271ms","start":"2026-07-01T01:08:22.376868Z","end":"2026-07-01T01:08:22.502496Z","steps":["trace[1133373631] 'process raft request' (duration: 36.140051ms)","trace[1133373631] 'compare' (duration: 89.078633ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:08:22.502675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.792684ms","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-07-01T01:08:22.502734Z","caller":"traceutil/trace.go:171","msg":"trace[921303553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2854; }","duration":"124.902157ms","start":"2026-07-01T01:08:22.377815Z","end":"2026-07-01T01:08:22.502717Z","steps":["trace[921303553] 'agreement among raft nodes before linearized reading' (duration: 124.714983ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:08:22.742872Z","caller":"traceutil/trace.go:171","msg":"trace[586866701] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"236.225163ms","start":"2026-07-01T01:08:22.506622Z","end":"2026-07-01T01:08:22.742847Z","steps":["trace[586866701] 'process raft request' (duration: 236.029129ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:08:22.816053Z","caller":"traceutil/trace.go:171","msg":"trace[1318709696] linearizableReadLoop","detail":"{readStateIndex:2964; appliedIndex:2962; }","duration":"174.991746ms","start":"2026-07-01T01:08:22.641022Z","end":"2026-07-01T01:08:22.816014Z","steps":["trace[1318709696] 'read index received' (duration: 101.634786ms)","trace[1318709696] 'applied index is now lower than readState.Index' (duration: 73.35503ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:08:22.816214Z","caller":"traceutil/trace.go:171","msg":"trace[76990925] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"298.756902ms","start":"2026-07-01T01:08:22.517439Z","end":"2026-07-01T01:08:22.816196Z","steps":["trace[76990925] 'process raft request' (duration: 298.391976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:08:22.816534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.504546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-01T01:08:22.816609Z","caller":"traceutil/trace.go:171","msg":"trace[1994617782] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2856; }","duration":"175.628008ms","start":"2026-07-01T01:08:22.640962Z","end":"2026-07-01T01:08:22.81659Z","steps":["trace[1994617782] 'agreement among raft nodes before linearized reading' (duration: 175.444914ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:10:53.142839Z","caller":"traceutil/trace.go:171","msg":"trace[1015735286] transaction","detail":"{read_only:false; response_revision:3850; number_of_response:1; }","duration":"118.712766ms","start":"2026-07-01T01:10:53.024089Z","end":"2026-07-01T01:10:53.142802Z","steps":["trace[1015735286] 'process raft request' (duration: 118.544023ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:10.345348Z","caller":"traceutil/trace.go:171","msg":"trace[1953215825] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4879; }","duration":"117.972748ms","start":"2026-07-01T01:12:10.227334Z","end":"2026-07-01T01:12:10.345307Z","steps":["trace[1953215825] 'process raft request' (duration: 40.632781ms)","trace[1953215825] 'compare' (duration: 77.283706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:12:12.74323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.586249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:12:12.743347Z","caller":"traceutil/trace.go:171","msg":"trace[174708059] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4993; }","duration":"205.756272ms","start":"2026-07-01T01:12:12.537563Z","end":"2026-07-01T01:12:12.743319Z","steps":["trace[174708059] 'range keys from in-memory index tree' (duration: 205.515648ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:14.393707Z","caller":"traceutil/trace.go:171","msg":"trace[358317484] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"123.889159ms","start":"2026-07-01T01:12:14.269793Z","end":"2026-07-01T01:12:14.393682Z","steps":["trace[358317484] 'process raft request' (duration: 123.746407ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:14.417966Z","caller":"traceutil/trace.go:171","msg":"trace[1799872856] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5212; }","duration":"107.845725ms","start":"2026-07-01T01:12:14.310094Z","end":"2026-07-01T01:12:14.41794Z","steps":["trace[1799872856] 'read index received' (duration: 83.482733ms)","trace[1799872856] 'applied index is now lower than readState.Index' (duration: 24.361922ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:12:14.418001Z","caller":"traceutil/trace.go:171","msg":"trace[599985410] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"147.958735ms","start":"2026-07-01T01:12:14.270011Z","end":"2026-07-01T01:12:14.417969Z","steps":["trace[599985410] 'process raft request' (duration: 147.750521ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:14.418096Z","caller":"traceutil/trace.go:171","msg":"trace[2005797784] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"147.752011ms","start":"2026-07-01T01:12:14.270317Z","end":"2026-07-01T01:12:14.418069Z","steps":["trace[2005797784] 'process raft request' (duration: 147.576028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:12:14.418278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.12093ms","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:5"} {"level":"info","ts":"2026-07-01T01:12:14.418333Z","caller":"traceutil/trace.go:171","msg":"trace[1508540326] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5024; }","duration":"108.263103ms","start":"2026-07-01T01:12:14.310055Z","end":"2026-07-01T01:12:14.418319Z","steps":["trace[1508540326] 'agreement among raft nodes before linearized reading' (duration: 107.979677ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:58.748057Z","caller":"traceutil/trace.go:171","msg":"trace[1336641295] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"219.662806ms","start":"2026-07-01T01:12:58.528365Z","end":"2026-07-01T01:12:58.748028Z","steps":["trace[1336641295] 'process raft request' (duration: 161.233827ms)","trace[1336641295] 'compare' (duration: 58.201673ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:12:58.783179Z","caller":"traceutil/trace.go:171","msg":"trace[741512399] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"254.017137ms","start":"2026-07-01T01:12:58.529129Z","end":"2026-07-01T01:12:58.783146Z","steps":["trace[741512399] 'process raft request' (duration: 253.787533ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:58.783535Z","caller":"traceutil/trace.go:171","msg":"trace[780092203] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"254.142149ms","start":"2026-07-01T01:12:58.529324Z","end":"2026-07-01T01:12:58.783466Z","steps":["trace[780092203] 'process raft request' (duration: 253.742632ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:58.783783Z","caller":"traceutil/trace.go:171","msg":"trace[1933018108] linearizableReadLoop","detail":"{readStateIndex:5830; appliedIndex:5824; }","duration":"143.577382ms","start":"2026-07-01T01:12:58.640181Z","end":"2026-07-01T01:12:58.783759Z","steps":["trace[1933018108] 'read index received' (duration: 49.564889ms)","trace[1933018108] 'applied index is now lower than readState.Index' (duration: 94.011273ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:12:58.783933Z","caller":"traceutil/trace.go:171","msg":"trace[1636332756] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"254.025406ms","start":"2026-07-01T01:12:58.529873Z","end":"2026-07-01T01:12:58.783898Z","steps":["trace[1636332756] 'process raft request' (duration: 253.739811ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:58.784112Z","caller":"traceutil/trace.go:171","msg":"trace[1034029321] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"253.15377ms","start":"2026-07-01T01:12:58.53094Z","end":"2026-07-01T01:12:58.784094Z","steps":["trace[1034029321] 'process raft request' (duration: 252.711042ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:58.784369Z","caller":"traceutil/trace.go:171","msg":"trace[1489532276] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"254.955845ms","start":"2026-07-01T01:12:58.529386Z","end":"2026-07-01T01:12:58.784342Z","steps":["trace[1489532276] 'process raft request' (duration: 253.732252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:12:58.784478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.292976ms","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-07-01T01:12:58.784513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.290112ms","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-07-01T01:12:58.784522Z","caller":"traceutil/trace.go:171","msg":"trace[1543565575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5621; }","duration":"144.410238ms","start":"2026-07-01T01:12:58.640099Z","end":"2026-07-01T01:12:58.784509Z","steps":["trace[1543565575] 'agreement among raft nodes before linearized reading' (duration: 144.253885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:12:58.784366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.147757ms","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-07-01T01:12:58.784552Z","caller":"traceutil/trace.go:171","msg":"trace[1780721968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5621; }","duration":"139.345853ms","start":"2026-07-01T01:12:58.645194Z","end":"2026-07-01T01:12:58.78454Z","steps":["trace[1780721968] 'agreement among raft nodes before linearized reading' (duration: 139.227831ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:58.784616Z","caller":"traceutil/trace.go:171","msg":"trace[959453092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5621; }","duration":"141.445162ms","start":"2026-07-01T01:12:58.643154Z","end":"2026-07-01T01:12:58.784599Z","steps":["trace[959453092] 'agreement among raft nodes before linearized reading' (duration: 141.025323ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:59.724204Z","caller":"traceutil/trace.go:171","msg":"trace[1580620749] linearizableReadLoop","detail":"{readStateIndex:5835; appliedIndex:5834; }","duration":"166.479857ms","start":"2026-07-01T01:12:59.5577Z","end":"2026-07-01T01:12:59.72418Z","steps":["trace[1580620749] 'read index received' (duration: 156.395266ms)","trace[1580620749] 'applied index is now lower than readState.Index' (duration: 10.083691ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:12:59.724219Z","caller":"traceutil/trace.go:171","msg":"trace[794422500] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"186.769042ms","start":"2026-07-01T01:12:59.537422Z","end":"2026-07-01T01:12:59.724191Z","steps":["trace[794422500] 'process raft request' (duration: 176.59631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:12:59.724427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.693731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-01T01:12:59.724499Z","caller":"traceutil/trace.go:171","msg":"trace[1154009188] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5626; }","duration":"166.810483ms","start":"2026-07-01T01:12:59.557673Z","end":"2026-07-01T01:12:59.724484Z","steps":["trace[1154009188] 'agreement among raft nodes before linearized reading' (duration: 166.595389ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:12:59.727976Z","caller":"traceutil/trace.go:171","msg":"trace[1834624485] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"142.234608ms","start":"2026-07-01T01:12:59.585716Z","end":"2026-07-01T01:12:59.72795Z","steps":["trace[1834624485] 'process raft request' (duration: 142.114735ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:13:00.012637Z","caller":"traceutil/trace.go:171","msg":"trace[1541893540] linearizableReadLoop","detail":"{readStateIndex:5848; appliedIndex:5847; }","duration":"106.795295ms","start":"2026-07-01T01:12:59.905815Z","end":"2026-07-01T01:13:00.012611Z","steps":["trace[1541893540] 'read index received' (duration: 58.879936ms)","trace[1541893540] 'applied index is now lower than readState.Index' (duration: 47.914449ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:13:00.012711Z","caller":"traceutil/trace.go:171","msg":"trace[466361391] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"119.231951ms","start":"2026-07-01T01:12:59.893439Z","end":"2026-07-01T01:13:00.012671Z","steps":["trace[466361391] 'process raft request' (duration: 71.312732ms)","trace[466361391] 'compare' (duration: 47.751816ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:13:00.012851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.017688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q5wpr\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-07-01T01:13:00.012891Z","caller":"traceutil/trace.go:171","msg":"trace[353725546] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q5wpr; range_end:; response_count:1; response_revision:5639; }","duration":"107.07719ms","start":"2026-07-01T01:12:59.905803Z","end":"2026-07-01T01:13:00.01288Z","steps":["trace[353725546] 'agreement among raft nodes before linearized reading' (duration: 106.912227ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:13:49.747656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3007} {"level":"info","ts":"2026-07-01T01:13:49.828883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3007,"took":"79.652791ms","hash":1989586618,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T01:13:49.82896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1989586618,"revision":3007,"compact-revision":-1} {"level":"info","ts":"2026-07-01T01:18:49.756097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6053} {"level":"info","ts":"2026-07-01T01:18:49.860916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6053,"took":"102.139648ms","hash":238416265,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":21987328,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-01T01:18:49.860992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":238416265,"revision":6053,"compact-revision":3007} {"level":"info","ts":"2026-07-01T01:18:59.389259Z","caller":"traceutil/trace.go:171","msg":"trace[554500306] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"355.140206ms","start":"2026-07-01T01:18:59.034086Z","end":"2026-07-01T01:18:59.389227Z","steps":["trace[554500306] 'process raft request' (duration: 354.886321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.389476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:18:59.034035Z","time spent":"355.32691ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:18:59.47064Z","caller":"traceutil/trace.go:171","msg":"trace[523246794] linearizableReadLoop","detail":"{readStateIndex:8651; appliedIndex:8649; }","duration":"289.750028ms","start":"2026-07-01T01:18:59.180863Z","end":"2026-07-01T01:18:59.470613Z","steps":["trace[523246794] 'read index received' (duration: 208.103576ms)","trace[523246794] 'applied index is now lower than readState.Index' (duration: 81.645042ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:18:59.470741Z","caller":"traceutil/trace.go:171","msg":"trace[517249062] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"433.464485ms","start":"2026-07-01T01:18:59.037254Z","end":"2026-07-01T01:18:59.470719Z","steps":["trace[517249062] 'process raft request' (duration: 433.16765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.470836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.624622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T01:18:59.470834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.943592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.470886Z","caller":"traceutil/trace.go:171","msg":"trace[1165968750] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8355; }","duration":"162.723224ms","start":"2026-07-01T01:18:59.308152Z","end":"2026-07-01T01:18:59.470875Z","steps":["trace[1165968750] 'agreement among raft nodes before linearized reading' (duration: 162.621082ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:18:59.470906Z","caller":"traceutil/trace.go:171","msg":"trace[435222532] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8355; }","duration":"290.076404ms","start":"2026-07-01T01:18:59.180811Z","end":"2026-07-01T01:18:59.470888Z","steps":["trace[435222532] 'agreement among raft nodes before linearized reading' (duration: 289.942162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.212323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T01:18:59.471116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.128509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.471182Z","caller":"traceutil/trace.go:171","msg":"trace[1015319430] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8355; }","duration":"106.314775ms","start":"2026-07-01T01:18:59.36485Z","end":"2026-07-01T01:18:59.471165Z","steps":["trace[1015319430] 'agreement among raft nodes before linearized reading' (duration: 106.217663ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:18:59.471208Z","caller":"traceutil/trace.go:171","msg":"trace[994451325] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8355; }","duration":"149.269272ms","start":"2026-07-01T01:18:59.321918Z","end":"2026-07-01T01:18:59.471187Z","steps":["trace[994451325] 'agreement among raft nodes before linearized reading' (duration: 149.050077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.317313ms","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:5"} {"level":"info","ts":"2026-07-01T01:18:59.471274Z","caller":"traceutil/trace.go:171","msg":"trace[461713845] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8355; }","duration":"149.380494ms","start":"2026-07-01T01:18:59.321884Z","end":"2026-07-01T01:18:59.471264Z","steps":["trace[461713845] 'agreement among raft nodes before linearized reading' (duration: 149.316943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.517835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T01:18:59.471389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.673462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.471433Z","caller":"traceutil/trace.go:171","msg":"trace[1752231750] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8355; }","duration":"138.742454ms","start":"2026-07-01T01:18:59.33268Z","end":"2026-07-01T01:18:59.471423Z","steps":["trace[1752231750] 'agreement among raft nodes before linearized reading' (duration: 138.673013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.96818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.471495Z","caller":"traceutil/trace.go:171","msg":"trace[21511253] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8355; }","duration":"163.02522ms","start":"2026-07-01T01:18:59.308462Z","end":"2026-07-01T01:18:59.471487Z","steps":["trace[21511253] 'agreement among raft nodes before linearized reading' (duration: 162.970129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.103212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.471593Z","caller":"traceutil/trace.go:171","msg":"trace[1624392808] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8355; }","duration":"106.178643ms","start":"2026-07-01T01:18:59.365404Z","end":"2026-07-01T01:18:59.471582Z","steps":["trace[1624392808] 'agreement among raft nodes before linearized reading' (duration: 106.00695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.403798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.47168Z","caller":"traceutil/trace.go:171","msg":"trace[1123575923] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8355; }","duration":"163.477089ms","start":"2026-07-01T01:18:59.30819Z","end":"2026-07-01T01:18:59.471667Z","steps":["trace[1123575923] 'agreement among raft nodes before linearized reading' (duration: 163.422498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.992109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.471729Z","caller":"traceutil/trace.go:171","msg":"trace[1860793573] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8355; }","duration":"139.051269ms","start":"2026-07-01T01:18:59.332666Z","end":"2026-07-01T01:18:59.471717Z","steps":["trace[1860793573] 'agreement among raft nodes before linearized reading' (duration: 138.995438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.744043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T01:18:59.471763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.460532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.471783Z","caller":"traceutil/trace.go:171","msg":"trace[1548303688] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8355; }","duration":"106.822834ms","start":"2026-07-01T01:18:59.364952Z","end":"2026-07-01T01:18:59.471774Z","steps":["trace[1548303688] 'agreement among raft nodes before linearized reading' (duration: 106.775764ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:18:59.471824Z","caller":"traceutil/trace.go:171","msg":"trace[1528232587] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8355; }","duration":"119.522633ms","start":"2026-07-01T01:18:59.352282Z","end":"2026-07-01T01:18:59.471805Z","steps":["trace[1528232587] 'agreement among raft nodes before linearized reading' (duration: 119.409601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.470932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:18:59.037226Z","time spent":"433.565997ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:18:59.471636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.078929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.471925Z","caller":"traceutil/trace.go:171","msg":"trace[449437838] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8355; }","duration":"149.388894ms","start":"2026-07-01T01:18:59.322527Z","end":"2026-07-01T01:18:59.471915Z","steps":["trace[449437838] 'agreement among raft nodes before linearized reading' (duration: 149.079758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.915148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.472032Z","caller":"traceutil/trace.go:171","msg":"trace[1221874445] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8355; }","duration":"138.988199ms","start":"2026-07-01T01:18:59.333035Z","end":"2026-07-01T01:18:59.472023Z","steps":["trace[1221874445] 'agreement among raft nodes before linearized reading' (duration: 137.848817ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:18:59.472035Z","caller":"traceutil/trace.go:171","msg":"trace[528534580] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8355; }","duration":"118.674088ms","start":"2026-07-01T01:18:59.352684Z","end":"2026-07-01T01:18:59.471359Z","steps":["trace[528534580] 'agreement among raft nodes before linearized reading' (duration: 118.472554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:18:59.471834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.545543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:18:59.472236Z","caller":"traceutil/trace.go:171","msg":"trace[1432769424] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8355; }","duration":"119.947421ms","start":"2026-07-01T01:18:59.352256Z","end":"2026-07-01T01:18:59.472204Z","steps":["trace[1432769424] 'agreement among raft nodes before linearized reading' (duration: 119.550774ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:10.845514Z","caller":"traceutil/trace.go:171","msg":"trace[271022747] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"180.464857ms","start":"2026-07-01T01:19:10.665006Z","end":"2026-07-01T01:19:10.845471Z","steps":["trace[271022747] 'process raft request' (duration: 180.176502ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:11.072581Z","caller":"traceutil/trace.go:171","msg":"trace[635185121] linearizableReadLoop","detail":"{readStateIndex:8716; appliedIndex:8713; }","duration":"256.512314ms","start":"2026-07-01T01:19:10.816042Z","end":"2026-07-01T01:19:11.072554Z","steps":["trace[635185121] 'read index received' (duration: 29.113716ms)","trace[635185121] 'applied index is now lower than readState.Index' (duration: 227.397068ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:19:11.072628Z","caller":"traceutil/trace.go:171","msg":"trace[1549554047] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"405.51068ms","start":"2026-07-01T01:19:10.667056Z","end":"2026-07-01T01:19:11.072567Z","steps":["trace[1549554047] 'process raft request' (duration: 405.269716ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:11.072705Z","caller":"traceutil/trace.go:171","msg":"trace[1917377625] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"350.998208ms","start":"2026-07-01T01:19:10.721695Z","end":"2026-07-01T01:19:11.072693Z","steps":["trace[1917377625] 'process raft request' (duration: 350.798294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:19:11.072836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:19:10.721672Z","time spent":"351.072009ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:19:11.072878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:19:10.667042Z","time spent":"405.691194ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:19:11.072935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.858161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T01:19:11.073026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.262456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T01:19:11.073117Z","caller":"traceutil/trace.go:171","msg":"trace[1295471164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8417; }","duration":"257.101806ms","start":"2026-07-01T01:19:10.815988Z","end":"2026-07-01T01:19:11.07309Z","steps":["trace[1295471164] 'agreement among raft nodes before linearized reading' (duration: 256.81697ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:11.073152Z","caller":"traceutil/trace.go:171","msg":"trace[283327881] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8417; }","duration":"243.451809ms","start":"2026-07-01T01:19:10.829675Z","end":"2026-07-01T01:19:11.073127Z","steps":["trace[283327881] 'agreement among raft nodes before linearized reading' (duration: 243.157694ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:39.442866Z","caller":"traceutil/trace.go:171","msg":"trace[1389073900] linearizableReadLoop","detail":"{readStateIndex:8927; appliedIndex:8926; }","duration":"246.541438ms","start":"2026-07-01T01:19:39.196307Z","end":"2026-07-01T01:19:39.442849Z","steps":["trace[1389073900] 'read index received' (duration: 246.392915ms)","trace[1389073900] 'applied index is now lower than readState.Index' (duration: 147.673µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:19:39.443132Z","caller":"traceutil/trace.go:171","msg":"trace[1325085340] transaction","detail":"{read_only:false; response_revision:8620; number_of_response:1; }","duration":"392.390495ms","start":"2026-07-01T01:19:39.050728Z","end":"2026-07-01T01:19:39.443118Z","steps":["trace[1325085340] 'process raft request' (duration: 392.020138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:19:39.443236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:19:39.050717Z","time spent":"392.458686ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:19:39.443449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.138508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T01:19:39.443492Z","caller":"traceutil/trace.go:171","msg":"trace[958842945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8620; }","duration":"247.174829ms","start":"2026-07-01T01:19:39.196303Z","end":"2026-07-01T01:19:39.443478Z","steps":["trace[958842945] 'agreement among raft nodes before linearized reading' (duration: 247.076427ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:51.651242Z","caller":"traceutil/trace.go:171","msg":"trace[1331067211] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"140.349484ms","start":"2026-07-01T01:19:51.510866Z","end":"2026-07-01T01:19:51.651216Z","steps":["trace[1331067211] 'process raft request' (duration: 140.169001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:19:51.830735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.249786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T01:19:51.831408Z","caller":"traceutil/trace.go:171","msg":"trace[312335727] transaction","detail":"{read_only:false; response_revision:8725; number_of_response:1; }","duration":"316.190635ms","start":"2026-07-01T01:19:51.515156Z","end":"2026-07-01T01:19:51.831346Z","steps":["trace[312335727] 'process raft request' (duration: 185.129744ms)","trace[312335727] 'compare' (duration: 130.071902ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:19:51.83162Z","caller":"traceutil/trace.go:171","msg":"trace[459131856] transaction","detail":"{read_only:false; response_revision:8726; number_of_response:1; }","duration":"163.907756ms","start":"2026-07-01T01:19:51.667688Z","end":"2026-07-01T01:19:51.831596Z","steps":["trace[459131856] 'process raft request' (duration: 163.608661ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:51.831626Z","caller":"traceutil/trace.go:171","msg":"trace[593277453] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"163.761504ms","start":"2026-07-01T01:19:51.667778Z","end":"2026-07-01T01:19:51.831539Z","steps":["trace[593277453] 'process raft request' (duration: 163.679863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:19:51.83167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:19:51.515141Z","time spent":"316.343937ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:19:51.970725Z","caller":"traceutil/trace.go:171","msg":"trace[1150233570] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"127.006455ms","start":"2026-07-01T01:19:51.843692Z","end":"2026-07-01T01:19:51.970698Z","steps":["trace[1150233570] 'process raft request' (duration: 119.656936ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:19:52.842004Z","caller":"traceutil/trace.go:171","msg":"trace[388934046] linearizableReadLoop","detail":"{readStateIndex:9047; appliedIndex:9046; }","duration":"200.948192ms","start":"2026-07-01T01:19:52.641034Z","end":"2026-07-01T01:19:52.841982Z","steps":["trace[388934046] 'read index received' (duration: 200.788849ms)","trace[388934046] 'applied index is now lower than readState.Index' (duration: 154.873µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:19:52.842102Z","caller":"traceutil/trace.go:171","msg":"trace[554354984] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"218.496501ms","start":"2026-07-01T01:19:52.623577Z","end":"2026-07-01T01:19:52.842073Z","steps":["trace[554354984] 'process raft request' (duration: 218.235037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:19:52.842173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.107764ms","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:5"} {"level":"info","ts":"2026-07-01T01:19:52.842287Z","caller":"traceutil/trace.go:171","msg":"trace[1227993019] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8735; }","duration":"201.261988ms","start":"2026-07-01T01:19:52.64101Z","end":"2026-07-01T01:19:52.842272Z","steps":["trace[1227993019] 'agreement among raft nodes before linearized reading' (duration: 201.101655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:19:52.842528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.613008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T01:19:52.842566Z","caller":"traceutil/trace.go:171","msg":"trace[46084548] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8735; }","duration":"100.70182ms","start":"2026-07-01T01:19:52.741853Z","end":"2026-07-01T01:19:52.842555Z","steps":["trace[46084548] 'agreement among raft nodes before linearized reading' (duration: 100.494736ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:20:09.100001Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002401.wal"} {"level":"info","ts":"2026-07-01T01:21:08.151131Z","caller":"traceutil/trace.go:171","msg":"trace[1304301154] linearizableReadLoop","detail":"{readStateIndex:9802; appliedIndex:9801; }","duration":"187.381296ms","start":"2026-07-01T01:21:07.963731Z","end":"2026-07-01T01:21:08.151112Z","steps":["trace[1304301154] 'read index received' (duration: 187.280394ms)","trace[1304301154] 'applied index is now lower than readState.Index' (duration: 99.792µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:21:08.151188Z","caller":"traceutil/trace.go:171","msg":"trace[567201840] transaction","detail":"{read_only:false; response_revision:9469; number_of_response:1; }","duration":"235.866955ms","start":"2026-07-01T01:21:07.91529Z","end":"2026-07-01T01:21:08.151157Z","steps":["trace[567201840] 'process raft request' (duration: 235.685182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:21:08.15125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.507188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:21:08.151277Z","caller":"traceutil/trace.go:171","msg":"trace[1659025112] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:9469; }","duration":"187.581309ms","start":"2026-07-01T01:21:07.963688Z","end":"2026-07-01T01:21:08.151269Z","steps":["trace[1659025112] 'agreement among raft nodes before linearized reading' (duration: 187.515078ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:21:08.332973Z","caller":"traceutil/trace.go:171","msg":"trace[915952843] transaction","detail":"{read_only:false; response_revision:9470; number_of_response:1; }","duration":"252.646184ms","start":"2026-07-01T01:21:08.080275Z","end":"2026-07-01T01:21:08.332921Z","steps":["trace[915952843] 'process raft request' (duration: 241.456871ms)","trace[915952843] 'compare' (duration: 10.804556ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:21:08.333056Z","caller":"traceutil/trace.go:171","msg":"trace[266795856] transaction","detail":"{read_only:false; response_revision:9471; number_of_response:1; }","duration":"251.856399ms","start":"2026-07-01T01:21:08.081157Z","end":"2026-07-01T01:21:08.333013Z","steps":["trace[266795856] 'process raft request' (duration: 251.620694ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:21:08.372559Z","caller":"traceutil/trace.go:171","msg":"trace[91165293] linearizableReadLoop","detail":"{readStateIndex:9805; appliedIndex:9802; }","duration":"221.344299ms","start":"2026-07-01T01:21:08.151188Z","end":"2026-07-01T01:21:08.372532Z","steps":["trace[91165293] 'read index received' (duration: 170.522085ms)","trace[91165293] 'applied index is now lower than readState.Index' (duration: 50.819274ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:21:08.372753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.471135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T01:21:08.372819Z","caller":"traceutil/trace.go:171","msg":"trace[31880371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9472; }","duration":"364.573077ms","start":"2026-07-01T01:21:08.008233Z","end":"2026-07-01T01:21:08.372806Z","steps":["trace[31880371] 'agreement among raft nodes before linearized reading' (duration: 364.378453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:21:08.372855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:21:08.008222Z","time spent":"364.624288ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T01:21:08.381599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.823988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T01:21:08.381708Z","caller":"traceutil/trace.go:171","msg":"trace[152515333] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:9473; }","duration":"193.9445ms","start":"2026-07-01T01:21:08.187739Z","end":"2026-07-01T01:21:08.381683Z","steps":["trace[152515333] 'agreement among raft nodes before linearized reading' (duration: 193.769877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:21:08.381918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.097974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T01:21:08.382019Z","caller":"traceutil/trace.go:171","msg":"trace[14064075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9473; }","duration":"115.269517ms","start":"2026-07-01T01:21:08.266728Z","end":"2026-07-01T01:21:08.381997Z","steps":["trace[14064075] 'agreement among raft nodes before linearized reading' (duration: 114.940091ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:21:32.704266Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T01:21:32.709501Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T01:21:32.709615Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T01:23:49.762367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8174} {"level":"info","ts":"2026-07-01T01:23:49.824497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8174,"took":"59.858695ms","hash":110129364,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":29593600,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T01:23:49.824557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":110129364,"revision":8174,"compact-revision":6053} {"level":"info","ts":"2026-07-01T01:24:56.457219Z","caller":"traceutil/trace.go:171","msg":"trace[1597419946] transaction","detail":"{read_only:false; response_revision:11345; number_of_response:1; }","duration":"128.790062ms","start":"2026-07-01T01:24:56.328399Z","end":"2026-07-01T01:24:56.457189Z","steps":["trace[1597419946] 'process raft request' (duration: 128.626159ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:28:49.77067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10752} {"level":"info","ts":"2026-07-01T01:28:49.857687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10752,"took":"84.66017ms","hash":2222825053,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":27041792,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-01T01:28:49.857765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2222825053,"revision":10752,"compact-revision":8174} {"level":"info","ts":"2026-07-01T01:31:20.359715Z","caller":"traceutil/trace.go:171","msg":"trace[533241325] transaction","detail":"{read_only:false; response_revision:13928; number_of_response:1; }","duration":"140.178647ms","start":"2026-07-01T01:31:20.219501Z","end":"2026-07-01T01:31:20.35968Z","steps":["trace[533241325] 'process raft request' (duration: 138.645638ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:31:20.370233Z","caller":"traceutil/trace.go:171","msg":"trace[2126327099] transaction","detail":"{read_only:false; response_revision:13929; number_of_response:1; }","duration":"146.904245ms","start":"2026-07-01T01:31:20.223305Z","end":"2026-07-01T01:31:20.370209Z","steps":["trace[2126327099] 'process raft request' (duration: 146.787923ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:31:29.629085Z","caller":"traceutil/trace.go:171","msg":"trace[636442279] transaction","detail":"{read_only:false; response_revision:14095; number_of_response:1; }","duration":"180.28969ms","start":"2026-07-01T01:31:29.448764Z","end":"2026-07-01T01:31:29.629054Z","steps":["trace[636442279] 'process raft request' (duration: 173.99716ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:33:04.438174Z","caller":"traceutil/trace.go:171","msg":"trace[1643012081] linearizableReadLoop","detail":"{readStateIndex:15673; appliedIndex:15672; }","duration":"283.824189ms","start":"2026-07-01T01:33:04.154329Z","end":"2026-07-01T01:33:04.438153Z","steps":["trace[1643012081] 'read index received' (duration: 283.648285ms)","trace[1643012081] 'applied index is now lower than readState.Index' (duration: 175.324µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:33:04.438224Z","caller":"traceutil/trace.go:171","msg":"trace[2053703276] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"313.750036ms","start":"2026-07-01T01:33:04.124443Z","end":"2026-07-01T01:33:04.438193Z","steps":["trace[2053703276] 'process raft request' (duration: 313.581043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:33:04.438492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:33:04.124425Z","time spent":"313.860138ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:33:04.438502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.753275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-07-01T01:33:04.438501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.812915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q5wpr\" ","response":"range_response_count:1 size:6522"} {"level":"warn","ts":"2026-07-01T01:33:04.438493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.6633ms","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-07-01T01:33:04.438539Z","caller":"traceutil/trace.go:171","msg":"trace[1725993058] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15158; }","duration":"137.857907ms","start":"2026-07-01T01:33:04.300672Z","end":"2026-07-01T01:33:04.43853Z","steps":["trace[1725993058] 'agreement among raft nodes before linearized reading' (duration: 137.676203ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:33:04.438559Z","caller":"traceutil/trace.go:171","msg":"trace[915506762] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q5wpr; range_end:; response_count:1; response_revision:15158; }","duration":"257.878626ms","start":"2026-07-01T01:33:04.180666Z","end":"2026-07-01T01:33:04.438545Z","steps":["trace[915506762] 'agreement among raft nodes before linearized reading' (duration: 257.688762ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:33:04.438576Z","caller":"traceutil/trace.go:171","msg":"trace[1758103791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15158; }","duration":"278.799383ms","start":"2026-07-01T01:33:04.159755Z","end":"2026-07-01T01:33:04.438555Z","steps":["trace[1758103791] 'agreement among raft nodes before linearized reading' (duration: 278.551868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:33:04.438752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.368288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T01:33:04.438925Z","caller":"traceutil/trace.go:171","msg":"trace[2135473958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15158; }","duration":"284.608903ms","start":"2026-07-01T01:33:04.154272Z","end":"2026-07-01T01:33:04.438881Z","steps":["trace[2135473958] 'agreement among raft nodes before linearized reading' (duration: 284.107764ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:33:04.598483Z","caller":"traceutil/trace.go:171","msg":"trace[1872057956] linearizableReadLoop","detail":"{readStateIndex:15675; appliedIndex:15673; }","duration":"150.516567ms","start":"2026-07-01T01:33:04.447925Z","end":"2026-07-01T01:33:04.598442Z","steps":["trace[1872057956] 'read index received' (duration: 147.829586ms)","trace[1872057956] 'applied index is now lower than readState.Index' (duration: 2.685691ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:33:04.598515Z","caller":"traceutil/trace.go:171","msg":"trace[771351916] transaction","detail":"{read_only:false; response_revision:15159; number_of_response:1; }","duration":"155.216326ms","start":"2026-07-01T01:33:04.443259Z","end":"2026-07-01T01:33:04.598475Z","steps":["trace[771351916] 'process raft request' (duration: 152.476224ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:33:04.598586Z","caller":"traceutil/trace.go:171","msg":"trace[274811902] transaction","detail":"{read_only:false; response_revision:15160; number_of_response:1; }","duration":"155.234706ms","start":"2026-07-01T01:33:04.443313Z","end":"2026-07-01T01:33:04.598547Z","steps":["trace[274811902] 'process raft request' (duration: 155.054523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:33:04.600145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.18293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:770047"} {"level":"info","ts":"2026-07-01T01:33:04.600273Z","caller":"traceutil/trace.go:171","msg":"trace[1424400761] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15160; }","duration":"152.328702ms","start":"2026-07-01T01:33:04.447919Z","end":"2026-07-01T01:33:04.600248Z","steps":["trace[1424400761] 'agreement among raft nodes before linearized reading' (duration: 150.669741ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:33:49.777865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12853} {"level":"info","ts":"2026-07-01T01:33:49.86245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12853,"took":"82.475386ms","hash":777544242,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":30838784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T01:33:49.862516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":777544242,"revision":12853,"compact-revision":10752} {"level":"info","ts":"2026-07-01T01:38:49.788912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15458} {"level":"info","ts":"2026-07-01T01:38:49.883637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15458,"took":"92.116025ms","hash":1236165199,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":32313344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T01:38:49.88371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1236165199,"revision":15458,"compact-revision":12853} {"level":"info","ts":"2026-07-01T01:38:52.426453Z","caller":"traceutil/trace.go:171","msg":"trace[1942024139] linearizableReadLoop","detail":"{readStateIndex:18424; appliedIndex:18423; }","duration":"138.626116ms","start":"2026-07-01T01:38:52.287807Z","end":"2026-07-01T01:38:52.426433Z","steps":["trace[1942024139] 'read index received' (duration: 138.466523ms)","trace[1942024139] 'applied index is now lower than readState.Index' (duration: 158.953µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:38:52.426624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.805029ms","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-07-01T01:38:52.426654Z","caller":"traceutil/trace.go:171","msg":"trace[711816609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17820; }","duration":"138.882531ms","start":"2026-07-01T01:38:52.287762Z","end":"2026-07-01T01:38:52.426644Z","steps":["trace[711816609] 'agreement among raft nodes before linearized reading' (duration: 138.750538ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:38:52.426872Z","caller":"traceutil/trace.go:171","msg":"trace[24109778] transaction","detail":"{read_only:false; response_revision:17820; number_of_response:1; }","duration":"184.237921ms","start":"2026-07-01T01:38:52.242627Z","end":"2026-07-01T01:38:52.426865Z","steps":["trace[24109778] 'process raft request' (duration: 183.69994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:38:52.784242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.300464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:38:52.784346Z","caller":"traceutil/trace.go:171","msg":"trace[57780582] linearizableReadLoop","detail":"{readStateIndex:18426; appliedIndex:18425; }","duration":"246.933068ms","start":"2026-07-01T01:38:52.537397Z","end":"2026-07-01T01:38:52.78433Z","steps":["trace[57780582] 'read index received' (duration: 8.40602ms)","trace[57780582] 'applied index is now lower than readState.Index' (duration: 238.525857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:38:52.78446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.05931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:38:52.7845Z","caller":"traceutil/trace.go:171","msg":"trace[105250536] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:17822; }","duration":"247.13142ms","start":"2026-07-01T01:38:52.537357Z","end":"2026-07-01T01:38:52.784488Z","steps":["trace[105250536] 'agreement among raft nodes before linearized reading' (duration: 247.064229ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:38:52.784381Z","caller":"traceutil/trace.go:171","msg":"trace[894448362] transaction","detail":"{read_only:false; response_revision:17822; number_of_response:1; }","duration":"297.683589ms","start":"2026-07-01T01:38:52.486665Z","end":"2026-07-01T01:38:52.784348Z","steps":["trace[894448362] 'process raft request' (duration: 59.190432ms)","trace[894448362] 'compare' (duration: 238.1059ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:38:52.784648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.235694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:10815"} {"level":"info","ts":"2026-07-01T01:38:52.784719Z","caller":"traceutil/trace.go:171","msg":"trace[881725875] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17822; }","duration":"180.351926ms","start":"2026-07-01T01:38:52.60435Z","end":"2026-07-01T01:38:52.784702Z","steps":["trace[881725875] 'agreement among raft nodes before linearized reading' (duration: 180.161743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:38:52.784711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.711005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-01T01:38:52.784784Z","caller":"traceutil/trace.go:171","msg":"trace[1724028526] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17822; }","duration":"159.909929ms","start":"2026-07-01T01:38:52.624856Z","end":"2026-07-01T01:38:52.784766Z","steps":["trace[1724028526] 'agreement among raft nodes before linearized reading' (duration: 159.708266ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:38:53.02392Z","caller":"traceutil/trace.go:171","msg":"trace[752871507] linearizableReadLoop","detail":"{readStateIndex:18427; appliedIndex:18426; }","duration":"228.784903ms","start":"2026-07-01T01:38:52.79511Z","end":"2026-07-01T01:38:53.023895Z","steps":["trace[752871507] 'read index received' (duration: 228.420757ms)","trace[752871507] 'applied index is now lower than readState.Index' (duration: 363.136µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:38:53.024022Z","caller":"traceutil/trace.go:171","msg":"trace[1153681288] transaction","detail":"{read_only:false; response_revision:17823; number_of_response:1; }","duration":"228.936456ms","start":"2026-07-01T01:38:52.795079Z","end":"2026-07-01T01:38:53.024015Z","steps":["trace[1153681288] 'process raft request' (duration: 228.527739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:38:53.024168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.272808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:38:53.024243Z","caller":"traceutil/trace.go:171","msg":"trace[332940102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17823; }","duration":"205.377809ms","start":"2026-07-01T01:38:52.818845Z","end":"2026-07-01T01:38:53.024223Z","steps":["trace[332940102] 'agreement among raft nodes before linearized reading' (duration: 205.273667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:38:53.024464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.341033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0e97a544-f098-488c-b9f2-2a34c2bf492c\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-07-01T01:38:53.024503Z","caller":"traceutil/trace.go:171","msg":"trace[2058316155] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0e97a544-f098-488c-b9f2-2a34c2bf492c; range_end:; response_count:1; response_revision:17823; }","duration":"229.419225ms","start":"2026-07-01T01:38:52.795072Z","end":"2026-07-01T01:38:53.024491Z","steps":["trace[2058316155] 'agreement among raft nodes before linearized reading' (duration: 229.294803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:38:53.024468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.186442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-01T01:38:53.024629Z","caller":"traceutil/trace.go:171","msg":"trace[339036712] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:17823; }","duration":"142.418148ms","start":"2026-07-01T01:38:52.882204Z","end":"2026-07-01T01:38:53.024622Z","steps":["trace[339036712] 'agreement among raft nodes before linearized reading' (duration: 142.176173ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:41:55.674957Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T01:41:55.680647Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-01T01:41:55.680753Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-01T01:43:49.794766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17799} {"level":"info","ts":"2026-07-01T01:43:49.891919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17799,"took":"93.961168ms","hash":3082246411,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":34418688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T01:43:49.891977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3082246411,"revision":17799,"compact-revision":15458} {"level":"info","ts":"2026-07-01T01:44:02.029263Z","caller":"traceutil/trace.go:171","msg":"trace[491438497] transaction","detail":"{read_only:false; response_revision:20216; number_of_response:1; }","duration":"102.89637ms","start":"2026-07-01T01:44:01.926335Z","end":"2026-07-01T01:44:02.029231Z","steps":["trace[491438497] 'process raft request' (duration: 102.685726ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:44:02.150855Z","caller":"traceutil/trace.go:171","msg":"trace[1038474938] transaction","detail":"{read_only:false; response_revision:20217; number_of_response:1; }","duration":"116.787917ms","start":"2026-07-01T01:44:02.034024Z","end":"2026-07-01T01:44:02.150812Z","steps":["trace[1038474938] 'process raft request' (duration: 56.041589ms)","trace[1038474938] 'compare' (duration: 60.599426ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:44:17.847514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.61428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T01:44:17.847607Z","caller":"traceutil/trace.go:171","msg":"trace[1145914679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20297; }","duration":"214.755354ms","start":"2026-07-01T01:44:17.632834Z","end":"2026-07-01T01:44:17.847589Z","steps":["trace[1145914679] 'range keys from in-memory index tree' (duration: 214.444637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:44:18.102413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.155888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:44:18.10255Z","caller":"traceutil/trace.go:171","msg":"trace[1323121672] linearizableReadLoop","detail":"{readStateIndex:20988; appliedIndex:20987; }","duration":"236.132585ms","start":"2026-07-01T01:44:17.866377Z","end":"2026-07-01T01:44:18.102509Z","steps":["trace[1323121672] 'read index received' (duration: 121.765384ms)","trace[1323121672] 'applied index is now lower than readState.Index' (duration: 114.365531ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:44:18.102683Z","caller":"traceutil/trace.go:171","msg":"trace[537654522] transaction","detail":"{read_only:false; response_revision:20299; number_of_response:1; }","duration":"251.235085ms","start":"2026-07-01T01:44:17.85143Z","end":"2026-07-01T01:44:18.102665Z","steps":["trace[537654522] 'process raft request' (duration: 136.731231ms)","trace[537654522] 'compare' (duration: 114.016015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:44:18.103032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.345489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:44:18.103184Z","caller":"traceutil/trace.go:171","msg":"trace[1245409263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20299; }","duration":"235.594614ms","start":"2026-07-01T01:44:17.867568Z","end":"2026-07-01T01:44:18.103163Z","steps":["trace[1245409263] 'agreement among raft nodes before linearized reading' (duration: 235.301309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:44:18.102972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.676197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:44:18.104107Z","caller":"traceutil/trace.go:171","msg":"trace[119033457] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:20299; }","duration":"262.876889ms","start":"2026-07-01T01:44:17.841197Z","end":"2026-07-01T01:44:18.104073Z","steps":["trace[119033457] 'agreement among raft nodes before linearized reading' (duration: 261.633505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:44:18.365724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.257416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:44:18.365828Z","caller":"traceutil/trace.go:171","msg":"trace[1766359502] linearizableReadLoop","detail":"{readStateIndex:20989; appliedIndex:20988; }","duration":"142.632465ms","start":"2026-07-01T01:44:18.223182Z","end":"2026-07-01T01:44:18.365815Z","steps":["trace[1766359502] 'read index received' (duration: 49.03µs)","trace[1766359502] 'applied index is now lower than readState.Index' (duration: 142.574704ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:44:18.365969Z","caller":"traceutil/trace.go:171","msg":"trace[296948807] transaction","detail":"{read_only:false; response_revision:20300; number_of_response:1; }","duration":"258.614758ms","start":"2026-07-01T01:44:18.107308Z","end":"2026-07-01T01:44:18.365923Z","steps":["trace[296948807] 'process raft request' (duration: 100.066627ms)","trace[296948807] 'compare' (duration: 158.132203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:44:18.366142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.970651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-01T01:44:18.366209Z","caller":"traceutil/trace.go:171","msg":"trace[695587209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20300; }","duration":"143.087344ms","start":"2026-07-01T01:44:18.223107Z","end":"2026-07-01T01:44:18.366194Z","steps":["trace[695587209] 'agreement among raft nodes before linearized reading' (duration: 142.90222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:44:18.624548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.363127ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T01:44:18.624921Z","caller":"traceutil/trace.go:171","msg":"trace[1716717172] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"253.885126ms","start":"2026-07-01T01:44:18.371016Z","end":"2026-07-01T01:44:18.624902Z","steps":["trace[1716717172] 'process raft request' (duration: 253.650602ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:44:18.624801Z","caller":"traceutil/trace.go:171","msg":"trace[861094696] linearizableReadLoop","detail":"{readStateIndex:20990; appliedIndex:20989; }","duration":"258.891013ms","start":"2026-07-01T01:44:18.365849Z","end":"2026-07-01T01:44:18.62474Z","steps":["trace[861094696] 'read index received' (duration: 20.386163ms)","trace[861094696] 'applied index is now lower than readState.Index' (duration: 238.50176ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:44:18.62513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.053275ms","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-07-01T01:44:18.625245Z","caller":"traceutil/trace.go:171","msg":"trace[1405233059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20301; }","duration":"272.232769ms","start":"2026-07-01T01:44:18.352988Z","end":"2026-07-01T01:44:18.625221Z","steps":["trace[1405233059] 'agreement among raft nodes before linearized reading' (duration: 271.867312ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:44:18.783248Z","caller":"traceutil/trace.go:171","msg":"trace[1769341577] transaction","detail":"{read_only:false; response_revision:20302; number_of_response:1; }","duration":"151.867593ms","start":"2026-07-01T01:44:18.631358Z","end":"2026-07-01T01:44:18.783226Z","steps":["trace[1769341577] 'process raft request' (duration: 151.72414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:48:10.881602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.174239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T01:48:10.881763Z","caller":"traceutil/trace.go:171","msg":"trace[1044319772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22732; }","duration":"218.376903ms","start":"2026-07-01T01:48:10.663366Z","end":"2026-07-01T01:48:10.881743Z","steps":["trace[1044319772] 'range keys from in-memory index tree' (duration: 218.047397ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:48:10.881966Z","caller":"traceutil/trace.go:171","msg":"trace[368529003] linearizableReadLoop","detail":"{readStateIndex:23496; appliedIndex:23495; }","duration":"165.80916ms","start":"2026-07-01T01:48:10.716134Z","end":"2026-07-01T01:48:10.881944Z","steps":["trace[368529003] 'read index received' (duration: 164.969764ms)","trace[368529003] 'applied index is now lower than readState.Index' (duration: 838.186µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:48:10.882011Z","caller":"traceutil/trace.go:171","msg":"trace[1458491325] transaction","detail":"{read_only:false; response_revision:22733; number_of_response:1; }","duration":"176.408705ms","start":"2026-07-01T01:48:10.705576Z","end":"2026-07-01T01:48:10.881984Z","steps":["trace[1458491325] 'process raft request' (duration: 175.513968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:48:10.882137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.988794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-01T01:48:10.882186Z","caller":"traceutil/trace.go:171","msg":"trace[1336781880] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:22733; }","duration":"166.075156ms","start":"2026-07-01T01:48:10.7161Z","end":"2026-07-01T01:48:10.882175Z","steps":["trace[1336781880] 'agreement among raft nodes before linearized reading' (duration: 165.933793ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:48:10.921967Z","caller":"traceutil/trace.go:171","msg":"trace[1508757982] transaction","detail":"{read_only:false; response_revision:22734; number_of_response:1; }","duration":"115.773318ms","start":"2026-07-01T01:48:10.806163Z","end":"2026-07-01T01:48:10.921937Z","steps":["trace[1508757982] 'process raft request' (duration: 115.37761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:48:10.922559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.439149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:48:10.922633Z","caller":"traceutil/trace.go:171","msg":"trace[1205210070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22735; }","duration":"105.552161ms","start":"2026-07-01T01:48:10.817059Z","end":"2026-07-01T01:48:10.922612Z","steps":["trace[1205210070] 'agreement among raft nodes before linearized reading' (duration: 105.417738ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:48:49.800726Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20155} {"level":"info","ts":"2026-07-01T01:48:49.910265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20155,"took":"105.69135ms","hash":2311310231,"current-db-size-bytes":57184256,"current-db-size":"57 MB","current-db-size-in-use-bytes":42377216,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-01T01:48:49.910324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2311310231,"revision":20155,"compact-revision":17799} {"level":"info","ts":"2026-07-01T01:51:26.439691Z","caller":"traceutil/trace.go:171","msg":"trace[739357716] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25542; }","duration":"107.522524ms","start":"2026-07-01T01:51:26.332131Z","end":"2026-07-01T01:51:26.439653Z","steps":["trace[739357716] 'read index received' (duration: 21.495568ms)","trace[739357716] 'applied index is now lower than readState.Index' (duration: 86.025616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:26.440026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.87086ms","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-07-01T01:51:26.440073Z","caller":"traceutil/trace.go:171","msg":"trace[1462124579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24732; }","duration":"107.989002ms","start":"2026-07-01T01:51:26.33207Z","end":"2026-07-01T01:51:26.440059Z","steps":["trace[1462124579] 'agreement among raft nodes before linearized reading' (duration: 107.727507ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:26.440499Z","caller":"traceutil/trace.go:171","msg":"trace[727413014] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"154.602308ms","start":"2026-07-01T01:51:26.285884Z","end":"2026-07-01T01:51:26.440486Z","steps":["trace[727413014] 'process raft request' (duration: 153.552209ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:39.488765Z","caller":"traceutil/trace.go:171","msg":"trace[151756244] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"119.736716ms","start":"2026-07-01T01:51:39.369Z","end":"2026-07-01T01:51:39.488737Z","steps":["trace[151756244] 'process raft request' (duration: 119.575553ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:42.114612Z","caller":"traceutil/trace.go:171","msg":"trace[58769171] linearizableReadLoop","detail":"{readStateIndex:25702; appliedIndex:25701; }","duration":"125.721489ms","start":"2026-07-01T01:51:41.98887Z","end":"2026-07-01T01:51:42.114591Z","steps":["trace[58769171] 'read index received' (duration: 125.620637ms)","trace[58769171] 'applied index is now lower than readState.Index' (duration: 100.262µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:51:42.114653Z","caller":"traceutil/trace.go:171","msg":"trace[298782257] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"135.293851ms","start":"2026-07-01T01:51:41.979331Z","end":"2026-07-01T01:51:42.114625Z","steps":["trace[298782257] 'process raft request' (duration: 135.118208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:42.114916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.021715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-01T01:51:42.114954Z","caller":"traceutil/trace.go:171","msg":"trace[415281855] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24885; }","duration":"126.115997ms","start":"2026-07-01T01:51:41.988829Z","end":"2026-07-01T01:51:42.114945Z","steps":["trace[415281855] 'agreement among raft nodes before linearized reading' (duration: 125.872072ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:42.653787Z","caller":"traceutil/trace.go:171","msg":"trace[134160790] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"128.712476ms","start":"2026-07-01T01:51:42.525042Z","end":"2026-07-01T01:51:42.653755Z","steps":["trace[134160790] 'process raft request' (duration: 128.523493ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.110187Z","caller":"traceutil/trace.go:171","msg":"trace[2130210345] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"425.387095ms","start":"2026-07-01T01:51:42.684783Z","end":"2026-07-01T01:51:43.11017Z","steps":["trace[2130210345] 'process raft request' (duration: 425.291253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:43.11029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:42.684763Z","time spent":"425.473597ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:43.110335Z","caller":"traceutil/trace.go:171","msg":"trace[1211577590] linearizableReadLoop","detail":"{readStateIndex:25705; appliedIndex:25705; }","duration":"293.382485ms","start":"2026-07-01T01:51:42.816928Z","end":"2026-07-01T01:51:43.110311Z","steps":["trace[1211577590] 'read index received' (duration: 293.369725ms)","trace[1211577590] 'applied index is now lower than readState.Index' (duration: 10.58µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:43.110502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.558318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:43.110524Z","caller":"traceutil/trace.go:171","msg":"trace[522172049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24888; }","duration":"293.62751ms","start":"2026-07-01T01:51:42.816891Z","end":"2026-07-01T01:51:43.110518Z","steps":["trace[522172049] 'agreement among raft nodes before linearized reading' (duration: 293.528888ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.116719Z","caller":"traceutil/trace.go:171","msg":"trace[817060114] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"204.062129ms","start":"2026-07-01T01:51:42.912641Z","end":"2026-07-01T01:51:43.116703Z","steps":["trace[817060114] 'process raft request' (duration: 202.594351ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.116709Z","caller":"traceutil/trace.go:171","msg":"trace[83370937] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"201.552561ms","start":"2026-07-01T01:51:42.915137Z","end":"2026-07-01T01:51:43.116689Z","steps":["trace[83370937] 'process raft request' (duration: 201.466129ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.116875Z","caller":"traceutil/trace.go:171","msg":"trace[30531751] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"201.617472ms","start":"2026-07-01T01:51:42.91523Z","end":"2026-07-01T01:51:43.116847Z","steps":["trace[30531751] 'process raft request' (duration: 201.413718ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.11707Z","caller":"traceutil/trace.go:171","msg":"trace[1508155922] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"201.777885ms","start":"2026-07-01T01:51:42.91526Z","end":"2026-07-01T01:51:43.117038Z","steps":["trace[1508155922] 'process raft request' (duration: 201.475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:43.117156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.341608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-01T01:51:43.117196Z","caller":"traceutil/trace.go:171","msg":"trace[1321225543] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:24892; }","duration":"190.414379ms","start":"2026-07-01T01:51:42.926769Z","end":"2026-07-01T01:51:43.117184Z","steps":["trace[1321225543] 'agreement among raft nodes before linearized reading' (duration: 190.296337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:43.117305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.207987ms","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-07-01T01:51:43.11739Z","caller":"traceutil/trace.go:171","msg":"trace[800457377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24892; }","duration":"198.299438ms","start":"2026-07-01T01:51:42.919076Z","end":"2026-07-01T01:51:43.117376Z","steps":["trace[800457377] 'agreement among raft nodes before linearized reading' (duration: 198.112205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:43.117501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.635496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:43.117554Z","caller":"traceutil/trace.go:171","msg":"trace[1907796388] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24892; }","duration":"197.712228ms","start":"2026-07-01T01:51:42.919829Z","end":"2026-07-01T01:51:43.117541Z","steps":["trace[1907796388] 'agreement among raft nodes before linearized reading' (duration: 197.607835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:43.117564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.847781ms","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-07-01T01:51:43.117596Z","caller":"traceutil/trace.go:171","msg":"trace[24666608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24892; }","duration":"197.906882ms","start":"2026-07-01T01:51:42.919679Z","end":"2026-07-01T01:51:43.117586Z","steps":["trace[24666608] 'agreement among raft nodes before linearized reading' (duration: 197.82626ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.412932Z","caller":"traceutil/trace.go:171","msg":"trace[671053117] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"290.883858ms","start":"2026-07-01T01:51:43.122028Z","end":"2026-07-01T01:51:43.412912Z","steps":["trace[671053117] 'process raft request' (duration: 290.805847ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.413118Z","caller":"traceutil/trace.go:171","msg":"trace[1499973591] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"291.263226ms","start":"2026-07-01T01:51:43.121847Z","end":"2026-07-01T01:51:43.413111Z","steps":["trace[1499973591] 'process raft request' (duration: 290.368018ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.651765Z","caller":"traceutil/trace.go:171","msg":"trace[804568090] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"522.874857ms","start":"2026-07-01T01:51:43.128861Z","end":"2026-07-01T01:51:43.651736Z","steps":["trace[804568090] 'process raft request' (duration: 427.688118ms)","trace[804568090] 'compare' (duration: 94.571738ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:51:43.651883Z","caller":"traceutil/trace.go:171","msg":"trace[920298473] linearizableReadLoop","detail":"{readStateIndex:25713; appliedIndex:25710; }","duration":"519.969182ms","start":"2026-07-01T01:51:43.131895Z","end":"2026-07-01T01:51:43.651864Z","steps":["trace[920298473] 'read index received' (duration: 280.331527ms)","trace[920298473] 'applied index is now lower than readState.Index' (duration: 239.635305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:43.651903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:43.128846Z","time spent":"522.989849ms","remote":"127.0.0.1:41806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:43.652009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.100134ms","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-07-01T01:51:43.65206Z","caller":"traceutil/trace.go:171","msg":"trace[1249879042] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24895; }","duration":"520.171046ms","start":"2026-07-01T01:51:43.131875Z","end":"2026-07-01T01:51:43.652046Z","steps":["trace[1249879042] 'agreement among raft nodes before linearized reading' (duration: 520.058604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:43.652097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:43.131868Z","time spent":"520.219547ms","remote":"127.0.0.1:42072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-07-01T01:51:43.652354Z","caller":"traceutil/trace.go:171","msg":"trace[1943957178] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"141.013449ms","start":"2026-07-01T01:51:43.511318Z","end":"2026-07-01T01:51:43.652331Z","steps":["trace[1943957178] 'process raft request' (duration: 140.874777ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:43.719117Z","caller":"traceutil/trace.go:171","msg":"trace[978944271] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"112.406666ms","start":"2026-07-01T01:51:43.606663Z","end":"2026-07-01T01:51:43.71907Z","steps":["trace[978944271] 'process raft request' (duration: 112.253603ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:44.069986Z","caller":"traceutil/trace.go:171","msg":"trace[693965959] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"420.414121ms","start":"2026-07-01T01:51:43.649549Z","end":"2026-07-01T01:51:44.069963Z","steps":["trace[693965959] 'process raft request' (duration: 420.214857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:44.070129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:43.649524Z","time spent":"420.532603ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:44.070209Z","caller":"traceutil/trace.go:171","msg":"trace[1520388098] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"401.520481ms","start":"2026-07-01T01:51:43.668657Z","end":"2026-07-01T01:51:44.070177Z","steps":["trace[1520388098] 'process raft request' (duration: 401.267636ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:44.07028Z","caller":"traceutil/trace.go:171","msg":"trace[175189044] linearizableReadLoop","detail":"{readStateIndex:25716; appliedIndex:25714; }","duration":"416.616578ms","start":"2026-07-01T01:51:43.653637Z","end":"2026-07-01T01:51:44.070253Z","steps":["trace[175189044] 'read index received' (duration: 65.333172ms)","trace[175189044] 'applied index is now lower than readState.Index' (duration: 351.281266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:44.07037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:43.668632Z","time spent":"401.652574ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:44.070452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.59364ms","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":"warn","ts":"2026-07-01T01:51:44.07046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.821902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-01T01:51:44.070493Z","caller":"traceutil/trace.go:171","msg":"trace[1198521056] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24899; }","duration":"413.649921ms","start":"2026-07-01T01:51:43.656833Z","end":"2026-07-01T01:51:44.070483Z","steps":["trace[1198521056] 'agreement among raft nodes before linearized reading' (duration: 413.562839ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:44.070501Z","caller":"traceutil/trace.go:171","msg":"trace[1229352007] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24899; }","duration":"416.890463ms","start":"2026-07-01T01:51:43.653603Z","end":"2026-07-01T01:51:44.070494Z","steps":["trace[1229352007] 'agreement among raft nodes before linearized reading' (duration: 416.75976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:44.070524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:43.653591Z","time spent":"416.928514ms","remote":"127.0.0.1:41952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-01T01:51:44.070523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.759436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T01:51:44.07053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:43.656766Z","time spent":"413.755613ms","remote":"127.0.0.1:42072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-07-01T01:51:44.070555Z","caller":"traceutil/trace.go:171","msg":"trace[422449815] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24899; }","duration":"259.825808ms","start":"2026-07-01T01:51:43.810718Z","end":"2026-07-01T01:51:44.070544Z","steps":["trace[422449815] 'agreement among raft nodes before linearized reading' (duration: 259.780647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:44.070448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.207583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:44.070701Z","caller":"traceutil/trace.go:171","msg":"trace[1098307369] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24899; }","duration":"112.493107ms","start":"2026-07-01T01:51:43.958203Z","end":"2026-07-01T01:51:44.070696Z","steps":["trace[1098307369] 'agreement among raft nodes before linearized reading' (duration: 112.198262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:44.230049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.618062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T01:51:44.230124Z","caller":"traceutil/trace.go:171","msg":"trace[1476757256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24899; }","duration":"111.727973ms","start":"2026-07-01T01:51:44.118377Z","end":"2026-07-01T01:51:44.230105Z","steps":["trace[1476757256] 'range keys from in-memory index tree' (duration: 111.465588ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:45.26994Z","caller":"traceutil/trace.go:171","msg":"trace[54039077] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"155.269021ms","start":"2026-07-01T01:51:45.114634Z","end":"2026-07-01T01:51:45.269903Z","steps":["trace[54039077] 'process raft request' (duration: 155.023357ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:45.643748Z","caller":"traceutil/trace.go:171","msg":"trace[326708118] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"522.872278ms","start":"2026-07-01T01:51:45.120851Z","end":"2026-07-01T01:51:45.643723Z","steps":["trace[326708118] 'process raft request' (duration: 522.640414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:45.643915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.120835Z","time spent":"522.9833ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:45.644198Z","caller":"traceutil/trace.go:171","msg":"trace[40578582] linearizableReadLoop","detail":"{readStateIndex:25725; appliedIndex:25720; }","duration":"417.856061ms","start":"2026-07-01T01:51:45.226315Z","end":"2026-07-01T01:51:45.644171Z","steps":["trace[40578582] 'read index received' (duration: 43.299113ms)","trace[40578582] 'applied index is now lower than readState.Index' (duration: 374.555868ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:51:45.644281Z","caller":"traceutil/trace.go:171","msg":"trace[1200385291] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"522.818006ms","start":"2026-07-01T01:51:45.12145Z","end":"2026-07-01T01:51:45.644268Z","steps":["trace[1200385291] 'process raft request' (duration: 522.49241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:45.644348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.12142Z","time spent":"522.891207ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:45.644353Z","caller":"traceutil/trace.go:171","msg":"trace[1974040786] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"522.637292ms","start":"2026-07-01T01:51:45.121698Z","end":"2026-07-01T01:51:45.644336Z","steps":["trace[1974040786] 'process raft request' (duration: 522.406138ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:45.644485Z","caller":"traceutil/trace.go:171","msg":"trace[1371459336] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"523.101401ms","start":"2026-07-01T01:51:45.121373Z","end":"2026-07-01T01:51:45.644475Z","steps":["trace[1371459336] 'process raft request' (duration: 522.268685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:45.644484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.121688Z","time spent":"522.702004ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:45.644532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.12136Z","time spent":"523.146422ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:45.644716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.031385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:45.64483Z","caller":"traceutil/trace.go:171","msg":"trace[652428510] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24907; }","duration":"317.177999ms","start":"2026-07-01T01:51:45.327634Z","end":"2026-07-01T01:51:45.644812Z","steps":["trace[652428510] 'agreement among raft nodes before linearized reading' (duration: 317.002035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:45.644869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.327613Z","time spent":"317.24465ms","remote":"127.0.0.1:50530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-07-01T01:51:45.64492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.520814ms","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-07-01T01:51:45.645016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.168883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:45.645083Z","caller":"traceutil/trace.go:171","msg":"trace[1474351572] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24907; }","duration":"262.267975ms","start":"2026-07-01T01:51:45.382796Z","end":"2026-07-01T01:51:45.645064Z","steps":["trace[1474351572] 'agreement among raft nodes before linearized reading' (duration: 262.185253ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:45.645023Z","caller":"traceutil/trace.go:171","msg":"trace[259221892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24907; }","duration":"227.760949ms","start":"2026-07-01T01:51:45.417244Z","end":"2026-07-01T01:51:45.645005Z","steps":["trace[259221892] 'agreement among raft nodes before linearized reading' (duration: 227.429931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:45.644938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.623676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T01:51:45.645312Z","caller":"traceutil/trace.go:171","msg":"trace[2141228238] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24907; }","duration":"419.035674ms","start":"2026-07-01T01:51:45.226259Z","end":"2026-07-01T01:51:45.645295Z","steps":["trace[2141228238] 'agreement among raft nodes before linearized reading' (duration: 418.607786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:45.645352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.226237Z","time spent":"419.101075ms","remote":"127.0.0.1:42110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":22,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-01T01:51:45.645345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.279939ms","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-07-01T01:51:45.645464Z","caller":"traceutil/trace.go:171","msg":"trace[1720980588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24907; }","duration":"228.454921ms","start":"2026-07-01T01:51:45.416976Z","end":"2026-07-01T01:51:45.645431Z","steps":["trace[1720980588] 'agreement among raft nodes before linearized reading' (duration: 228.071234ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:46.147033Z","caller":"traceutil/trace.go:171","msg":"trace[317153873] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"451.897439ms","start":"2026-07-01T01:51:45.69505Z","end":"2026-07-01T01:51:46.146947Z","steps":["trace[317153873] 'process raft request' (duration: 451.692825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.147185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.695026Z","time spent":"452.080323ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:46.394523Z","caller":"traceutil/trace.go:171","msg":"trace[733350904] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"698.284971ms","start":"2026-07-01T01:51:45.696199Z","end":"2026-07-01T01:51:46.394484Z","steps":["trace[733350904] 'process raft request' (duration: 698.091748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.39467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.696186Z","time spent":"698.412243ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:46.395337Z","caller":"traceutil/trace.go:171","msg":"trace[1166389575] linearizableReadLoop","detail":"{readStateIndex:25730; appliedIndex:25728; }","duration":"678.18918ms","start":"2026-07-01T01:51:45.717133Z","end":"2026-07-01T01:51:46.395322Z","steps":["trace[1166389575] 'read index received' (duration: 429.635515ms)","trace[1166389575] 'applied index is now lower than readState.Index' (duration: 248.552585ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:46.395823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.021501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:46.39587Z","caller":"traceutil/trace.go:171","msg":"trace[2000187795] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24915; }","duration":"612.108113ms","start":"2026-07-01T01:51:45.783751Z","end":"2026-07-01T01:51:46.395859Z","steps":["trace[2000187795] 'agreement among raft nodes before linearized reading' (duration: 612.031532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.395912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.783736Z","time spent":"612.164054ms","remote":"127.0.0.1:50222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T01:51:46.39592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.77677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-07-01T01:51:46.39599Z","caller":"traceutil/trace.go:171","msg":"trace[880354440] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24915; }","duration":"678.856381ms","start":"2026-07-01T01:51:45.717113Z","end":"2026-07-01T01:51:46.39597Z","steps":["trace[880354440] 'agreement among raft nodes before linearized reading' (duration: 678.645418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.396024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.717036Z","time spent":"678.982224ms","remote":"127.0.0.1:41944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-07-01T01:51:46.396145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.957376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18be0770d825cd6e\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-07-01T01:51:46.396181Z","caller":"traceutil/trace.go:171","msg":"trace[1666168367] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"417.465624ms","start":"2026-07-01T01:51:45.978711Z","end":"2026-07-01T01:51:46.396176Z","steps":["trace[1666168367] 'process raft request' (duration: 416.530407ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:46.396185Z","caller":"traceutil/trace.go:171","msg":"trace[15026405] range","detail":"{range_begin:/registry/events/openstack/container-infra.18be0770d825cd6e; range_end:; response_count:1; response_revision:24915; }","duration":"245.030887ms","start":"2026-07-01T01:51:46.151145Z","end":"2026-07-01T01:51:46.396175Z","steps":["trace[15026405] 'agreement among raft nodes before linearized reading' (duration: 244.908925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.396251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.978682Z","time spent":"417.517585ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:46.396349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.651247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:46.396389Z","caller":"traceutil/trace.go:171","msg":"trace[395253724] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:24915; }","duration":"337.691708ms","start":"2026-07-01T01:51:46.058686Z","end":"2026-07-01T01:51:46.396377Z","steps":["trace[395253724] 'agreement among raft nodes before linearized reading' (duration: 337.637257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.396426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:46.058668Z","time spent":"337.748219ms","remote":"127.0.0.1:36046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true "} {"level":"info","ts":"2026-07-01T01:51:46.39616Z","caller":"traceutil/trace.go:171","msg":"trace[1443871534] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"671.829029ms","start":"2026-07-01T01:51:45.724324Z","end":"2026-07-01T01:51:46.396153Z","steps":["trace[1443871534] 'process raft request' (duration: 670.116297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.396534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.724303Z","time spent":"672.211125ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:46.396367Z","caller":"traceutil/trace.go:171","msg":"trace[96092907] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"320.332148ms","start":"2026-07-01T01:51:46.076029Z","end":"2026-07-01T01:51:46.396362Z","steps":["trace[96092907] 'process raft request' (duration: 319.260318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.396639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.015271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T01:51:46.396658Z","caller":"traceutil/trace.go:171","msg":"trace[1018105170] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24915; }","duration":"350.095905ms","start":"2026-07-01T01:51:46.046557Z","end":"2026-07-01T01:51:46.396653Z","steps":["trace[1018105170] 'agreement among raft nodes before linearized reading' (duration: 350.046033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.396657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:46.07601Z","time spent":"320.596412ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:46.39671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.35488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T01:51:46.396676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:46.046531Z","time spent":"350.141325ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-07-01T01:51:46.396767Z","caller":"traceutil/trace.go:171","msg":"trace[183855571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24915; }","duration":"581.468622ms","start":"2026-07-01T01:51:45.815286Z","end":"2026-07-01T01:51:46.396755Z","steps":["trace[183855571] 'agreement among raft nodes before linearized reading' (duration: 581.38697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:46.396807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:45.815267Z","time spent":"581.534863ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T01:51:46.646183Z","caller":"traceutil/trace.go:171","msg":"trace[1657173623] linearizableReadLoop","detail":"{readStateIndex:25736; appliedIndex:25734; }","duration":"215.636238ms","start":"2026-07-01T01:51:46.430524Z","end":"2026-07-01T01:51:46.64616Z","steps":["trace[1657173623] 'read index received' (duration: 146.082576ms)","trace[1657173623] 'applied index is now lower than readState.Index' (duration: 69.552742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:46.646433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.879793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18be077170183eae\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-07-01T01:51:46.646474Z","caller":"traceutil/trace.go:171","msg":"trace[736118910] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18be077170183eae; range_end:; response_count:1; response_revision:24918; }","duration":"215.971914ms","start":"2026-07-01T01:51:46.43049Z","end":"2026-07-01T01:51:46.646462Z","steps":["trace[736118910] 'agreement among raft nodes before linearized reading' (duration: 215.801261ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:46.646445Z","caller":"traceutil/trace.go:171","msg":"trace[1488100794] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"245.72001ms","start":"2026-07-01T01:51:46.400692Z","end":"2026-07-01T01:51:46.646412Z","steps":["trace[1488100794] 'process raft request' (duration: 175.918314ms)","trace[1488100794] 'compare' (duration: 69.223755ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:51:46.646657Z","caller":"traceutil/trace.go:171","msg":"trace[1285203258] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"245.696609ms","start":"2026-07-01T01:51:46.40095Z","end":"2026-07-01T01:51:46.646647Z","steps":["trace[1285203258] 'process raft request' (duration: 245.117888ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:47.018438Z","caller":"traceutil/trace.go:171","msg":"trace[1188940454] linearizableReadLoop","detail":"{readStateIndex:25738; appliedIndex:25737; }","duration":"202.316845ms","start":"2026-07-01T01:51:46.816098Z","end":"2026-07-01T01:51:47.018415Z","steps":["trace[1188940454] 'read index received' (duration: 123.696781ms)","trace[1188940454] 'applied index is now lower than readState.Index' (duration: 78.618814ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:51:47.018687Z","caller":"traceutil/trace.go:171","msg":"trace[871594103] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"281.858207ms","start":"2026-07-01T01:51:46.736815Z","end":"2026-07-01T01:51:47.018673Z","steps":["trace[871594103] 'process raft request' (duration: 203.055799ms)","trace[871594103] 'compare' (duration: 78.145465ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:47.018922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.815344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:47.018963Z","caller":"traceutil/trace.go:171","msg":"trace[1432510481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24920; }","duration":"202.910356ms","start":"2026-07-01T01:51:46.816043Z","end":"2026-07-01T01:51:47.018954Z","steps":["trace[1432510481] 'agreement among raft nodes before linearized reading' (duration: 202.810504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:47.019184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.790009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:47.019223Z","caller":"traceutil/trace.go:171","msg":"trace[1400339307] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24920; }","duration":"196.880031ms","start":"2026-07-01T01:51:46.822334Z","end":"2026-07-01T01:51:47.019214Z","steps":["trace[1400339307] 'agreement among raft nodes before linearized reading' (duration: 196.81517ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:47.377692Z","caller":"traceutil/trace.go:171","msg":"trace[1237699229] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"101.858056ms","start":"2026-07-01T01:51:47.275814Z","end":"2026-07-01T01:51:47.377672Z","steps":["trace[1237699229] 'process raft request' (duration: 101.649402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.016835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.343075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:51:48.016977Z","caller":"traceutil/trace.go:171","msg":"trace[1552078012] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"367.80735ms","start":"2026-07-01T01:51:47.649156Z","end":"2026-07-01T01:51:48.016963Z","steps":["trace[1552078012] 'process raft request' (duration: 225.251221ms)","trace[1552078012] 'compare' (duration: 142.228473ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:48.017027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:47.649129Z","time spent":"367.877892ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:48.017307Z","caller":"traceutil/trace.go:171","msg":"trace[1280905650] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"366.72577ms","start":"2026-07-01T01:51:47.650555Z","end":"2026-07-01T01:51:48.01728Z","steps":["trace[1280905650] 'process raft request' (duration: 366.373344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.017453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:47.650534Z","time spent":"366.844592ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:48.017475Z","caller":"traceutil/trace.go:171","msg":"trace[1869353334] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"366.643098ms","start":"2026-07-01T01:51:47.650822Z","end":"2026-07-01T01:51:48.017465Z","steps":["trace[1869353334] 'process raft request' (duration: 366.364863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.01752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:47.650811Z","time spent":"366.684729ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:48.01754Z","caller":"traceutil/trace.go:171","msg":"trace[1351638020] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"198.284578ms","start":"2026-07-01T01:51:47.819234Z","end":"2026-07-01T01:51:48.017519Z","steps":["trace[1351638020] 'process raft request' (duration: 198.241797ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:48.017661Z","caller":"traceutil/trace.go:171","msg":"trace[1801667378] linearizableReadLoop","detail":"{readStateIndex:25743; appliedIndex:25739; }","duration":"323.316585ms","start":"2026-07-01T01:51:47.694337Z","end":"2026-07-01T01:51:48.017653Z","steps":["trace[1801667378] 'read index received' (duration: 22.373835ms)","trace[1801667378] 'applied index is now lower than readState.Index' (duration: 300.94213ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:51:48.017582Z","caller":"traceutil/trace.go:171","msg":"trace[1386422382] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"320.964299ms","start":"2026-07-01T01:51:47.696611Z","end":"2026-07-01T01:51:48.017575Z","steps":["trace[1386422382] 'process raft request' (duration: 320.773236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.017798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.469357ms","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-07-01T01:51:48.017816Z","caller":"traceutil/trace.go:171","msg":"trace[1978548598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24927; }","duration":"323.524799ms","start":"2026-07-01T01:51:47.694286Z","end":"2026-07-01T01:51:48.017811Z","steps":["trace[1978548598] 'agreement among raft nodes before linearized reading' (duration: 323.443077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.017835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:47.694266Z","time spent":"323.565789ms","remote":"127.0.0.1:42066","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":"warn","ts":"2026-07-01T01:51:48.017839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:47.696597Z","time spent":"321.205464ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:48.017961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.698013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:51:48.017996Z","caller":"traceutil/trace.go:171","msg":"trace[315846052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24927; }","duration":"201.764465ms","start":"2026-07-01T01:51:47.816222Z","end":"2026-07-01T01:51:48.017986Z","steps":["trace[315846052] 'agreement among raft nodes before linearized reading' (duration: 201.708484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.017988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.572355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T01:51:48.018048Z","caller":"traceutil/trace.go:171","msg":"trace[1588809290] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:24927; }","duration":"136.690669ms","start":"2026-07-01T01:51:47.881339Z","end":"2026-07-01T01:51:48.01803Z","steps":["trace[1588809290] 'agreement among raft nodes before linearized reading' (duration: 136.562675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.018048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.841769ms","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-07-01T01:51:48.018109Z","caller":"traceutil/trace.go:171","msg":"trace[2094839118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24927; }","duration":"320.93642ms","start":"2026-07-01T01:51:47.697156Z","end":"2026-07-01T01:51:48.018092Z","steps":["trace[2094839118] 'agreement among raft nodes before linearized reading' (duration: 320.749025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.018181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:47.697142Z","time spent":"321.024512ms","remote":"127.0.0.1:42066","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-07-01T01:51:48.369893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.167878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:51:48.370046Z","caller":"traceutil/trace.go:171","msg":"trace[1692487617] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"347.349751ms","start":"2026-07-01T01:51:48.022679Z","end":"2026-07-01T01:51:48.370029Z","steps":["trace[1692487617] 'process raft request' (duration: 200.972949ms)","trace[1692487617] 'compare' (duration: 146.076327ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:48.370117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:48.022665Z","time spent":"347.418372ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:48.828426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.996386ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T01:51:48.828799Z","caller":"traceutil/trace.go:171","msg":"trace[1779648416] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"426.759381ms","start":"2026-07-01T01:51:48.402006Z","end":"2026-07-01T01:51:48.828765Z","steps":["trace[1779648416] 'process raft request' (duration: 426.522406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.828909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:48.401979Z","time spent":"426.868183ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:48.840819Z","caller":"traceutil/trace.go:171","msg":"trace[360468694] linearizableReadLoop","detail":"{readStateIndex:25751; appliedIndex:25748; }","duration":"188.840119ms","start":"2026-07-01T01:51:48.651954Z","end":"2026-07-01T01:51:48.840794Z","steps":["trace[360468694] 'read index received' (duration: 1.479718ms)","trace[360468694] 'applied index is now lower than readState.Index' (duration: 187.358791ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:51:48.840955Z","caller":"traceutil/trace.go:171","msg":"trace[368670239] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"437.308321ms","start":"2026-07-01T01:51:48.403621Z","end":"2026-07-01T01:51:48.840929Z","steps":["trace[368670239] 'process raft request' (duration: 425.13418ms)","trace[368670239] 'compare' (duration: 11.910396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:48.841069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.095814ms","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-07-01T01:51:48.841087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:48.40361Z","time spent":"437.406152ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:48.84111Z","caller":"traceutil/trace.go:171","msg":"trace[1467921205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24932; }","duration":"189.186196ms","start":"2026-07-01T01:51:48.651913Z","end":"2026-07-01T01:51:48.841099Z","steps":["trace[1467921205] 'agreement among raft nodes before linearized reading' (duration: 189.024433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:48.841245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.197246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T01:51:48.84133Z","caller":"traceutil/trace.go:171","msg":"trace[1950880815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24932; }","duration":"189.303529ms","start":"2026-07-01T01:51:48.652003Z","end":"2026-07-01T01:51:48.841307Z","steps":["trace[1950880815] 'agreement among raft nodes before linearized reading' (duration: 189.060063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:51:49.228917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.53999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:51:49.229117Z","caller":"traceutil/trace.go:171","msg":"trace[1386069115] linearizableReadLoop","detail":"{readStateIndex:25753; appliedIndex:25751; }","duration":"205.183639ms","start":"2026-07-01T01:51:49.023916Z","end":"2026-07-01T01:51:49.229099Z","steps":["trace[1386069115] 'read index received' (duration: 115.598667ms)","trace[1386069115] 'applied index is now lower than readState.Index' (duration: 89.583732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:49.229276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.366934ms","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-07-01T01:51:49.229312Z","caller":"traceutil/trace.go:171","msg":"trace[1990433263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24934; }","duration":"205.443104ms","start":"2026-07-01T01:51:49.023859Z","end":"2026-07-01T01:51:49.229303Z","steps":["trace[1990433263] 'agreement among raft nodes before linearized reading' (duration: 205.295652ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:49.22974Z","caller":"traceutil/trace.go:171","msg":"trace[410167885] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"383.288405ms","start":"2026-07-01T01:51:48.846238Z","end":"2026-07-01T01:51:49.229526Z","steps":["trace[410167885] 'process raft request' (duration: 382.794855ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:51:49.229464Z","caller":"traceutil/trace.go:171","msg":"trace[1917464226] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"384.253094ms","start":"2026-07-01T01:51:48.845191Z","end":"2026-07-01T01:51:49.229444Z","steps":["trace[1917464226] 'process raft request' (duration: 142.082031ms)","trace[1917464226] 'compare' (duration: 241.272105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:51:49.229848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:48.846216Z","time spent":"383.592431ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:51:49.229894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:51:48.845168Z","time spent":"384.678951ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:51:49.531996Z","caller":"traceutil/trace.go:171","msg":"trace[1426923952] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"149.38301ms","start":"2026-07-01T01:51:49.382594Z","end":"2026-07-01T01:51:49.531977Z","steps":["trace[1426923952] 'process raft request' (duration: 149.277478ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:01.717368Z","caller":"traceutil/trace.go:171","msg":"trace[2123774586] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"134.332483ms","start":"2026-07-01T01:52:01.582998Z","end":"2026-07-01T01:52:01.717331Z","steps":["trace[2123774586] 'process raft request' (duration: 134.13245ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:01.86577Z","caller":"traceutil/trace.go:171","msg":"trace[715676080] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"140.657724ms","start":"2026-07-01T01:52:01.725079Z","end":"2026-07-01T01:52:01.865737Z","steps":["trace[715676080] 'process raft request' (duration: 140.362468ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:02.198251Z","caller":"traceutil/trace.go:171","msg":"trace[300523734] linearizableReadLoop","detail":"{readStateIndex:25905; appliedIndex:25903; }","duration":"383.5404ms","start":"2026-07-01T01:52:01.814684Z","end":"2026-07-01T01:52:02.198224Z","steps":["trace[300523734] 'read index received' (duration: 50.908148ms)","trace[300523734] 'applied index is now lower than readState.Index' (duration: 332.631071ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:02.198341Z","caller":"traceutil/trace.go:171","msg":"trace[1859183166] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"421.688594ms","start":"2026-07-01T01:52:01.776603Z","end":"2026-07-01T01:52:02.198292Z","steps":["trace[1859183166] 'process raft request' (duration: 421.430419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:02.198405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.698112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:52:02.198448Z","caller":"traceutil/trace.go:171","msg":"trace[1968144020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25083; }","duration":"383.786373ms","start":"2026-07-01T01:52:01.81465Z","end":"2026-07-01T01:52:02.198436Z","steps":["trace[1968144020] 'agreement among raft nodes before linearized reading' (duration: 383.699012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:02.198489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:01.814637Z","time spent":"383.843985ms","remote":"127.0.0.1:41772","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-07-01T01:52:02.198548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:01.776581Z","time spent":"421.869378ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:52:02.215095Z","caller":"traceutil/trace.go:171","msg":"trace[1866826395] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"135.570287ms","start":"2026-07-01T01:52:02.07951Z","end":"2026-07-01T01:52:02.21508Z","steps":["trace[1866826395] 'process raft request' (duration: 135.526696ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:02.215113Z","caller":"traceutil/trace.go:171","msg":"trace[1199982772] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"136.435083ms","start":"2026-07-01T01:52:02.078647Z","end":"2026-07-01T01:52:02.215082Z","steps":["trace[1199982772] 'process raft request' (duration: 136.216219ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:02.215214Z","caller":"traceutil/trace.go:171","msg":"trace[748170054] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"135.704929ms","start":"2026-07-01T01:52:02.079504Z","end":"2026-07-01T01:52:02.215209Z","steps":["trace[748170054] 'process raft request' (duration: 135.455615ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:02.215294Z","caller":"traceutil/trace.go:171","msg":"trace[483015810] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"135.144158ms","start":"2026-07-01T01:52:02.080128Z","end":"2026-07-01T01:52:02.215272Z","steps":["trace[483015810] 'process raft request' (duration: 134.928995ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:02.551878Z","caller":"traceutil/trace.go:171","msg":"trace[1465682628] linearizableReadLoop","detail":"{readStateIndex:25910; appliedIndex:25909; }","duration":"108.245126ms","start":"2026-07-01T01:52:02.443605Z","end":"2026-07-01T01:52:02.55185Z","steps":["trace[1465682628] 'read index received' (duration: 75.868071ms)","trace[1465682628] 'applied index is now lower than readState.Index' (duration: 32.375755ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:02.551947Z","caller":"traceutil/trace.go:171","msg":"trace[1151406094] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"332.758634ms","start":"2026-07-01T01:52:02.219146Z","end":"2026-07-01T01:52:02.551904Z","steps":["trace[1151406094] 'process raft request' (duration: 300.42634ms)","trace[1151406094] 'compare' (duration: 31.726412ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:02.552084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.46263ms","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-07-01T01:52:02.552112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:02.219136Z","time spent":"332.884687ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:52:02.552121Z","caller":"traceutil/trace.go:171","msg":"trace[673191667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25088; }","duration":"108.550593ms","start":"2026-07-01T01:52:02.443559Z","end":"2026-07-01T01:52:02.552109Z","steps":["trace[673191667] 'agreement among raft nodes before linearized reading' (duration: 108.39597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:02.845491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.632733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:52:02.845561Z","caller":"traceutil/trace.go:171","msg":"trace[627933133] linearizableReadLoop","detail":"{readStateIndex:25911; appliedIndex:25910; }","duration":"181.414218ms","start":"2026-07-01T01:52:02.664136Z","end":"2026-07-01T01:52:02.84555Z","steps":["trace[627933133] 'read index received' (duration: 30.611751ms)","trace[627933133] 'applied index is now lower than readState.Index' (duration: 150.801547ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:02.845613Z","caller":"traceutil/trace.go:171","msg":"trace[1069978402] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"286.310372ms","start":"2026-07-01T01:52:02.559296Z","end":"2026-07-01T01:52:02.845607Z","steps":["trace[1069978402] 'process raft request' (duration: 135.495515ms)","trace[1069978402] 'compare' (duration: 150.228705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:02.845758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.295992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41db2e65-c1b4-4c4d-82f5-c436b5545a9a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-07-01T01:52:02.845807Z","caller":"traceutil/trace.go:171","msg":"trace[1065266098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41db2e65-c1b4-4c4d-82f5-c436b5545a9a; range_end:; response_count:1; response_revision:25089; }","duration":"124.379374ms","start":"2026-07-01T01:52:02.721415Z","end":"2026-07-01T01:52:02.845795Z","steps":["trace[1065266098] 'agreement among raft nodes before linearized reading' (duration: 124.243222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:02.845834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.666493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-01T01:52:02.845942Z","caller":"traceutil/trace.go:171","msg":"trace[61356126] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25089; }","duration":"181.825586ms","start":"2026-07-01T01:52:02.664093Z","end":"2026-07-01T01:52:02.845919Z","steps":["trace[61356126] 'agreement among raft nodes before linearized reading' (duration: 181.563711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:03.159021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.402635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:52:03.159285Z","caller":"traceutil/trace.go:171","msg":"trace[1610209144] linearizableReadLoop","detail":"{readStateIndex:25912; appliedIndex:25911; }","duration":"296.702449ms","start":"2026-07-01T01:52:02.862558Z","end":"2026-07-01T01:52:03.159261Z","steps":["trace[1610209144] 'read index received' (duration: 132.962147ms)","trace[1610209144] 'applied index is now lower than readState.Index' (duration: 163.739052ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:03.159352Z","caller":"traceutil/trace.go:171","msg":"trace[451694308] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"256.026346ms","start":"2026-07-01T01:52:02.903304Z","end":"2026-07-01T01:52:03.159331Z","steps":["trace[451694308] 'process raft request' (duration: 255.960905ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:03.159402Z","caller":"traceutil/trace.go:171","msg":"trace[908071595] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"299.788797ms","start":"2026-07-01T01:52:02.859603Z","end":"2026-07-01T01:52:03.159392Z","steps":["trace[908071595] 'process raft request' (duration: 135.845151ms)","trace[908071595] 'compare' (duration: 163.209962ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:03.159702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.125856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-07-01T01:52:03.15973Z","caller":"traceutil/trace.go:171","msg":"trace[1600284877] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25092; }","duration":"297.185607ms","start":"2026-07-01T01:52:02.862536Z","end":"2026-07-01T01:52:03.159722Z","steps":["trace[1600284877] 'agreement among raft nodes before linearized reading' (duration: 297.071635ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:03.159789Z","caller":"traceutil/trace.go:171","msg":"trace[958103128] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"264.507297ms","start":"2026-07-01T01:52:02.895273Z","end":"2026-07-01T01:52:03.15978Z","steps":["trace[958103128] 'process raft request' (duration: 263.906756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:03.160433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.015429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-01T01:52:03.160775Z","caller":"traceutil/trace.go:171","msg":"trace[1832285930] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:25092; }","duration":"232.157783ms","start":"2026-07-01T01:52:02.928349Z","end":"2026-07-01T01:52:03.160507Z","steps":["trace[1832285930] 'agreement among raft nodes before linearized reading' (duration: 231.114702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:03.690591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186150426970258,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T01:52:03.718082Z","caller":"traceutil/trace.go:171","msg":"trace[1627941006] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"533.763514ms","start":"2026-07-01T01:52:03.184289Z","end":"2026-07-01T01:52:03.718053Z","steps":["trace[1627941006] 'process raft request' (duration: 533.56926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:03.71824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.184258Z","time spent":"533.896907ms","remote":"127.0.0.1:41806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:52:04.028949Z","caller":"traceutil/trace.go:171","msg":"trace[196569117] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"306.953524ms","start":"2026-07-01T01:52:03.721956Z","end":"2026-07-01T01:52:04.028909Z","steps":["trace[196569117] 'process raft request' (duration: 306.897223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.029186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.721938Z","time spent":"307.127687ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:52:04.029202Z","caller":"traceutil/trace.go:171","msg":"trace[1950100005] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"645.250123ms","start":"2026-07-01T01:52:03.383922Z","end":"2026-07-01T01:52:04.029172Z","steps":["trace[1950100005] 'process raft request' (duration: 644.843526ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:04.02927Z","caller":"traceutil/trace.go:171","msg":"trace[17704704] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"843.41763ms","start":"2026-07-01T01:52:03.185812Z","end":"2026-07-01T01:52:04.029229Z","steps":["trace[17704704] 'process raft request' (duration: 780.774139ms)","trace[17704704] 'compare' (duration: 61.965508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:04.029339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.38389Z","time spent":"645.376175ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:52:04.029472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.185795Z","time spent":"843.575192ms","remote":"127.0.0.1:41854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:52:04.029477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.112498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-07-01T01:52:04.029536Z","caller":"traceutil/trace.go:171","msg":"trace[254486343] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25096; }","duration":"839.195119ms","start":"2026-07-01T01:52:03.19033Z","end":"2026-07-01T01:52:04.029525Z","steps":["trace[254486343] 'agreement among raft nodes before linearized reading' (duration: 839.027236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.029568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.190324Z","time spent":"839.23826ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9626,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"info","ts":"2026-07-01T01:52:04.02985Z","caller":"traceutil/trace.go:171","msg":"trace[509175459] linearizableReadLoop","detail":"{readStateIndex:25918; appliedIndex:25916; }","duration":"838.924323ms","start":"2026-07-01T01:52:03.190349Z","end":"2026-07-01T01:52:04.029273Z","steps":["trace[509175459] 'read index received' (duration: 527.693409ms)","trace[509175459] 'applied index is now lower than readState.Index' (duration: 311.229594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:04.03005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.756623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:52:04.030091Z","caller":"traceutil/trace.go:171","msg":"trace[1310112911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25096; }","duration":"213.832094ms","start":"2026-07-01T01:52:03.816249Z","end":"2026-07-01T01:52:04.030081Z","steps":["trace[1310112911] 'agreement among raft nodes before linearized reading' (duration: 213.760413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.030125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.110784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T01:52:04.03017Z","caller":"traceutil/trace.go:171","msg":"trace[2143589904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25096; }","duration":"732.191885ms","start":"2026-07-01T01:52:03.297966Z","end":"2026-07-01T01:52:04.030158Z","steps":["trace[2143589904] 'agreement among raft nodes before linearized reading' (duration: 732.034253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.030206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.297949Z","time spent":"732.249386ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T01:52:04.030286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.680554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41db2e65-c1b4-4c4d-82f5-c436b5545a9a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-07-01T01:52:04.030326Z","caller":"traceutil/trace.go:171","msg":"trace[51155316] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41db2e65-c1b4-4c4d-82f5-c436b5545a9a; range_end:; response_count:1; response_revision:25096; }","duration":"180.852997ms","start":"2026-07-01T01:52:03.849464Z","end":"2026-07-01T01:52:04.030317Z","steps":["trace[51155316] 'agreement among raft nodes before linearized reading' (duration: 180.735815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.030242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.971835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-01T01:52:04.0304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.264787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-01T01:52:04.030428Z","caller":"traceutil/trace.go:171","msg":"trace[94501385] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25096; }","duration":"175.24158ms","start":"2026-07-01T01:52:03.855179Z","end":"2026-07-01T01:52:04.03042Z","steps":["trace[94501385] 'agreement among raft nodes before linearized reading' (duration: 174.914524ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:04.030432Z","caller":"traceutil/trace.go:171","msg":"trace[793977789] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25096; }","duration":"310.329358ms","start":"2026-07-01T01:52:03.720094Z","end":"2026-07-01T01:52:04.030423Z","steps":["trace[793977789] 'agreement among raft nodes before linearized reading' (duration: 310.261587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.03046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.720077Z","time spent":"310.377969ms","remote":"127.0.0.1:41952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-01T01:52:04.030684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.138317ms","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-07-01T01:52:04.030721Z","caller":"traceutil/trace.go:171","msg":"trace[1941143012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25096; }","duration":"680.217748ms","start":"2026-07-01T01:52:03.350494Z","end":"2026-07-01T01:52:04.030712Z","steps":["trace[1941143012] 'agreement among raft nodes before linearized reading' (duration: 680.122186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.030756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.350473Z","time spent":"680.273699ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T01:52:04.030844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.724335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T01:52:04.030942Z","caller":"traceutil/trace.go:171","msg":"trace[2036913051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25096; }","duration":"732.867997ms","start":"2026-07-01T01:52:03.298048Z","end":"2026-07-01T01:52:04.030916Z","steps":["trace[2036913051] 'agreement among raft nodes before linearized reading' (duration: 732.632883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:04.031172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:03.298018Z","time spent":"733.132893ms","remote":"127.0.0.1:42066","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":"info","ts":"2026-07-01T01:52:06.2947Z","caller":"traceutil/trace.go:171","msg":"trace[2682384] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"167.484843ms","start":"2026-07-01T01:52:06.127191Z","end":"2026-07-01T01:52:06.294676Z","steps":["trace[2682384] 'process raft request' (duration: 89.031672ms)","trace[2682384] 'compare' (duration: 78.284488ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:06.294882Z","caller":"traceutil/trace.go:171","msg":"trace[1493214739] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"167.594735ms","start":"2026-07-01T01:52:06.127214Z","end":"2026-07-01T01:52:06.294809Z","steps":["trace[1493214739] 'process raft request' (duration: 167.416982ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:06.3361Z","caller":"traceutil/trace.go:171","msg":"trace[545485768] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"125.922753ms","start":"2026-07-01T01:52:06.210152Z","end":"2026-07-01T01:52:06.336074Z","steps":["trace[545485768] 'process raft request' (duration: 125.876723ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:06.336161Z","caller":"traceutil/trace.go:171","msg":"trace[1111807294] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"208.449001ms","start":"2026-07-01T01:52:06.12768Z","end":"2026-07-01T01:52:06.336129Z","steps":["trace[1111807294] 'process raft request' (duration: 208.174485ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:06.340244Z","caller":"traceutil/trace.go:171","msg":"trace[134952515] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"115.286592ms","start":"2026-07-01T01:52:06.224942Z","end":"2026-07-01T01:52:06.340229Z","steps":["trace[134952515] 'process raft request' (duration: 115.171419ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:06.340503Z","caller":"traceutil/trace.go:171","msg":"trace[976249542] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"112.806424ms","start":"2026-07-01T01:52:06.227676Z","end":"2026-07-01T01:52:06.340482Z","steps":["trace[976249542] 'process raft request' (duration: 112.517548ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:06.34058Z","caller":"traceutil/trace.go:171","msg":"trace[1030387378] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"111.810145ms","start":"2026-07-01T01:52:06.228756Z","end":"2026-07-01T01:52:06.340566Z","steps":["trace[1030387378] 'process raft request' (duration: 111.635412ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:06.340582Z","caller":"traceutil/trace.go:171","msg":"trace[1260632040] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"111.606121ms","start":"2026-07-01T01:52:06.228955Z","end":"2026-07-01T01:52:06.340561Z","steps":["trace[1260632040] 'process raft request' (duration: 111.532ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.274686Z","caller":"traceutil/trace.go:171","msg":"trace[892421110] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"157.441932ms","start":"2026-07-01T01:52:08.117218Z","end":"2026-07-01T01:52:08.27466Z","steps":["trace[892421110] 'process raft request' (duration: 129.725845ms)","trace[892421110] 'compare' (duration: 27.525994ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:08.277454Z","caller":"traceutil/trace.go:171","msg":"trace[9735215] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"148.766987ms","start":"2026-07-01T01:52:08.128421Z","end":"2026-07-01T01:52:08.277188Z","steps":["trace[9735215] 'process raft request' (duration: 148.605694ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.484738Z","caller":"traceutil/trace.go:171","msg":"trace[1621981723] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"182.027089ms","start":"2026-07-01T01:52:08.302689Z","end":"2026-07-01T01:52:08.484716Z","steps":["trace[1621981723] 'process raft request' (duration: 181.881757ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.555606Z","caller":"traceutil/trace.go:171","msg":"trace[1472467299] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"211.131873ms","start":"2026-07-01T01:52:08.344452Z","end":"2026-07-01T01:52:08.555583Z","steps":["trace[1472467299] 'process raft request' (duration: 210.775106ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.555635Z","caller":"traceutil/trace.go:171","msg":"trace[1529699394] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"252.744684ms","start":"2026-07-01T01:52:08.302866Z","end":"2026-07-01T01:52:08.555611Z","steps":["trace[1529699394] 'process raft request' (duration: 252.228924ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.555687Z","caller":"traceutil/trace.go:171","msg":"trace[1410254486] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"210.700095ms","start":"2026-07-01T01:52:08.344959Z","end":"2026-07-01T01:52:08.555659Z","steps":["trace[1410254486] 'process raft request' (duration: 210.319428ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.555723Z","caller":"traceutil/trace.go:171","msg":"trace[636552854] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"211.078872ms","start":"2026-07-01T01:52:08.34461Z","end":"2026-07-01T01:52:08.555689Z","steps":["trace[636552854] 'process raft request' (duration: 210.645824ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.555784Z","caller":"traceutil/trace.go:171","msg":"trace[302468642] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"215.225791ms","start":"2026-07-01T01:52:08.340553Z","end":"2026-07-01T01:52:08.555779Z","steps":["trace[302468642] 'process raft request' (duration: 214.645951ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.555751Z","caller":"traceutil/trace.go:171","msg":"trace[2023872680] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"209.425901ms","start":"2026-07-01T01:52:08.346316Z","end":"2026-07-01T01:52:08.555742Z","steps":["trace[2023872680] 'process raft request' (duration: 208.992383ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:08.559651Z","caller":"traceutil/trace.go:171","msg":"trace[1484953478] linearizableReadLoop","detail":"{readStateIndex:25966; appliedIndex:25964; }","duration":"219.867849ms","start":"2026-07-01T01:52:08.339775Z","end":"2026-07-01T01:52:08.559643Z","steps":["trace[1484953478] 'read index received' (duration: 144.893553ms)","trace[1484953478] 'applied index is now lower than readState.Index' (duration: 74.973326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:08.559737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.96519ms","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-07-01T01:52:08.559754Z","caller":"traceutil/trace.go:171","msg":"trace[568962229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25148; }","duration":"220.026342ms","start":"2026-07-01T01:52:08.339724Z","end":"2026-07-01T01:52:08.55975Z","steps":["trace[568962229] 'agreement among raft nodes before linearized reading' (duration: 219.94877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:33.379593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.988832ms","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-07-01T01:52:33.379661Z","caller":"traceutil/trace.go:171","msg":"trace[922032052] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25355; }","duration":"250.111054ms","start":"2026-07-01T01:52:33.129537Z","end":"2026-07-01T01:52:33.379648Z","steps":["trace[922032052] 'count revisions from in-memory index tree' (duration: 249.86935ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:33.419056Z","caller":"traceutil/trace.go:171","msg":"trace[1012673920] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"127.505303ms","start":"2026-07-01T01:52:33.29152Z","end":"2026-07-01T01:52:33.419025Z","steps":["trace[1012673920] 'process raft request' (duration: 127.35531ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:34.49118Z","caller":"traceutil/trace.go:171","msg":"trace[428498568] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"109.149214ms","start":"2026-07-01T01:52:34.381995Z","end":"2026-07-01T01:52:34.491144Z","steps":["trace[428498568] 'process raft request' (duration: 108.649895ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:34.775509Z","caller":"traceutil/trace.go:171","msg":"trace[749385965] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"140.737515ms","start":"2026-07-01T01:52:34.634746Z","end":"2026-07-01T01:52:34.775484Z","steps":["trace[749385965] 'process raft request' (duration: 140.50481ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:34.996253Z","caller":"traceutil/trace.go:171","msg":"trace[1508921190] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"316.591307ms","start":"2026-07-01T01:52:34.679639Z","end":"2026-07-01T01:52:34.99623Z","steps":["trace[1508921190] 'process raft request' (duration: 316.535676ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:34.996269Z","caller":"traceutil/trace.go:171","msg":"trace[1041351876] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"320.99703ms","start":"2026-07-01T01:52:34.675248Z","end":"2026-07-01T01:52:34.996245Z","steps":["trace[1041351876] 'process raft request' (duration: 320.868138ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:34.996287Z","caller":"traceutil/trace.go:171","msg":"trace[732334304] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"316.716159ms","start":"2026-07-01T01:52:34.679553Z","end":"2026-07-01T01:52:34.996269Z","steps":["trace[732334304] 'process raft request' (duration: 316.598767ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:34.996348Z","caller":"traceutil/trace.go:171","msg":"trace[977489860] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"321.094902ms","start":"2026-07-01T01:52:34.675209Z","end":"2026-07-01T01:52:34.996304Z","steps":["trace[977489860] 'process raft request' (duration: 320.750766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:34.996387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:34.679517Z","time spent":"316.80986ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:52:34.996375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:34.679608Z","time spent":"316.703248ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:52:34.996384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:34.67522Z","time spent":"321.102152ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:52:34.996442Z","caller":"traceutil/trace.go:171","msg":"trace[1223586356] linearizableReadLoop","detail":"{readStateIndex:26194; appliedIndex:26188; }","duration":"298.90963ms","start":"2026-07-01T01:52:34.697508Z","end":"2026-07-01T01:52:34.996417Z","steps":["trace[1223586356] 'read index received' (duration: 77.837148ms)","trace[1223586356] 'applied index is now lower than readState.Index' (duration: 221.070422ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:34.996488Z","caller":"traceutil/trace.go:171","msg":"trace[1118771196] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"316.271191ms","start":"2026-07-01T01:52:34.680209Z","end":"2026-07-01T01:52:34.99648Z","steps":["trace[1118771196] 'process raft request' (duration: 315.990256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:34.996531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:34.680198Z","time spent":"316.309622ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:52:34.996538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:34.675189Z","time spent":"321.236935ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:52:34.996639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.322178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T01:52:34.996653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.143875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T01:52:34.99666Z","caller":"traceutil/trace.go:171","msg":"trace[2098578237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25365; }","duration":"179.342918ms","start":"2026-07-01T01:52:34.817311Z","end":"2026-07-01T01:52:34.996653Z","steps":["trace[2098578237] 'agreement among raft nodes before linearized reading' (duration: 179.306028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:34.996657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.484346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T01:52:34.996678Z","caller":"traceutil/trace.go:171","msg":"trace[1905010294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25365; }","duration":"299.240678ms","start":"2026-07-01T01:52:34.697429Z","end":"2026-07-01T01:52:34.996669Z","steps":["trace[1905010294] 'agreement among raft nodes before linearized reading' (duration: 299.101454ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:34.996682Z","caller":"traceutil/trace.go:171","msg":"trace[1746610927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25365; }","duration":"204.549229ms","start":"2026-07-01T01:52:34.792125Z","end":"2026-07-01T01:52:34.996675Z","steps":["trace[1746610927] 'agreement among raft nodes before linearized reading' (duration: 204.447936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:34.996775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.176471ms","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-07-01T01:52:34.996811Z","caller":"traceutil/trace.go:171","msg":"trace[264519142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25365; }","duration":"204.231362ms","start":"2026-07-01T01:52:34.792567Z","end":"2026-07-01T01:52:34.996799Z","steps":["trace[264519142] 'agreement among raft nodes before linearized reading' (duration: 204.057128ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:35.218742Z","caller":"traceutil/trace.go:171","msg":"trace[1876998139] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"129.9428ms","start":"2026-07-01T01:52:35.088758Z","end":"2026-07-01T01:52:35.2187Z","steps":["trace[1876998139] 'process raft request' (duration: 49.550852ms)","trace[1876998139] 'compare' (duration: 80.056112ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:35.429074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.045174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T01:52:35.429134Z","caller":"traceutil/trace.go:171","msg":"trace[345112412] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25369; }","duration":"107.143396ms","start":"2026-07-01T01:52:35.321977Z","end":"2026-07-01T01:52:35.429121Z","steps":["trace[345112412] 'count revisions from in-memory index tree' (duration: 106.918372ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:35.429249Z","caller":"traceutil/trace.go:171","msg":"trace[1917467085] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"102.030169ms","start":"2026-07-01T01:52:35.327193Z","end":"2026-07-01T01:52:35.429223Z","steps":["trace[1917467085] 'compare' (duration: 92.941446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:35.656452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.509438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T01:52:35.656581Z","caller":"traceutil/trace.go:171","msg":"trace[133957818] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"228.012854ms","start":"2026-07-01T01:52:35.428558Z","end":"2026-07-01T01:52:35.65657Z","steps":["trace[133957818] 'process raft request' (duration: 227.966573ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:35.656662Z","caller":"traceutil/trace.go:171","msg":"trace[1710293500] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"232.59161ms","start":"2026-07-01T01:52:35.424033Z","end":"2026-07-01T01:52:35.656625Z","steps":["trace[1710293500] 'process raft request' (duration: 128.848688ms)","trace[1710293500] 'compare' (duration: 103.189821ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:36.896095Z","caller":"traceutil/trace.go:171","msg":"trace[1584210330] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"111.917498ms","start":"2026-07-01T01:52:36.784137Z","end":"2026-07-01T01:52:36.896055Z","steps":["trace[1584210330] 'process raft request' (duration: 111.683523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:37.032968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.14861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:52:37.033072Z","caller":"traceutil/trace.go:171","msg":"trace[983089037] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25375; }","duration":"124.314172ms","start":"2026-07-01T01:52:36.908729Z","end":"2026-07-01T01:52:37.033044Z","steps":["trace[983089037] 'count revisions from in-memory index tree' (duration: 124.007197ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:37.244799Z","caller":"traceutil/trace.go:171","msg":"trace[551554364] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"152.10811ms","start":"2026-07-01T01:52:37.092664Z","end":"2026-07-01T01:52:37.244772Z","steps":["trace[551554364] 'process raft request' (duration: 151.929787ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:37.379852Z","caller":"traceutil/trace.go:171","msg":"trace[2043052991] linearizableReadLoop","detail":"{readStateIndex:26212; appliedIndex:26209; }","duration":"212.523099ms","start":"2026-07-01T01:52:37.167289Z","end":"2026-07-01T01:52:37.379812Z","steps":["trace[2043052991] 'read index received' (duration: 77.348209ms)","trace[2043052991] 'applied index is now lower than readState.Index' (duration: 135.17329ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:37.380163Z","caller":"traceutil/trace.go:171","msg":"trace[1969019521] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"287.060606ms","start":"2026-07-01T01:52:37.093073Z","end":"2026-07-01T01:52:37.380133Z","steps":["trace[1969019521] 'process raft request' (duration: 218.253058ms)","trace[1969019521] 'compare' (duration: 68.211186ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:37.380224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.923927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-07-01T01:52:37.380271Z","caller":"traceutil/trace.go:171","msg":"trace[1170316651] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"286.399904ms","start":"2026-07-01T01:52:37.093852Z","end":"2026-07-01T01:52:37.380252Z","steps":["trace[1170316651] 'process raft request' (duration: 285.897045ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:37.380306Z","caller":"traceutil/trace.go:171","msg":"trace[1563489860] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25383; }","duration":"213.06818ms","start":"2026-07-01T01:52:37.167216Z","end":"2026-07-01T01:52:37.380285Z","steps":["trace[1563489860] 'agreement among raft nodes before linearized reading' (duration: 212.815835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:37.38042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.670717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-01T01:52:37.380573Z","caller":"traceutil/trace.go:171","msg":"trace[1484793089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25383; }","duration":"157.906131ms","start":"2026-07-01T01:52:37.222615Z","end":"2026-07-01T01:52:37.380521Z","steps":["trace[1484793089] 'agreement among raft nodes before linearized reading' (duration: 157.474703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:47.609214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.368625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T01:52:47.609297Z","caller":"traceutil/trace.go:171","msg":"trace[229886792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25466; }","duration":"184.500407ms","start":"2026-07-01T01:52:47.424778Z","end":"2026-07-01T01:52:47.609278Z","steps":["trace[229886792] 'range keys from in-memory index tree' (duration: 184.204601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:47.609416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.037426ms","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-07-01T01:52:47.609511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.416402ms","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-07-01T01:52:47.60959Z","caller":"traceutil/trace.go:171","msg":"trace[23267808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25466; }","duration":"107.489853ms","start":"2026-07-01T01:52:47.502085Z","end":"2026-07-01T01:52:47.609575Z","steps":["trace[23267808] 'range keys from in-memory index tree' (duration: 107.31456ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:47.609594Z","caller":"traceutil/trace.go:171","msg":"trace[106319521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25466; }","duration":"176.293601ms","start":"2026-07-01T01:52:47.433228Z","end":"2026-07-01T01:52:47.609522Z","steps":["trace[106319521] 'range keys from in-memory index tree' (duration: 175.891373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:47.609224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.814477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:52:47.610092Z","caller":"traceutil/trace.go:171","msg":"trace[1842731629] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25466; }","duration":"121.692353ms","start":"2026-07-01T01:52:47.488362Z","end":"2026-07-01T01:52:47.610055Z","steps":["trace[1842731629] 'count revisions from in-memory index tree' (duration: 120.753396ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:47.805618Z","caller":"traceutil/trace.go:171","msg":"trace[594044676] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"191.668512ms","start":"2026-07-01T01:52:47.613925Z","end":"2026-07-01T01:52:47.805594Z","steps":["trace[594044676] 'process raft request' (duration: 191.52576ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:47.919329Z","caller":"traceutil/trace.go:171","msg":"trace[1108886965] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"305.026738ms","start":"2026-07-01T01:52:47.614284Z","end":"2026-07-01T01:52:47.91931Z","steps":["trace[1108886965] 'process raft request' (duration: 304.948776ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:47.919359Z","caller":"traceutil/trace.go:171","msg":"trace[897922669] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"305.095819ms","start":"2026-07-01T01:52:47.614225Z","end":"2026-07-01T01:52:47.919321Z","steps":["trace[897922669] 'process raft request' (duration: 270.95975ms)","trace[897922669] 'compare' (duration: 33.953555ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T01:52:47.91949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:47.614261Z","time spent":"305.138579ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T01:52:47.919513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T01:52:47.614218Z","time spent":"305.206991ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T01:52:47.919506Z","caller":"traceutil/trace.go:171","msg":"trace[996788053] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"219.558693ms","start":"2026-07-01T01:52:47.699927Z","end":"2026-07-01T01:52:47.919486Z","steps":["trace[996788053] 'process raft request' (duration: 219.342719ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:52:47.919686Z","caller":"traceutil/trace.go:171","msg":"trace[1809922870] linearizableReadLoop","detail":"{readStateIndex:26302; appliedIndex:26298; }","duration":"103.246551ms","start":"2026-07-01T01:52:47.816432Z","end":"2026-07-01T01:52:47.919678Z","steps":["trace[1809922870] 'read index received' (duration: 68.763546ms)","trace[1809922870] 'applied index is now lower than readState.Index' (duration: 34.482475ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T01:52:47.9198Z","caller":"traceutil/trace.go:171","msg":"trace[1587190711] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"215.791131ms","start":"2026-07-01T01:52:47.70398Z","end":"2026-07-01T01:52:47.919771Z","steps":["trace[1587190711] 'process raft request' (duration: 215.439905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T01:52:47.919797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.353793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T01:52:47.919899Z","caller":"traceutil/trace.go:171","msg":"trace[1266803312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25471; }","duration":"103.498116ms","start":"2026-07-01T01:52:47.816394Z","end":"2026-07-01T01:52:47.919892Z","steps":["trace[1266803312] 'agreement among raft nodes before linearized reading' (duration: 103.353414ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:53:14.330159Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067db.wal"} {"level":"info","ts":"2026-07-01T01:53:49.811567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23132} {"level":"info","ts":"2026-07-01T01:53:49.937578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23132,"took":"121.442678ms","hash":4274314075,"current-db-size-bytes":58871808,"current-db-size":"59 MB","current-db-size-in-use-bytes":36458496,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-01T01:53:49.937662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4274314075,"revision":23132,"compact-revision":20155} {"level":"warn","ts":"2026-07-01T01:56:19.771423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.925942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/openstack-exporter\" ","response":"range_response_count:1 size:5416"} {"level":"info","ts":"2026-07-01T01:56:19.771535Z","caller":"traceutil/trace.go:171","msg":"trace[1307217688] range","detail":"{range_begin:/registry/deployments/openstack/openstack-exporter; range_end:; response_count:1; response_revision:27614; }","duration":"104.067115ms","start":"2026-07-01T01:56:19.667448Z","end":"2026-07-01T01:56:19.771515Z","steps":["trace[1307217688] 'agreement among raft nodes before linearized reading' (duration: 103.865011ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T01:58:49.818373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26097} {"level":"info","ts":"2026-07-01T01:58:49.962093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26097,"took":"139.180204ms","hash":123984139,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":34725888,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-01T01:58:49.962174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123984139,"revision":26097,"compact-revision":23132} {"level":"info","ts":"2026-07-01T01:59:28.109868Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T01:59:28.114474Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-01T01:59:28.114617Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-01T02:03:49.825082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28795} {"level":"info","ts":"2026-07-01T02:03:49.963777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28795,"took":"132.570166ms","hash":988462046,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T02:03:49.963832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":988462046,"revision":28795,"compact-revision":26097} {"level":"info","ts":"2026-07-01T02:08:49.833132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31083} {"level":"info","ts":"2026-07-01T02:08:49.964285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31083,"took":"126.719341ms","hash":2274107906,"current-db-size-bytes":60141568,"current-db-size":"60 MB","current-db-size-in-use-bytes":30814208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T02:08:49.964363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2274107906,"revision":31083,"compact-revision":28795} {"level":"info","ts":"2026-07-01T02:10:45.77091Z","caller":"traceutil/trace.go:171","msg":"trace[1805215437] transaction","detail":"{read_only:false; response_revision:34317; number_of_response:1; }","duration":"153.660714ms","start":"2026-07-01T02:10:45.617224Z","end":"2026-07-01T02:10:45.770884Z","steps":["trace[1805215437] 'process raft request' (duration: 153.49074ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T02:13:49.838866Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33376} {"level":"info","ts":"2026-07-01T02:13:49.98531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33376,"took":"141.810972ms","hash":435624858,"current-db-size-bytes":61186048,"current-db-size":"61 MB","current-db-size-in-use-bytes":32518144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T02:13:49.985387Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":435624858,"revision":33376,"compact-revision":31083} {"level":"info","ts":"2026-07-01T02:18:49.844784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35671} {"level":"info","ts":"2026-07-01T02:18:49.99208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35671,"took":"144.107956ms","hash":2677868331,"current-db-size-bytes":61607936,"current-db-size":"62 MB","current-db-size-in-use-bytes":31903744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T02:18:49.992153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2677868331,"revision":35671,"compact-revision":33376} {"level":"info","ts":"2026-07-01T02:20:41.084457Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T02:20:41.089528Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-01T02:20:41.08961Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-01T02:23:49.850807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37900} {"level":"info","ts":"2026-07-01T02:23:49.965058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37900,"took":"110.277809ms","hash":1343332160,"current-db-size-bytes":61607936,"current-db-size":"62 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T02:23:49.965106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1343332160,"revision":37900,"compact-revision":35671}