{"level":"warn","ts":"2026-10-15T14:16:44.286122Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:16:44.28624Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149: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.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149: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-10-15T14:16:44.286316Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:16:44.286334Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-10-15T14:16:44.286362Z","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-10-15T14:16:44.287067Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-10-15T14:16:44.287169Z","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.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149: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-10-15T14:16:44.29088Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.498422ms"} {"level":"info","ts":"2026-10-15T14:16:44.301145Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-10-15T14:16:44.301278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:16:44.301303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-10-15T14:16:44.301314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:16:44.301331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-10-15T14:16:44.301377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-10-15T14:16:44.309173Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:16:44.311498Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:16:44.313026Z","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-10-15T14:16:44.315636Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:16:44.315906Z","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-10-15T14:16:44.316071Z","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-10-15T14:16:44.316082Z","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-10-15T14:16:44.316615Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:16:44.317686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-10-15T14:16:44.317819Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-10-15T14:16:44.321236Z","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-10-15T14:16:44.321337Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-10-15T14:16:44.321399Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-10-15T14:16:44.321614Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:16:44.321658Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:16:44.802197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:16:44.802277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:16:44.802339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-10-15T14:16:44.802366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:16:44.802383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-10-15T14:16:44.802404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-10-15T14:16:44.802425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-10-15T14:16:44.803678Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:44.805531Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:44.805619Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:44.805655Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:44.805697Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:16:44.805934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:16:44.806551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:16:44.806829Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:16:44.806856Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:16:44.809479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:16:44.814117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-10-15T14:17:38.381275Z","caller":"traceutil/trace.go:171","msg":"trace[1765582152] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"298.119572ms","start":"2026-10-15T14:17:38.083124Z","end":"2026-10-15T14:17:38.381244Z","steps":["trace[1765582152] 'read index received' (duration: 297.325807ms)","trace[1765582152] 'applied index is now lower than readState.Index' (duration: 792.645µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:38.381415Z","caller":"traceutil/trace.go:171","msg":"trace[88623460] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"344.524504ms","start":"2026-10-15T14:17:38.036871Z","end":"2026-10-15T14:17:38.381396Z","steps":["trace[88623460] 'process raft request' (duration: 343.734217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:38.381515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.359287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-15T14:17:38.381617Z","caller":"traceutil/trace.go:171","msg":"trace[1790323445] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:655; }","duration":"298.515971ms","start":"2026-10-15T14:17:38.083087Z","end":"2026-10-15T14:17:38.381603Z","steps":["trace[1790323445] 'agreement among raft nodes before linearized reading' (duration: 298.342928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:38.38212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.036833Z","time spent":"344.637746ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:17:39.245857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.379784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:17:39.246119Z","caller":"traceutil/trace.go:171","msg":"trace[334298995] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.146890424s","start":"2026-10-15T14:17:38.099185Z","end":"2026-10-15T14:17:39.246076Z","steps":["trace[334298995] 'process raft request' (duration: 298.061752ms)","trace[334298995] 'compare' (duration: 848.245019ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:39.246197Z","caller":"traceutil/trace.go:171","msg":"trace[240140286] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"773.422038ms","start":"2026-10-15T14:17:38.472757Z","end":"2026-10-15T14:17:39.246179Z","steps":["trace[240140286] 'process raft request' (duration: 773.295864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.246261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.47272Z","time spent":"773.515049ms","remote":"127.0.0.1:34178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:17:39.246337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.099156Z","time spent":"1.147069447s","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:17:39.246115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.383125Z","time spent":"862.978963ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-15T14:17:39.246559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.184563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p5zgv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T14:17:39.246633Z","caller":"traceutil/trace.go:171","msg":"trace[659951756] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p5zgv; range_end:; response_count:1; response_revision:657; }","duration":"855.333337ms","start":"2026-10-15T14:17:38.391273Z","end":"2026-10-15T14:17:39.246606Z","steps":["trace[659951756] 'agreement among raft nodes before linearized reading' (duration: 855.032981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.24669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.391219Z","time spent":"855.458678ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-p5zgv\" "} {"level":"info","ts":"2026-10-15T14:17:39.246776Z","caller":"traceutil/trace.go:171","msg":"trace[476083120] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"854.906737ms","start":"2026-10-15T14:17:38.391375Z","end":"2026-10-15T14:17:39.246282Z","steps":["trace[476083120] 'read index received' (duration: 5.888241ms)","trace[476083120] 'applied index is now lower than readState.Index' (duration: 848.920244ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:17:39.247177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.369804ms","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-10-15T14:17:39.247239Z","caller":"traceutil/trace.go:171","msg":"trace[1022616835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"293.472186ms","start":"2026-10-15T14:17:38.953754Z","end":"2026-10-15T14:17:39.247226Z","steps":["trace[1022616835] 'agreement among raft nodes before linearized reading' (duration: 293.289043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.247288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.10717ms","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-10-15T14:17:39.247364Z","caller":"traceutil/trace.go:171","msg":"trace[1341005000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"353.269823ms","start":"2026-10-15T14:17:38.894072Z","end":"2026-10-15T14:17:39.247341Z","steps":["trace[1341005000] 'agreement among raft nodes before linearized reading' (duration: 352.928736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.247375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.997256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T14:17:39.247378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.878461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-15T14:17:39.247425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.893946Z","time spent":"353.464807ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T14:17:39.247439Z","caller":"traceutil/trace.go:171","msg":"trace[1872270156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"344.959021ms","start":"2026-10-15T14:17:38.902464Z","end":"2026-10-15T14:17:39.247423Z","steps":["trace[1872270156] 'agreement among raft nodes before linearized reading' (duration: 344.799358ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:17:39.247435Z","caller":"traceutil/trace.go:171","msg":"trace[123388023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"350.074677ms","start":"2026-10-15T14:17:38.897339Z","end":"2026-10-15T14:17:39.247414Z","steps":["trace[123388023] 'agreement among raft nodes before linearized reading' (duration: 349.931415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.24749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.902454Z","time spent":"345.025004ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T14:17:39.247591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.897327Z","time spent":"350.249271ms","remote":"127.0.0.1:33954","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":"info","ts":"2026-10-15T14:19:11.805982Z","caller":"traceutil/trace.go:171","msg":"trace[409517042] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"210.473999ms","start":"2026-10-15T14:19:11.595475Z","end":"2026-10-15T14:19:11.805949Z","steps":["trace[409517042] 'read index received' (duration: 210.255705ms)","trace[409517042] 'applied index is now lower than readState.Index' (duration: 217.104µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:11.806104Z","caller":"traceutil/trace.go:171","msg":"trace[938259878] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"255.598987ms","start":"2026-10-15T14:19:11.550484Z","end":"2026-10-15T14:19:11.806083Z","steps":["trace[938259878] 'process raft request' (duration: 255.309601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:11.80625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.753985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e8dae14-9db6-431b-8ee0-26a5525a3927\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-15T14:19:11.806267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.907696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:11.806374Z","caller":"traceutil/trace.go:171","msg":"trace[1527864418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1891; }","duration":"112.071349ms","start":"2026-10-15T14:19:11.694276Z","end":"2026-10-15T14:19:11.806348Z","steps":["trace[1527864418] 'agreement among raft nodes before linearized reading' (duration: 111.881915ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:11.806302Z","caller":"traceutil/trace.go:171","msg":"trace[583076155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e8dae14-9db6-431b-8ee0-26a5525a3927; range_end:; response_count:1; response_revision:1891; }","duration":"210.857038ms","start":"2026-10-15T14:19:11.595426Z","end":"2026-10-15T14:19:11.806283Z","steps":["trace[583076155] 'agreement among raft nodes before linearized reading' (duration: 210.674033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:51.805237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.066158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:51.80533Z","caller":"traceutil/trace.go:171","msg":"trace[816765484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2166; }","duration":"110.213372ms","start":"2026-10-15T14:19:51.695097Z","end":"2026-10-15T14:19:51.80531Z","steps":["trace[816765484] 'range keys from in-memory index tree' (duration: 109.975756ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:03.015338Z","caller":"traceutil/trace.go:171","msg":"trace[182522177] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"168.496708ms","start":"2026-10-15T14:20:02.846806Z","end":"2026-10-15T14:20:03.015303Z","steps":["trace[182522177] 'process raft request' (duration: 168.304414ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:21:26.296416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.160171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:21:26.296553Z","caller":"traceutil/trace.go:171","msg":"trace[1100860145] linearizableReadLoop","detail":"{readStateIndex:2982; appliedIndex:2981; }","duration":"244.559753ms","start":"2026-10-15T14:21:26.051973Z","end":"2026-10-15T14:21:26.296533Z","steps":["trace[1100860145] 'read index received' (duration: 78.164458ms)","trace[1100860145] 'applied index is now lower than readState.Index' (duration: 166.393505ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:21:26.296713Z","caller":"traceutil/trace.go:171","msg":"trace[85377706] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"287.996167ms","start":"2026-10-15T14:21:26.008688Z","end":"2026-10-15T14:21:26.296684Z","steps":["trace[85377706] 'process raft request' (duration: 121.429438ms)","trace[85377706] 'compare' (duration: 165.722572ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:21:26.296761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.778858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:21:26.296852Z","caller":"traceutil/trace.go:171","msg":"trace[1251211992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2868; }","duration":"244.895771ms","start":"2026-10-15T14:21:26.051939Z","end":"2026-10-15T14:21:26.296835Z","steps":["trace[1251211992] 'agreement among raft nodes before linearized reading' (duration: 244.725717ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:26.485444Z","caller":"traceutil/trace.go:171","msg":"trace[1713781724] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"130.813494ms","start":"2026-10-15T14:21:26.354607Z","end":"2026-10-15T14:21:26.48542Z","steps":["trace[1713781724] 'process raft request' (duration: 130.6545ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:12.601194Z","caller":"traceutil/trace.go:171","msg":"trace[1964464116] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"135.797958ms","start":"2026-10-15T14:26:12.465359Z","end":"2026-10-15T14:26:12.601157Z","steps":["trace[1964464116] 'process raft request' (duration: 134.638077ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:17.442537Z","caller":"traceutil/trace.go:171","msg":"trace[1019695912] linearizableReadLoop","detail":"{readStateIndex:5530; appliedIndex:5529; }","duration":"370.884371ms","start":"2026-10-15T14:26:17.071629Z","end":"2026-10-15T14:26:17.442513Z","steps":["trace[1019695912] 'read index received' (duration: 370.609676ms)","trace[1019695912] 'applied index is now lower than readState.Index' (duration: 273.755µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:17.44294Z","caller":"traceutil/trace.go:171","msg":"trace[2111531397] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"413.831723ms","start":"2026-10-15T14:26:17.029049Z","end":"2026-10-15T14:26:17.442881Z","steps":["trace[2111531397] 'process raft request' (duration: 413.267972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:17.443056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.39589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-15T14:26:17.443126Z","caller":"traceutil/trace.go:171","msg":"trace[562800369] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5323; }","duration":"371.516373ms","start":"2026-10-15T14:26:17.071589Z","end":"2026-10-15T14:26:17.443105Z","steps":["trace[562800369] 'agreement among raft nodes before linearized reading' (duration: 371.238448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:17.443126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:17.029032Z","time spent":"414.008276ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14481,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:26:17.44318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:17.07157Z","time spent":"371.596735ms","remote":"127.0.0.1:33890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":918,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"warn","ts":"2026-10-15T14:26:17.443357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.873914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a50ed959-5725-4812-95f8-1c1fe654159e\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T14:26:17.44341Z","caller":"traceutil/trace.go:171","msg":"trace[1279057000] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a50ed959-5725-4812-95f8-1c1fe654159e; range_end:; response_count:1; response_revision:5323; }","duration":"368.995217ms","start":"2026-10-15T14:26:17.074401Z","end":"2026-10-15T14:26:17.443396Z","steps":["trace[1279057000] 'agreement among raft nodes before linearized reading' (duration: 368.742373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:17.443479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:17.074388Z","time spent":"369.048367ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a50ed959-5725-4812-95f8-1c1fe654159e\" "} {"level":"warn","ts":"2026-10-15T14:26:17.443741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.013324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a50ed959-5725-4812-95f8-1c1fe654159e\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T14:26:17.443788Z","caller":"traceutil/trace.go:171","msg":"trace[1916170004] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a50ed959-5725-4812-95f8-1c1fe654159e; range_end:; response_count:1; response_revision:5323; }","duration":"321.064396ms","start":"2026-10-15T14:26:17.122713Z","end":"2026-10-15T14:26:17.443778Z","steps":["trace[1916170004] 'agreement among raft nodes before linearized reading' (duration: 320.936503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:17.443815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:17.122644Z","time spent":"321.164398ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a50ed959-5725-4812-95f8-1c1fe654159e\" "} {"level":"warn","ts":"2026-10-15T14:26:17.444065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.169875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786940"} {"level":"info","ts":"2026-10-15T14:26:17.444131Z","caller":"traceutil/trace.go:171","msg":"trace[366635881] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5323; }","duration":"301.277586ms","start":"2026-10-15T14:26:17.142837Z","end":"2026-10-15T14:26:17.444115Z","steps":["trace[366635881] 'agreement among raft nodes before linearized reading' (duration: 300.716986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:17.444175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:17.142817Z","time spent":"301.345287ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":786964,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-10-15T14:26:19.598748Z","caller":"traceutil/trace.go:171","msg":"trace[821569326] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"107.954483ms","start":"2026-10-15T14:26:19.490737Z","end":"2026-10-15T14:26:19.598691Z","steps":["trace[821569326] 'process raft request' (duration: 87.512541ms)","trace[821569326] 'compare' (duration: 20.224078ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:26:39.884703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.691144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T14:26:39.885412Z","caller":"traceutil/trace.go:171","msg":"trace[1885099853] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5730; }","duration":"148.413678ms","start":"2026-10-15T14:26:39.736963Z","end":"2026-10-15T14:26:39.885376Z","steps":["trace[1885099853] 'range keys from in-memory index tree' (duration: 147.577043ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:44.842427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-10-15T14:26:44.914255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"70.692886ms","hash":2165604637,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":31817728,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:26:44.914396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165604637,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-10-15T14:26:51.716112Z","caller":"traceutil/trace.go:171","msg":"trace[460664917] linearizableReadLoop","detail":"{readStateIndex:6123; appliedIndex:6122; }","duration":"165.916616ms","start":"2026-10-15T14:26:51.550172Z","end":"2026-10-15T14:26:51.716088Z","steps":["trace[460664917] 'read index received' (duration: 165.692972ms)","trace[460664917] 'applied index is now lower than readState.Index' (duration: 222.844µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:51.716238Z","caller":"traceutil/trace.go:171","msg":"trace[1505343569] transaction","detail":"{read_only:false; response_revision:5900; number_of_response:1; }","duration":"167.28451ms","start":"2026-10-15T14:26:51.548905Z","end":"2026-10-15T14:26:51.71619Z","steps":["trace[1505343569] 'process raft request' (duration: 167.022515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:51.716392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.198351ms","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-10-15T14:26:51.716476Z","caller":"traceutil/trace.go:171","msg":"trace[1694556399] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5900; }","duration":"166.329843ms","start":"2026-10-15T14:26:51.550124Z","end":"2026-10-15T14:26:51.716454Z","steps":["trace[1694556399] 'agreement among raft nodes before linearized reading' (duration: 166.096639ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:52.529Z","caller":"traceutil/trace.go:171","msg":"trace[1657038838] transaction","detail":"{read_only:false; response_revision:5910; number_of_response:1; }","duration":"172.918123ms","start":"2026-10-15T14:26:52.356009Z","end":"2026-10-15T14:26:52.528927Z","steps":["trace[1657038838] 'process raft request' (duration: 172.639098ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:31:44.849782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5803} {"level":"info","ts":"2026-10-15T14:31:44.946527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5803,"took":"94.426296ms","hash":2987487979,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":26673152,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T14:31:44.94662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2987487979,"revision":5803,"compact-revision":2970} {"level":"info","ts":"2026-10-15T14:32:46.686975Z","caller":"traceutil/trace.go:171","msg":"trace[1904544492] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8897; }","duration":"190.693015ms","start":"2026-10-15T14:32:46.496243Z","end":"2026-10-15T14:32:46.686936Z","steps":["trace[1904544492] 'read index received' (duration: 190.468381ms)","trace[1904544492] 'applied index is now lower than readState.Index' (duration: 223.574µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:32:46.687052Z","caller":"traceutil/trace.go:171","msg":"trace[453549518] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"240.646647ms","start":"2026-10-15T14:32:46.446361Z","end":"2026-10-15T14:32:46.687008Z","steps":["trace[453549518] 'process raft request' (duration: 240.428482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:32:46.687336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.043872ms","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-10-15T14:32:46.687454Z","caller":"traceutil/trace.go:171","msg":"trace[1061570574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8589; }","duration":"191.242266ms","start":"2026-10-15T14:32:46.49619Z","end":"2026-10-15T14:32:46.687432Z","steps":["trace[1061570574] 'agreement among raft nodes before linearized reading' (duration: 190.920499ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:46.913778Z","caller":"traceutil/trace.go:171","msg":"trace[1941032754] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"221.53758ms","start":"2026-10-15T14:32:46.692217Z","end":"2026-10-15T14:32:46.913754Z","steps":["trace[1941032754] 'read index received' (duration: 221.409087ms)","trace[1941032754] 'applied index is now lower than readState.Index' (duration: 127.823µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:32:46.913884Z","caller":"traceutil/trace.go:171","msg":"trace[2134158612] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"221.729353ms","start":"2026-10-15T14:32:46.692145Z","end":"2026-10-15T14:32:46.913874Z","steps":["trace[2134158612] 'process raft request' (duration: 221.474949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:32:46.914072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.828376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:32:46.914152Z","caller":"traceutil/trace.go:171","msg":"trace[207122756] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8590; }","duration":"221.921777ms","start":"2026-10-15T14:32:46.692211Z","end":"2026-10-15T14:32:46.914132Z","steps":["trace[207122756] 'agreement among raft nodes before linearized reading' (duration: 221.784765ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:46.916953Z","caller":"traceutil/trace.go:171","msg":"trace[625215752] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"224.510569ms","start":"2026-10-15T14:32:46.692419Z","end":"2026-10-15T14:32:46.916929Z","steps":["trace[625215752] 'process raft request' (duration: 224.366006ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:46.917215Z","caller":"traceutil/trace.go:171","msg":"trace[1475661460] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"224.774484ms","start":"2026-10-15T14:32:46.692419Z","end":"2026-10-15T14:32:46.917193Z","steps":["trace[1475661460] 'process raft request' (duration: 224.469159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:32:46.917246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.581982ms","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":"info","ts":"2026-10-15T14:32:46.917282Z","caller":"traceutil/trace.go:171","msg":"trace[1107267700] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8592; }","duration":"153.661944ms","start":"2026-10-15T14:32:46.763608Z","end":"2026-10-15T14:32:46.91727Z","steps":["trace[1107267700] 'agreement among raft nodes before linearized reading' (duration: 153.587232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:32:46.917241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.091841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:32:46.918856Z","caller":"traceutil/trace.go:171","msg":"trace[302906236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8592; }","duration":"223.713373ms","start":"2026-10-15T14:32:46.695103Z","end":"2026-10-15T14:32:46.918816Z","steps":["trace[302906236] 'agreement among raft nodes before linearized reading' (duration: 222.05953ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:58.847565Z","caller":"traceutil/trace.go:171","msg":"trace[1393296379] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"106.754703ms","start":"2026-10-15T14:32:58.740782Z","end":"2026-10-15T14:32:58.847537Z","steps":["trace[1393296379] 'process raft request' (duration: 106.555859ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:30.540198Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ef.wal"} {"level":"info","ts":"2026-10-15T14:33:36.307608Z","caller":"traceutil/trace.go:171","msg":"trace[1334299239] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"144.312015ms","start":"2026-10-15T14:33:36.163269Z","end":"2026-10-15T14:33:36.307581Z","steps":["trace[1334299239] 'process raft request' (duration: 144.06212ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:36.530779Z","caller":"traceutil/trace.go:171","msg":"trace[1249528758] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"196.745577ms","start":"2026-10-15T14:33:36.333968Z","end":"2026-10-15T14:33:36.530714Z","steps":["trace[1249528758] 'process raft request' (duration: 176.68544ms)","trace[1249528758] 'compare' (duration: 19.813062ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:33:41.264821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.360974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:33:41.264969Z","caller":"traceutil/trace.go:171","msg":"trace[2067494894] transaction","detail":"{read_only:false; response_revision:8944; number_of_response:1; }","duration":"191.467606ms","start":"2026-10-15T14:33:41.073492Z","end":"2026-10-15T14:33:41.26496Z","steps":["trace[2067494894] 'process raft request' (duration: 191.412155ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:41.264962Z","caller":"traceutil/trace.go:171","msg":"trace[211019245] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"196.186594ms","start":"2026-10-15T14:33:41.068713Z","end":"2026-10-15T14:33:41.2649Z","steps":["trace[211019245] 'process raft request' (duration: 58.687846ms)","trace[211019245] 'compare' (duration: 137.166002ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:41.265084Z","caller":"traceutil/trace.go:171","msg":"trace[111992110] linearizableReadLoop","detail":"{readStateIndex:9266; appliedIndex:9265; }","duration":"193.632973ms","start":"2026-10-15T14:33:41.07142Z","end":"2026-10-15T14:33:41.265053Z","steps":["trace[111992110] 'read index received' (duration: 55.990441ms)","trace[111992110] 'applied index is now lower than readState.Index' (duration: 137.638832ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:33:41.265263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.836107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T14:33:41.265292Z","caller":"traceutil/trace.go:171","msg":"trace[1272449523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8944; }","duration":"193.916147ms","start":"2026-10-15T14:33:41.071367Z","end":"2026-10-15T14:33:41.265283Z","steps":["trace[1272449523] 'agreement among raft nodes before linearized reading' (duration: 193.815425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:41.568782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.474739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:33:41.568986Z","caller":"traceutil/trace.go:171","msg":"trace[656186111] transaction","detail":"{read_only:false; response_revision:8947; number_of_response:1; }","duration":"208.369964ms","start":"2026-10-15T14:33:41.360594Z","end":"2026-10-15T14:33:41.568964Z","steps":["trace[656186111] 'process raft request' (duration: 208.281312ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:41.569081Z","caller":"traceutil/trace.go:171","msg":"trace[1181944617] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"298.150521ms","start":"2026-10-15T14:33:41.27088Z","end":"2026-10-15T14:33:41.56903Z","steps":["trace[1181944617] 'process raft request' (duration: 137.346614ms)","trace[1181944617] 'compare' (duration: 160.216754ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:41.569237Z","caller":"traceutil/trace.go:171","msg":"trace[1403038522] linearizableReadLoop","detail":"{readStateIndex:9269; appliedIndex:9268; }","duration":"293.187358ms","start":"2026-10-15T14:33:41.276023Z","end":"2026-10-15T14:33:41.569211Z","steps":["trace[1403038522] 'read index received' (duration: 3.194146ms)","trace[1403038522] 'applied index is now lower than readState.Index' (duration: 289.989022ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:33:41.569572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.546036ms","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-10-15T14:33:41.569642Z","caller":"traceutil/trace.go:171","msg":"trace[1278085729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8947; }","duration":"293.618486ms","start":"2026-10-15T14:33:41.276006Z","end":"2026-10-15T14:33:41.569624Z","steps":["trace[1278085729] 'agreement among raft nodes before linearized reading' (duration: 293.415152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:41.570249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.798265ms","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-10-15T14:33:41.570359Z","caller":"traceutil/trace.go:171","msg":"trace[1567374154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8947; }","duration":"283.905007ms","start":"2026-10-15T14:33:41.286367Z","end":"2026-10-15T14:33:41.570296Z","steps":["trace[1567374154] 'agreement among raft nodes before linearized reading' (duration: 283.388817ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:41.693086Z","caller":"traceutil/trace.go:171","msg":"trace[1442431615] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"118.092718ms","start":"2026-10-15T14:33:41.574961Z","end":"2026-10-15T14:33:41.693054Z","steps":["trace[1442431615] 'process raft request' (duration: 98.570087ms)","trace[1442431615] 'compare' (duration: 19.415059ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:34:48.008817Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b1b675f124322401","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:34:48.024733Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T14:34:48.024856Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T14:36:44.857842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8032} {"level":"info","ts":"2026-10-15T14:36:44.934137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8032,"took":"73.839133ms","hash":874485612,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":28663808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T14:36:44.934206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874485612,"revision":8032,"compact-revision":5803} {"level":"info","ts":"2026-10-15T14:38:49.471252Z","caller":"traceutil/trace.go:171","msg":"trace[1405694480] transaction","detail":"{read_only:false; response_revision:11617; number_of_response:1; }","duration":"131.477348ms","start":"2026-10-15T14:38:49.339746Z","end":"2026-10-15T14:38:49.471224Z","steps":["trace[1405694480] 'process raft request' (duration: 131.282134ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:38:49.487619Z","caller":"traceutil/trace.go:171","msg":"trace[1189083511] transaction","detail":"{read_only:false; response_revision:11618; number_of_response:1; }","duration":"147.168801ms","start":"2026-10-15T14:38:49.340415Z","end":"2026-10-15T14:38:49.487584Z","steps":["trace[1189083511] 'process raft request' (duration: 146.970776ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:41:44.865514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10460} {"level":"info","ts":"2026-10-15T14:41:44.969649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10460,"took":"100.851538ms","hash":3960812727,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":27627520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:41:44.969745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3960812727,"revision":10460,"compact-revision":8032} {"level":"info","ts":"2026-10-15T14:44:39.558021Z","caller":"traceutil/trace.go:171","msg":"trace[1458420271] transaction","detail":"{read_only:false; response_revision:14005; number_of_response:1; }","duration":"151.671947ms","start":"2026-10-15T14:44:39.406311Z","end":"2026-10-15T14:44:39.557983Z","steps":["trace[1458420271] 'process raft request' (duration: 142.435651ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:45:04.585956Z","caller":"traceutil/trace.go:171","msg":"trace[248742331] transaction","detail":"{read_only:false; response_revision:14140; number_of_response:1; }","duration":"110.201377ms","start":"2026-10-15T14:45:04.475711Z","end":"2026-10-15T14:45:04.585912Z","steps":["trace[248742331] 'process raft request' (duration: 109.892101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:45:23.167029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.753537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nbw25\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T14:45:23.167159Z","caller":"traceutil/trace.go:171","msg":"trace[400065438] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nbw25; range_end:; response_count:1; response_revision:14251; }","duration":"141.959441ms","start":"2026-10-15T14:45:23.025165Z","end":"2026-10-15T14:45:23.167125Z","steps":["trace[400065438] 'range keys from in-memory index tree' (duration: 141.473161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:45:34.321233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.49828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:45:34.321401Z","caller":"traceutil/trace.go:171","msg":"trace[829870821] linearizableReadLoop","detail":"{readStateIndex:14924; appliedIndex:14923; }","duration":"297.202646ms","start":"2026-10-15T14:45:34.024169Z","end":"2026-10-15T14:45:34.321371Z","steps":["trace[829870821] 'read index received' (duration: 93.791217ms)","trace[829870821] 'applied index is now lower than readState.Index' (duration: 203.408909ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:45:34.321462Z","caller":"traceutil/trace.go:171","msg":"trace[1539774425] transaction","detail":"{read_only:false; response_revision:14427; number_of_response:1; }","duration":"481.575842ms","start":"2026-10-15T14:45:33.839831Z","end":"2026-10-15T14:45:34.321407Z","steps":["trace[1539774425] 'process raft request' (duration: 278.242985ms)","trace[1539774425] 'compare' (duration: 202.314736ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:45:34.321679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:45:33.839792Z","time spent":"481.775337ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:45:34.321773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.540804ms","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-10-15T14:45:34.321834Z","caller":"traceutil/trace.go:171","msg":"trace[255363673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14427; }","duration":"297.612786ms","start":"2026-10-15T14:45:34.024204Z","end":"2026-10-15T14:45:34.321816Z","steps":["trace[255363673] 'agreement among raft nodes before linearized reading' (duration: 297.316459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:45:34.321804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.052206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-15T14:45:34.321806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.617926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T14:45:34.321943Z","caller":"traceutil/trace.go:171","msg":"trace[1810646221] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14427; }","duration":"122.25638ms","start":"2026-10-15T14:45:34.199657Z","end":"2026-10-15T14:45:34.321913Z","steps":["trace[1810646221] 'agreement among raft nodes before linearized reading' (duration: 121.917194ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:45:34.322598Z","caller":"traceutil/trace.go:171","msg":"trace[300581095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14427; }","duration":"297.83473ms","start":"2026-10-15T14:45:34.024087Z","end":"2026-10-15T14:45:34.321921Z","steps":["trace[300581095] 'agreement among raft nodes before linearized reading' (duration: 297.406422ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:46:44.873193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12778} {"level":"info","ts":"2026-10-15T14:46:44.969013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12778,"took":"92.515255ms","hash":3974514842,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":31080448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T14:46:44.969082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3974514842,"revision":12778,"compact-revision":10460} {"level":"info","ts":"2026-10-15T14:47:17.153811Z","caller":"traceutil/trace.go:171","msg":"trace[1968986597] linearizableReadLoop","detail":"{readStateIndex:16062; appliedIndex:16061; }","duration":"197.160969ms","start":"2026-10-15T14:47:16.956623Z","end":"2026-10-15T14:47:17.153784Z","steps":["trace[1968986597] 'read index received' (duration: 196.957374ms)","trace[1968986597] 'applied index is now lower than readState.Index' (duration: 202.605µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:47:17.15395Z","caller":"traceutil/trace.go:171","msg":"trace[274011985] transaction","detail":"{read_only:false; response_revision:15528; number_of_response:1; }","duration":"293.847492ms","start":"2026-10-15T14:47:16.860058Z","end":"2026-10-15T14:47:17.153906Z","steps":["trace[274011985] 'process raft request' (duration: 293.592987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:47:17.154153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.403909ms","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-10-15T14:47:17.154211Z","caller":"traceutil/trace.go:171","msg":"trace[1065893585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15528; }","duration":"186.504072ms","start":"2026-10-15T14:47:16.967694Z","end":"2026-10-15T14:47:17.154198Z","steps":["trace[1065893585] 'agreement among raft nodes before linearized reading' (duration: 186.238316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:47:17.154125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.484764ms","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-10-15T14:47:17.154369Z","caller":"traceutil/trace.go:171","msg":"trace[1981952072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15528; }","duration":"197.77977ms","start":"2026-10-15T14:47:16.956562Z","end":"2026-10-15T14:47:17.154342Z","steps":["trace[1981952072] 'agreement among raft nodes before linearized reading' (duration: 197.376422ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:17.333317Z","caller":"traceutil/trace.go:171","msg":"trace[93831655] transaction","detail":"{read_only:false; response_revision:15531; number_of_response:1; }","duration":"172.837091ms","start":"2026-10-15T14:47:17.160437Z","end":"2026-10-15T14:47:17.333274Z","steps":["trace[93831655] 'process raft request' (duration: 172.75016ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:17.333392Z","caller":"traceutil/trace.go:171","msg":"trace[2034057397] linearizableReadLoop","detail":"{readStateIndex:16065; appliedIndex:16063; }","duration":"137.739847ms","start":"2026-10-15T14:47:17.195626Z","end":"2026-10-15T14:47:17.333366Z","steps":["trace[2034057397] 'read index received' (duration: 136.643564ms)","trace[2034057397] 'applied index is now lower than readState.Index' (duration: 1.093942ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:47:17.333365Z","caller":"traceutil/trace.go:171","msg":"trace[139770033] transaction","detail":"{read_only:false; response_revision:15530; number_of_response:1; }","duration":"174.298263ms","start":"2026-10-15T14:47:17.158971Z","end":"2026-10-15T14:47:17.333269Z","steps":["trace[139770033] 'process raft request' (duration: 173.281882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:47:17.333533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.879379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:47:17.333591Z","caller":"traceutil/trace.go:171","msg":"trace[1898640828] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15532; }","duration":"137.975471ms","start":"2026-10-15T14:47:17.195601Z","end":"2026-10-15T14:47:17.333577Z","steps":["trace[1898640828] 'agreement among raft nodes before linearized reading' (duration: 137.867898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:47:17.333777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.96144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-15T14:47:17.333833Z","caller":"traceutil/trace.go:171","msg":"trace[82773659] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15532; }","duration":"138.038302ms","start":"2026-10-15T14:47:17.195783Z","end":"2026-10-15T14:47:17.333821Z","steps":["trace[82773659] 'agreement among raft nodes before linearized reading' (duration: 137.838648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:47:17.333841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.736646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T14:47:17.333912Z","caller":"traceutil/trace.go:171","msg":"trace[1029634698] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15532; }","duration":"121.942351ms","start":"2026-10-15T14:47:17.211957Z","end":"2026-10-15T14:47:17.333899Z","steps":["trace[1029634698] 'agreement among raft nodes before linearized reading' (duration: 121.674395ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:51:44.88359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15360} {"level":"info","ts":"2026-10-15T14:51:45.012074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15360,"took":"124.999301ms","hash":2378889633,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:51:45.012194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2378889633,"revision":15360,"compact-revision":12778} {"level":"info","ts":"2026-10-15T14:53:00.12268Z","caller":"traceutil/trace.go:171","msg":"trace[1548516657] linearizableReadLoop","detail":"{readStateIndex:18681; appliedIndex:18680; }","duration":"191.579268ms","start":"2026-10-15T14:52:59.931078Z","end":"2026-10-15T14:53:00.122657Z","steps":["trace[1548516657] 'read index received' (duration: 191.383094ms)","trace[1548516657] 'applied index is now lower than readState.Index' (duration: 195.284µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:53:00.122996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.888035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-10-15T14:53:00.123047Z","caller":"traceutil/trace.go:171","msg":"trace[106056177] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:18063; }","duration":"191.957947ms","start":"2026-10-15T14:52:59.931071Z","end":"2026-10-15T14:53:00.123029Z","steps":["trace[106056177] 'agreement among raft nodes before linearized reading' (duration: 191.722051ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:00.12347Z","caller":"traceutil/trace.go:171","msg":"trace[2084883591] transaction","detail":"{read_only:false; response_revision:18063; number_of_response:1; }","duration":"193.007279ms","start":"2026-10-15T14:52:59.93045Z","end":"2026-10-15T14:53:00.123457Z","steps":["trace[2084883591] 'process raft request' (duration: 192.088749ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:00.292885Z","caller":"traceutil/trace.go:171","msg":"trace[936880137] transaction","detail":"{read_only:false; response_revision:18064; number_of_response:1; }","duration":"161.693732ms","start":"2026-10-15T14:53:00.131148Z","end":"2026-10-15T14:53:00.292842Z","steps":["trace[936880137] 'process raft request' (duration: 161.565399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:00.589019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.882524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:53:00.589211Z","caller":"traceutil/trace.go:171","msg":"trace[773226247] linearizableReadLoop","detail":"{readStateIndex:18684; appliedIndex:18682; }","duration":"449.118486ms","start":"2026-10-15T14:53:00.140076Z","end":"2026-10-15T14:53:00.589194Z","steps":["trace[773226247] 'read index received' (duration: 152.788361ms)","trace[773226247] 'applied index is now lower than readState.Index' (duration: 296.328905ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:53:00.589307Z","caller":"traceutil/trace.go:171","msg":"trace[1304468691] transaction","detail":"{read_only:false; response_revision:18066; number_of_response:1; }","duration":"454.181445ms","start":"2026-10-15T14:53:00.135115Z","end":"2026-10-15T14:53:00.589297Z","steps":["trace[1304468691] 'process raft request' (duration: 454.00307ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:00.589383Z","caller":"traceutil/trace.go:171","msg":"trace[672915542] transaction","detail":"{read_only:false; response_revision:18065; number_of_response:1; }","duration":"456.723199ms","start":"2026-10-15T14:53:00.132639Z","end":"2026-10-15T14:53:00.589362Z","steps":["trace[672915542] 'process raft request' (duration: 279.419146ms)","trace[672915542] 'compare' (duration: 176.70679ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:53:00.589485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.13262Z","time spent":"456.81402ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":701,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:53:00.589638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.544655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"warn","ts":"2026-10-15T14:53:00.589519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.135089Z","time spent":"454.293057ms","remote":"127.0.0.1:34138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9233,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:53:00.589721Z","caller":"traceutil/trace.go:171","msg":"trace[1772409977] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:18066; }","duration":"449.656878ms","start":"2026-10-15T14:53:00.140042Z","end":"2026-10-15T14:53:00.589699Z","steps":["trace[1772409977] 'agreement among raft nodes before linearized reading' (duration: 449.405813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:00.589776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.140031Z","time spent":"449.73081ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":733,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-10-15T14:53:00.590226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.088223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-15T14:53:00.590264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.235929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q7vx6\" ","response":"range_response_count:1 size:20756"} {"level":"warn","ts":"2026-10-15T14:53:00.590265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.415482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jfz8c\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-15T14:53:00.590307Z","caller":"traceutil/trace.go:171","msg":"trace[1635741107] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-q7vx6; range_end:; response_count:1; response_revision:18066; }","duration":"384.314731ms","start":"2026-10-15T14:53:00.20598Z","end":"2026-10-15T14:53:00.590295Z","steps":["trace[1635741107] 'agreement among raft nodes before linearized reading' (duration: 384.167208ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:00.590313Z","caller":"traceutil/trace.go:171","msg":"trace[1947576071] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18066; }","duration":"411.221685ms","start":"2026-10-15T14:53:00.179069Z","end":"2026-10-15T14:53:00.590291Z","steps":["trace[1947576071] 'agreement among raft nodes before linearized reading' (duration: 410.977309ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:00.590323Z","caller":"traceutil/trace.go:171","msg":"trace[58124038] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jfz8c; range_end:; response_count:1; response_revision:18066; }","duration":"113.518674ms","start":"2026-10-15T14:53:00.476788Z","end":"2026-10-15T14:53:00.590306Z","steps":["trace[58124038] 'agreement among raft nodes before linearized reading' (duration: 113.363291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:00.590352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.17905Z","time spent":"411.293867ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:53:00.590378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.442797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:53:00.590449Z","caller":"traceutil/trace.go:171","msg":"trace[981573573] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:18066; }","duration":"288.549629ms","start":"2026-10-15T14:53:00.301876Z","end":"2026-10-15T14:53:00.590426Z","steps":["trace[981573573] 'agreement among raft nodes before linearized reading' (duration: 288.477147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:00.590337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.205959Z","time spent":"384.370522ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20780,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q7vx6\" "} {"level":"warn","ts":"2026-10-15T14:53:00.59137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.72379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-10-15T14:53:00.591416Z","caller":"traceutil/trace.go:171","msg":"trace[1697555006] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:18066; }","duration":"406.807602ms","start":"2026-10-15T14:53:00.184597Z","end":"2026-10-15T14:53:00.591405Z","steps":["trace[1697555006] 'agreement among raft nodes before linearized reading' (duration: 405.929143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:00.591445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.18458Z","time spent":"406.855962ms","remote":"127.0.0.1:33786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225433,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-10-15T14:53:00.591509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.230518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T14:53:00.592403Z","caller":"traceutil/trace.go:171","msg":"trace[1787743593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18066; }","duration":"407.161049ms","start":"2026-10-15T14:53:00.185225Z","end":"2026-10-15T14:53:00.592386Z","steps":["trace[1787743593] 'agreement among raft nodes before linearized reading' (duration: 405.060354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:00.592481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.185212Z","time spent":"407.252762ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T14:53:01.027732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.414961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:53:01.028085Z","caller":"traceutil/trace.go:171","msg":"trace[223289579] transaction","detail":"{read_only:false; response_revision:18068; number_of_response:1; }","duration":"434.241442ms","start":"2026-10-15T14:53:00.593797Z","end":"2026-10-15T14:53:01.028039Z","steps":["trace[223289579] 'process raft request' (duration: 168.410242ms)","trace[223289579] 'compare' (duration: 265.191587ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:53:01.028278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.593791Z","time spent":"434.407276ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:53:01.028395Z","caller":"traceutil/trace.go:171","msg":"trace[1797845103] transaction","detail":"{read_only:false; response_revision:18070; number_of_response:1; }","duration":"426.458296ms","start":"2026-10-15T14:53:00.601925Z","end":"2026-10-15T14:53:01.028383Z","steps":["trace[1797845103] 'process raft request' (duration: 426.105848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.028477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.601891Z","time spent":"426.544688ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:53:01.028518Z","caller":"traceutil/trace.go:171","msg":"trace[820734289] linearizableReadLoop","detail":"{readStateIndex:18686; appliedIndex:18685; }","duration":"431.598827ms","start":"2026-10-15T14:53:00.596847Z","end":"2026-10-15T14:53:01.028445Z","steps":["trace[820734289] 'read index received' (duration: 31.73295ms)","trace[820734289] 'applied index is now lower than readState.Index' (duration: 399.861887ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:53:01.028108Z","caller":"traceutil/trace.go:171","msg":"trace[102115356] transaction","detail":"{read_only:false; response_revision:18069; number_of_response:1; }","duration":"430.055273ms","start":"2026-10-15T14:53:00.598029Z","end":"2026-10-15T14:53:01.028084Z","steps":["trace[102115356] 'process raft request' (duration: 429.805518ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:01.028769Z","caller":"traceutil/trace.go:171","msg":"trace[1644515894] transaction","detail":"{read_only:false; response_revision:18071; number_of_response:1; }","duration":"426.432965ms","start":"2026-10-15T14:53:00.602326Z","end":"2026-10-15T14:53:01.028759Z","steps":["trace[1644515894] 'process raft request' (duration: 425.857723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.028832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.602302Z","time spent":"426.498397ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:53:01.028853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.598003Z","time spent":"430.759278ms","remote":"127.0.0.1:60126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3188,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:53:01.029231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.361332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-10-15T14:53:01.029286Z","caller":"traceutil/trace.go:171","msg":"trace[1190216863] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:18071; }","duration":"432.454225ms","start":"2026-10-15T14:53:00.596814Z","end":"2026-10-15T14:53:01.029269Z","steps":["trace[1190216863] 'agreement among raft nodes before linearized reading' (duration: 432.248061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.029504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.596804Z","time spent":"432.660089ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":733,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-10-15T14:53:01.186512Z","caller":"traceutil/trace.go:171","msg":"trace[894434623] transaction","detail":"{read_only:false; response_revision:18072; number_of_response:1; }","duration":"554.108783ms","start":"2026-10-15T14:53:00.63237Z","end":"2026-10-15T14:53:01.186479Z","steps":["trace[894434623] 'process raft request' (duration: 553.03069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.186761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.632344Z","time spent":"554.253807ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:53:01.187566Z","caller":"traceutil/trace.go:171","msg":"trace[107443428] transaction","detail":"{read_only:false; response_revision:18073; number_of_response:1; }","duration":"554.991322ms","start":"2026-10-15T14:53:00.632545Z","end":"2026-10-15T14:53:01.187536Z","steps":["trace[107443428] 'process raft request' (duration: 554.42486ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:01.187625Z","caller":"traceutil/trace.go:171","msg":"trace[1073050601] transaction","detail":"{read_only:false; response_revision:18074; number_of_response:1; }","duration":"552.215522ms","start":"2026-10-15T14:53:00.635395Z","end":"2026-10-15T14:53:01.18761Z","steps":["trace[1073050601] 'process raft request' (duration: 552.023788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.187705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.63537Z","time spent":"552.300735ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9090,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:53:01.187764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.632535Z","time spent":"555.135036ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:53:01.188587Z","caller":"traceutil/trace.go:171","msg":"trace[472665024] transaction","detail":"{read_only:false; response_revision:18075; number_of_response:1; }","duration":"499.353119ms","start":"2026-10-15T14:53:00.689212Z","end":"2026-10-15T14:53:01.188565Z","steps":["trace[472665024] 'process raft request' (duration: 499.171405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.188689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.689195Z","time spent":"499.436821ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:53:01.227822Z","caller":"traceutil/trace.go:171","msg":"trace[1326183411] transaction","detail":"{read_only:false; response_revision:18076; number_of_response:1; }","duration":"444.573454ms","start":"2026-10-15T14:53:00.783216Z","end":"2026-10-15T14:53:01.22779Z","steps":["trace[1326183411] 'process raft request' (duration: 444.333029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.228041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.783166Z","time spent":"444.772849ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:53:01.228083Z","caller":"traceutil/trace.go:171","msg":"trace[2028448032] linearizableReadLoop","detail":"{readStateIndex:18695; appliedIndex:18690; }","duration":"199.402336ms","start":"2026-10-15T14:53:01.028622Z","end":"2026-10-15T14:53:01.228024Z","steps":["trace[2028448032] 'read index received' (duration: 157.739063ms)","trace[2028448032] 'applied index is now lower than readState.Index' (duration: 41.661303ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:53:01.228154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.209165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:53:01.228241Z","caller":"traceutil/trace.go:171","msg":"trace[644576910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18077; }","duration":"533.364528ms","start":"2026-10-15T14:53:00.69486Z","end":"2026-10-15T14:53:01.228225Z","steps":["trace[644576910] 'agreement among raft nodes before linearized reading' (duration: 533.224316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.228257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.928116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"warn","ts":"2026-10-15T14:53:01.228283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.69484Z","time spent":"533.43411ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T14:53:01.22828Z","caller":"traceutil/trace.go:171","msg":"trace[2138111385] transaction","detail":"{read_only:false; response_revision:18077; number_of_response:1; }","duration":"430.694547ms","start":"2026-10-15T14:53:00.797548Z","end":"2026-10-15T14:53:01.228243Z","steps":["trace[2138111385] 'process raft request' (duration: 430.183386ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:01.228324Z","caller":"traceutil/trace.go:171","msg":"trace[1056805531] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:18077; }","duration":"593.038607ms","start":"2026-10-15T14:53:00.635265Z","end":"2026-10-15T14:53:01.228304Z","steps":["trace[1056805531] 'agreement among raft nodes before linearized reading' (duration: 592.848493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.228399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.635237Z","time spent":"593.15234ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1842,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-10-15T14:53:01.228457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.797521Z","time spent":"430.84621ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:53:01.228641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.099246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:53:01.228694Z","caller":"traceutil/trace.go:171","msg":"trace[1019477111] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:18077; }","duration":"424.185007ms","start":"2026-10-15T14:53:00.804494Z","end":"2026-10-15T14:53:01.228679Z","steps":["trace[1019477111] 'agreement among raft nodes before linearized reading' (duration: 423.533083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.228721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.804481Z","time spent":"424.232338ms","remote":"127.0.0.1:60224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:53:01.229454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.067503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:941097"} {"level":"info","ts":"2026-10-15T14:53:01.230131Z","caller":"traceutil/trace.go:171","msg":"trace[84934979] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18077; }","duration":"630.178964ms","start":"2026-10-15T14:53:00.599336Z","end":"2026-10-15T14:53:01.229515Z","steps":["trace[84934979] 'agreement among raft nodes before linearized reading' (duration: 628.895696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.230198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:53:00.599327Z","time spent":"630.851288ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":941121,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-15T14:53:01.322483Z","caller":"traceutil/trace.go:171","msg":"trace[1283417984] transaction","detail":"{read_only:false; response_revision:18078; number_of_response:1; }","duration":"123.286285ms","start":"2026-10-15T14:53:01.199156Z","end":"2026-10-15T14:53:01.322442Z","steps":["trace[1283417984] 'process raft request' (duration: 103.915019ms)","trace[1283417984] 'compare' (duration: 19.141721ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:53:01.322593Z","caller":"traceutil/trace.go:171","msg":"trace[707961396] transaction","detail":"{read_only:false; response_revision:18079; number_of_response:1; }","duration":"113.478104ms","start":"2026-10-15T14:53:01.209086Z","end":"2026-10-15T14:53:01.322564Z","steps":["trace[707961396] 'process raft request' (duration: 113.268999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.322636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.970984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:53:01.322791Z","caller":"traceutil/trace.go:171","msg":"trace[383839487] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:18079; }","duration":"162.188059ms","start":"2026-10-15T14:53:01.160567Z","end":"2026-10-15T14:53:01.322755Z","steps":["trace[383839487] 'agreement among raft nodes before linearized reading' (duration: 161.908022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.322822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.138784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q7vx6\" ","response":"range_response_count:1 size:20888"} {"level":"info","ts":"2026-10-15T14:53:01.322985Z","caller":"traceutil/trace.go:171","msg":"trace[754525299] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-q7vx6; range_end:; response_count:1; response_revision:18079; }","duration":"277.339517ms","start":"2026-10-15T14:53:01.045628Z","end":"2026-10-15T14:53:01.322968Z","steps":["trace[754525299] 'agreement among raft nodes before linearized reading' (duration: 276.854667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.322868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.321535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9251"} {"level":"warn","ts":"2026-10-15T14:53:01.323245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.769787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3206"} {"level":"info","ts":"2026-10-15T14:53:01.323307Z","caller":"traceutil/trace.go:171","msg":"trace[336769550] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18079; }","duration":"277.882739ms","start":"2026-10-15T14:53:01.045405Z","end":"2026-10-15T14:53:01.323287Z","steps":["trace[336769550] 'agreement among raft nodes before linearized reading' (duration: 277.729226ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:01.323297Z","caller":"traceutil/trace.go:171","msg":"trace[1397758913] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18079; }","duration":"123.798764ms","start":"2026-10-15T14:53:01.199485Z","end":"2026-10-15T14:53:01.323284Z","steps":["trace[1397758913] 'agreement among raft nodes before linearized reading' (duration: 123.199562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:53:01.323493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.194957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-10-15T14:53:01.323555Z","caller":"traceutil/trace.go:171","msg":"trace[1012537372] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:18079; }","duration":"286.29066ms","start":"2026-10-15T14:53:01.03725Z","end":"2026-10-15T14:53:01.32354Z","steps":["trace[1012537372] 'agreement among raft nodes before linearized reading' (duration: 286.145646ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:24.214178Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b1b675f124322401","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:55:24.222783Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T14:55:24.222969Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T14:56:44.891202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17481} {"level":"info","ts":"2026-10-15T14:56:45.011737Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17481,"took":"116.236292ms","hash":814900304,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":34521088,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T14:56:45.01182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":814900304,"revision":17481,"compact-revision":15360} {"level":"warn","ts":"2026-10-15T14:58:23.07684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.971036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T14:58:23.076942Z","caller":"traceutil/trace.go:171","msg":"trace[1826061994] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20595; }","duration":"148.12763ms","start":"2026-10-15T14:58:22.928792Z","end":"2026-10-15T14:58:23.076919Z","steps":["trace[1826061994] 'range keys from in-memory index tree' (duration: 147.796792ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:23.076931Z","caller":"traceutil/trace.go:171","msg":"trace[571395259] transaction","detail":"{read_only:false; response_revision:20596; number_of_response:1; }","duration":"130.148637ms","start":"2026-10-15T14:58:22.946752Z","end":"2026-10-15T14:58:23.076901Z","steps":["trace[571395259] 'process raft request' (duration: 123.298017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:33.614642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.508232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-15T14:58:38.717676Z","caller":"traceutil/trace.go:171","msg":"trace[1997196443] transaction","detail":"{read_only:false; response_revision:20675; number_of_response:1; }","duration":"128.163501ms","start":"2026-10-15T14:58:38.589483Z","end":"2026-10-15T14:58:38.717647Z","steps":["trace[1997196443] 'process raft request' (duration: 128.010428ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:38.730243Z","caller":"traceutil/trace.go:171","msg":"trace[1096835631] transaction","detail":"{read_only:false; response_revision:20676; number_of_response:1; }","duration":"132.551058ms","start":"2026-10-15T14:58:38.597644Z","end":"2026-10-15T14:58:38.730196Z","steps":["trace[1096835631] 'process raft request' (duration: 132.379864ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:39.045325Z","caller":"traceutil/trace.go:171","msg":"trace[450996233] transaction","detail":"{read_only:false; response_revision:20677; number_of_response:1; }","duration":"110.119088ms","start":"2026-10-15T14:58:38.935176Z","end":"2026-10-15T14:58:39.045295Z","steps":["trace[450996233] 'process raft request' (duration: 109.911163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:39.356233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.366087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:58:39.356563Z","caller":"traceutil/trace.go:171","msg":"trace[590123626] linearizableReadLoop","detail":"{readStateIndex:21384; appliedIndex:21382; }","duration":"126.263411ms","start":"2026-10-15T14:58:39.230273Z","end":"2026-10-15T14:58:39.356537Z","steps":["trace[590123626] 'read index received' (duration: 36.991µs)","trace[590123626] 'applied index is now lower than readState.Index' (duration: 126.22497ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:58:39.356599Z","caller":"traceutil/trace.go:171","msg":"trace[1185872291] transaction","detail":"{read_only:false; response_revision:20679; number_of_response:1; }","duration":"219.870467ms","start":"2026-10-15T14:58:39.136679Z","end":"2026-10-15T14:58:39.35655Z","steps":["trace[1185872291] 'process raft request' (duration: 219.773055ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:39.356608Z","caller":"traceutil/trace.go:171","msg":"trace[42252540] transaction","detail":"{read_only:false; response_revision:20678; number_of_response:1; }","duration":"287.736322ms","start":"2026-10-15T14:58:39.068819Z","end":"2026-10-15T14:58:39.356555Z","steps":["trace[42252540] 'process raft request' (duration: 131.877934ms)","trace[42252540] 'compare' (duration: 155.176193ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:58:39.356944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.637149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T14:58:39.357065Z","caller":"traceutil/trace.go:171","msg":"trace[958203117] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20679; }","duration":"126.804612ms","start":"2026-10-15T14:58:39.230229Z","end":"2026-10-15T14:58:39.357033Z","steps":["trace[958203117] 'agreement among raft nodes before linearized reading' (duration: 126.394724ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:39.548815Z","caller":"traceutil/trace.go:171","msg":"trace[790410046] transaction","detail":"{read_only:false; response_revision:20680; number_of_response:1; }","duration":"186.417756ms","start":"2026-10-15T14:58:39.362369Z","end":"2026-10-15T14:58:39.548787Z","steps":["trace[790410046] 'process raft request' (duration: 180.085508ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:39.557185Z","caller":"traceutil/trace.go:171","msg":"trace[1781348721] transaction","detail":"{read_only:false; response_revision:20681; number_of_response:1; }","duration":"194.488274ms","start":"2026-10-15T14:58:39.362667Z","end":"2026-10-15T14:58:39.557156Z","steps":["trace[1781348721] 'process raft request' (duration: 194.32058ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:39.557308Z","caller":"traceutil/trace.go:171","msg":"trace[1476666521] linearizableReadLoop","detail":"{readStateIndex:21387; appliedIndex:21384; }","duration":"144.579991ms","start":"2026-10-15T14:58:39.412709Z","end":"2026-10-15T14:58:39.557289Z","steps":["trace[1476666521] 'read index received' (duration: 129.739867ms)","trace[1476666521] 'applied index is now lower than readState.Index' (duration: 14.838794ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:58:39.557472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.755915ms","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-10-15T14:58:39.55748Z","caller":"traceutil/trace.go:171","msg":"trace[1025257112] transaction","detail":"{read_only:false; response_revision:20682; number_of_response:1; }","duration":"194.794139ms","start":"2026-10-15T14:58:39.362675Z","end":"2026-10-15T14:58:39.557469Z","steps":["trace[1025257112] 'process raft request' (duration: 194.436502ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:39.557515Z","caller":"traceutil/trace.go:171","msg":"trace[964193350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20682; }","duration":"144.812516ms","start":"2026-10-15T14:58:39.412689Z","end":"2026-10-15T14:58:39.557501Z","steps":["trace[964193350] 'agreement among raft nodes before linearized reading' (duration: 144.664123ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:39.898313Z","caller":"traceutil/trace.go:171","msg":"trace[1605950614] transaction","detail":"{read_only:false; response_revision:20684; number_of_response:1; }","duration":"116.710643ms","start":"2026-10-15T14:58:39.78157Z","end":"2026-10-15T14:58:39.89828Z","steps":["trace[1605950614] 'process raft request' (duration: 116.541308ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:01:44.900901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19895} {"level":"info","ts":"2026-10-15T15:01:45.028913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19895,"took":"123.447627ms","hash":1614608921,"current-db-size-bytes":55238656,"current-db-size":"55 MB","current-db-size-in-use-bytes":30883840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:01:45.028987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1614608921,"revision":19895,"compact-revision":17481} {"level":"warn","ts":"2026-10-15T15:02:36.897415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.654213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:02:36.897512Z","caller":"traceutil/trace.go:171","msg":"trace[1564898854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23198; }","duration":"201.822517ms","start":"2026-10-15T15:02:36.695669Z","end":"2026-10-15T15:02:36.897491Z","steps":["trace[1564898854] 'range keys from in-memory index tree' (duration: 201.414237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.401748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.966334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:06:18.401855Z","caller":"traceutil/trace.go:171","msg":"trace[185944008] linearizableReadLoop","detail":"{readStateIndex:26229; appliedIndex:26228; }","duration":"137.009052ms","start":"2026-10-15T15:06:18.264829Z","end":"2026-10-15T15:06:18.401838Z","steps":["trace[185944008] 'read index received' (duration: 65.541µs)","trace[185944008] 'applied index is now lower than readState.Index' (duration: 136.942341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:06:18.40213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.702781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-10-15T15:06:18.402127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.284748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T15:06:18.40218Z","caller":"traceutil/trace.go:171","msg":"trace[528861259] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:25396; }","duration":"133.796024ms","start":"2026-10-15T15:06:18.268371Z","end":"2026-10-15T15:06:18.402167Z","steps":["trace[528861259] 'agreement among raft nodes before linearized reading' (duration: 133.62515ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.402197Z","caller":"traceutil/trace.go:171","msg":"trace[908905195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25396; }","duration":"137.40133ms","start":"2026-10-15T15:06:18.264777Z","end":"2026-10-15T15:06:18.402179Z","steps":["trace[908905195] 'agreement among raft nodes before linearized reading' (duration: 137.183226ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.401881Z","caller":"traceutil/trace.go:171","msg":"trace[1720699485] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"224.75439ms","start":"2026-10-15T15:06:18.177098Z","end":"2026-10-15T15:06:18.401852Z","steps":["trace[1720699485] 'process raft request' (duration: 71.605982ms)","trace[1720699485] 'compare' (duration: 152.617876ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:06:18.402635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.824669ms","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-10-15T15:06:18.40269Z","caller":"traceutil/trace.go:171","msg":"trace[1719928214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25396; }","duration":"129.91639ms","start":"2026-10-15T15:06:18.272758Z","end":"2026-10-15T15:06:18.402674Z","steps":["trace[1719928214] 'agreement among raft nodes before linearized reading' (duration: 129.801638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.402533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.340384ms","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-10-15T15:06:18.402985Z","caller":"traceutil/trace.go:171","msg":"trace[1863878539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25396; }","duration":"112.812704ms","start":"2026-10-15T15:06:18.290156Z","end":"2026-10-15T15:06:18.402969Z","steps":["trace[1863878539] 'agreement among raft nodes before linearized reading' (duration: 112.273042ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.864433Z","caller":"traceutil/trace.go:171","msg":"trace[765384706] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"453.846242ms","start":"2026-10-15T15:06:18.410564Z","end":"2026-10-15T15:06:18.86441Z","steps":["trace[765384706] 'process raft request' (duration: 453.808971ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.864473Z","caller":"traceutil/trace.go:171","msg":"trace[1288352986] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"453.996334ms","start":"2026-10-15T15:06:18.410441Z","end":"2026-10-15T15:06:18.864438Z","steps":["trace[1288352986] 'process raft request' (duration: 453.714579ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.864505Z","caller":"traceutil/trace.go:171","msg":"trace[501320689] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"454.002235ms","start":"2026-10-15T15:06:18.410462Z","end":"2026-10-15T15:06:18.864465Z","steps":["trace[501320689] 'process raft request' (duration: 453.870993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.864635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.41042Z","time spent":"454.124808ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:06:18.864685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.410557Z","time spent":"453.946173ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:06:18.864746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.410376Z","time spent":"454.20608ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:06:18.919563Z","caller":"traceutil/trace.go:171","msg":"trace[1021586325] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"444.728497ms","start":"2026-10-15T15:06:18.474807Z","end":"2026-10-15T15:06:18.919535Z","steps":["trace[1021586325] 'process raft request' (duration: 444.491642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.919718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.47478Z","time spent":"444.85854ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:06:18.920152Z","caller":"traceutil/trace.go:171","msg":"trace[515329957] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"380.329068ms","start":"2026-10-15T15:06:18.539811Z","end":"2026-10-15T15:06:18.92014Z","steps":["trace[515329957] 'process raft request' (duration: 380.247126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.920222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.539781Z","time spent":"380.407559ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:06:18.920346Z","caller":"traceutil/trace.go:171","msg":"trace[1561762047] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"381.78407ms","start":"2026-10-15T15:06:18.53853Z","end":"2026-10-15T15:06:18.920315Z","steps":["trace[1561762047] 'process raft request' (duration: 380.934202ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.920374Z","caller":"traceutil/trace.go:171","msg":"trace[519432393] linearizableReadLoop","detail":"{readStateIndex:26234; appliedIndex:26230; }","duration":"432.123687ms","start":"2026-10-15T15:06:18.488228Z","end":"2026-10-15T15:06:18.920352Z","steps":["trace[519432393] 'read index received' (duration: 376.028466ms)","trace[519432393] 'applied index is now lower than readState.Index' (duration: 56.093651ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:06:18.920397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.538496Z","time spent":"381.874022ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:06:18.920632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.383792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-15T15:06:18.920757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.477146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T15:06:18.920787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.278593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-869bc955dc-5d5zr\" ","response":"range_response_count:1 size:8652"} {"level":"info","ts":"2026-10-15T15:06:18.920801Z","caller":"traceutil/trace.go:171","msg":"trace[1642065517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25403; }","duration":"225.567327ms","start":"2026-10-15T15:06:18.695224Z","end":"2026-10-15T15:06:18.920791Z","steps":["trace[1642065517] 'agreement among raft nodes before linearized reading' (duration: 225.500325ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.92081Z","caller":"traceutil/trace.go:171","msg":"trace[1810093761] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-869bc955dc-5d5zr; range_end:; response_count:1; response_revision:25403; }","duration":"162.354924ms","start":"2026-10-15T15:06:18.758449Z","end":"2026-10-15T15:06:18.920804Z","steps":["trace[1810093761] 'agreement among raft nodes before linearized reading' (duration: 162.245151ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.920664Z","caller":"traceutil/trace.go:171","msg":"trace[2091081216] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"317.37421ms","start":"2026-10-15T15:06:18.603281Z","end":"2026-10-15T15:06:18.920655Z","steps":["trace[2091081216] 'process raft request' (duration: 316.823029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.920845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.562808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:06:18.920942Z","caller":"traceutil/trace.go:171","msg":"trace[1743402230] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25403; }","duration":"290.698441ms","start":"2026-10-15T15:06:18.630209Z","end":"2026-10-15T15:06:18.920907Z","steps":["trace[1743402230] 'agreement among raft nodes before linearized reading' (duration: 290.531508ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:18.920917Z","caller":"traceutil/trace.go:171","msg":"trace[582389709] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25403; }","duration":"432.68435ms","start":"2026-10-15T15:06:18.488175Z","end":"2026-10-15T15:06:18.920859Z","steps":["trace[582389709] 'agreement among raft nodes before linearized reading' (duration: 432.306551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.921119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.488155Z","time spent":"432.905994ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T15:06:18.921115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.798079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T15:06:18.921175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.603257Z","time spent":"317.700358ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:06:18.921254Z","caller":"traceutil/trace.go:171","msg":"trace[1128355847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25403; }","duration":"345.034963ms","start":"2026-10-15T15:06:18.576205Z","end":"2026-10-15T15:06:18.92124Z","steps":["trace[1128355847] 'agreement among raft nodes before linearized reading' (duration: 344.458521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:06:18.921401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:06:18.576181Z","time spent":"345.205796ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T15:06:44.909526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22272} {"level":"info","ts":"2026-10-15T15:06:45.041471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22272,"took":"125.753091ms","hash":289596089,"current-db-size-bytes":57348096,"current-db-size":"57 MB","current-db-size-in-use-bytes":45842432,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-15T15:06:45.041544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":289596089,"revision":22272,"compact-revision":19895} {"level":"info","ts":"2026-10-15T15:07:13.024501Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000687f.wal"} {"level":"warn","ts":"2026-10-15T15:07:18.385334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.97164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-15T15:07:18.385426Z","caller":"traceutil/trace.go:171","msg":"trace[747183952] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:25946; }","duration":"115.117614ms","start":"2026-10-15T15:07:18.270288Z","end":"2026-10-15T15:07:18.385405Z","steps":["trace[747183952] 'range keys from in-memory index tree' (duration: 114.756816ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:18.594992Z","caller":"traceutil/trace.go:171","msg":"trace[1356653472] linearizableReadLoop","detail":"{readStateIndex:26794; appliedIndex:26793; }","duration":"206.52617ms","start":"2026-10-15T15:07:18.388441Z","end":"2026-10-15T15:07:18.594967Z","steps":["trace[1356653472] 'read index received' (duration: 206.319025ms)","trace[1356653472] 'applied index is now lower than readState.Index' (duration: 205.584µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:07:18.595192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.734194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a50ed959-5725-4812-95f8-1c1fe654159e\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-10-15T15:07:18.595236Z","caller":"traceutil/trace.go:171","msg":"trace[361630299] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a50ed959-5725-4812-95f8-1c1fe654159e; range_end:; response_count:1; response_revision:25946; }","duration":"206.816636ms","start":"2026-10-15T15:07:18.388409Z","end":"2026-10-15T15:07:18.595225Z","steps":["trace[361630299] 'agreement among raft nodes before linearized reading' (duration: 206.650383ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:18.879735Z","caller":"traceutil/trace.go:171","msg":"trace[1281151314] linearizableReadLoop","detail":"{readStateIndex:26795; appliedIndex:26794; }","duration":"270.822906ms","start":"2026-10-15T15:07:18.608884Z","end":"2026-10-15T15:07:18.879707Z","steps":["trace[1281151314] 'read index received' (duration: 270.657472ms)","trace[1281151314] 'applied index is now lower than readState.Index' (duration: 163.864µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:07:18.879803Z","caller":"traceutil/trace.go:171","msg":"trace[679997320] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"283.746682ms","start":"2026-10-15T15:07:18.596012Z","end":"2026-10-15T15:07:18.879759Z","steps":["trace[679997320] 'process raft request' (duration: 283.514607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:18.881412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.504161ms","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-10-15T15:07:18.881465Z","caller":"traceutil/trace.go:171","msg":"trace[172864045] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25947; }","duration":"272.612514ms","start":"2026-10-15T15:07:18.608841Z","end":"2026-10-15T15:07:18.881454Z","steps":["trace[172864045] 'agreement among raft nodes before linearized reading' (duration: 271.00996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:18.927223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.436247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T15:07:18.927258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.196253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-10-15T15:07:18.927312Z","caller":"traceutil/trace.go:171","msg":"trace[210305367] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25948; }","duration":"263.56492ms","start":"2026-10-15T15:07:18.663725Z","end":"2026-10-15T15:07:18.92729Z","steps":["trace[210305367] 'agreement among raft nodes before linearized reading' (duration: 263.378796ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:18.927349Z","caller":"traceutil/trace.go:171","msg":"trace[411435192] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25948; }","duration":"286.322777ms","start":"2026-10-15T15:07:18.641003Z","end":"2026-10-15T15:07:18.927326Z","steps":["trace[411435192] 'agreement among raft nodes before linearized reading' (duration: 286.103072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:18.927639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.992246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:07:18.927368Z","caller":"traceutil/trace.go:171","msg":"trace[1371832564] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"278.405517ms","start":"2026-10-15T15:07:18.648903Z","end":"2026-10-15T15:07:18.927309Z","steps":["trace[1371832564] 'process raft request' (duration: 277.995668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:18.927722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.514763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-15T15:07:18.927824Z","caller":"traceutil/trace.go:171","msg":"trace[2003872903] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25948; }","duration":"202.674807ms","start":"2026-10-15T15:07:18.725125Z","end":"2026-10-15T15:07:18.927799Z","steps":["trace[2003872903] 'agreement among raft nodes before linearized reading' (duration: 202.37818ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:18.927724Z","caller":"traceutil/trace.go:171","msg":"trace[2140921304] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25948; }","duration":"311.150769ms","start":"2026-10-15T15:07:18.616554Z","end":"2026-10-15T15:07:18.927705Z","steps":["trace[2140921304] 'agreement among raft nodes before linearized reading' (duration: 310.774981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:18.927933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:07:18.616545Z","time spent":"311.367413ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:07:19.22259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.283371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-15T15:07:19.222704Z","caller":"traceutil/trace.go:171","msg":"trace[618425441] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25948; }","duration":"197.440965ms","start":"2026-10-15T15:07:19.025229Z","end":"2026-10-15T15:07:19.22267Z","steps":["trace[618425441] 'range keys from in-memory index tree' (duration: 196.987146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:19.222712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.62335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:07:19.222844Z","caller":"traceutil/trace.go:171","msg":"trace[7575419] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25948; }","duration":"219.830365ms","start":"2026-10-15T15:07:19.002991Z","end":"2026-10-15T15:07:19.222822Z","steps":["trace[7575419] 'count revisions from in-memory index tree' (duration: 219.520647ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:19.277901Z","caller":"traceutil/trace.go:171","msg":"trace[1146921595] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"100.52914ms","start":"2026-10-15T15:07:19.177339Z","end":"2026-10-15T15:07:19.277869Z","steps":["trace[1146921595] 'process raft request' (duration: 100.344207ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:19.439352Z","caller":"traceutil/trace.go:171","msg":"trace[1858174202] linearizableReadLoop","detail":"{readStateIndex:26799; appliedIndex:26796; }","duration":"216.85477ms","start":"2026-10-15T15:07:19.222474Z","end":"2026-10-15T15:07:19.439329Z","steps":["trace[1858174202] 'read index received' (duration: 55.249252ms)","trace[1858174202] 'applied index is now lower than readState.Index' (duration: 161.604428ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:07:19.439623Z","caller":"traceutil/trace.go:171","msg":"trace[958233402] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"254.622829ms","start":"2026-10-15T15:07:19.184977Z","end":"2026-10-15T15:07:19.4396Z","steps":["trace[958233402] 'process raft request' (duration: 232.627937ms)","trace[958233402] 'compare' (duration: 21.558142ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:07:19.439815Z","caller":"traceutil/trace.go:171","msg":"trace[911114813] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"243.449659ms","start":"2026-10-15T15:07:19.196354Z","end":"2026-10-15T15:07:19.439804Z","steps":["trace[911114813] 'process raft request' (duration: 242.927618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:19.440075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.570375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T15:07:19.440115Z","caller":"traceutil/trace.go:171","msg":"trace[644811303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25951; }","duration":"217.692329ms","start":"2026-10-15T15:07:19.222414Z","end":"2026-10-15T15:07:19.440106Z","steps":["trace[644811303] 'agreement among raft nodes before linearized reading' (duration: 217.516825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:19.503213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.338869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T15:07:19.503304Z","caller":"traceutil/trace.go:171","msg":"trace[1822412277] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25952; }","duration":"179.492771ms","start":"2026-10-15T15:07:19.323794Z","end":"2026-10-15T15:07:19.503287Z","steps":["trace[1822412277] 'agreement among raft nodes before linearized reading' (duration: 179.302897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:19.503497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.45623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-15T15:07:19.50353Z","caller":"traceutil/trace.go:171","msg":"trace[1498192295] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25952; }","duration":"187.545963ms","start":"2026-10-15T15:07:19.315975Z","end":"2026-10-15T15:07:19.503521Z","steps":["trace[1498192295] 'agreement among raft nodes before linearized reading' (duration: 187.453052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:19.503723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.578711ms","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-10-15T15:07:19.503756Z","caller":"traceutil/trace.go:171","msg":"trace[501244482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25953; }","duration":"264.641293ms","start":"2026-10-15T15:07:19.239106Z","end":"2026-10-15T15:07:19.503748Z","steps":["trace[501244482] 'agreement among raft nodes before linearized reading' (duration: 264.547512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:19.503911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.145954ms","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-10-15T15:07:19.503968Z","caller":"traceutil/trace.go:171","msg":"trace[811881001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25953; }","duration":"272.212505ms","start":"2026-10-15T15:07:19.231725Z","end":"2026-10-15T15:07:19.503937Z","steps":["trace[811881001] 'agreement among raft nodes before linearized reading' (duration: 272.130274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:19.504132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.046727ms","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-10-15T15:07:19.504165Z","caller":"traceutil/trace.go:171","msg":"trace[340862653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25953; }","duration":"275.120288ms","start":"2026-10-15T15:07:19.229036Z","end":"2026-10-15T15:07:19.504156Z","steps":["trace[340862653] 'agreement among raft nodes before linearized reading' (duration: 274.999875ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:44.919812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25678} {"level":"info","ts":"2026-10-15T15:11:45.112147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25678,"took":"187.662292ms","hash":2864521531,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":36208640,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T15:11:45.112221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2864521531,"revision":25678,"compact-revision":22272} {"level":"info","ts":"2026-10-15T15:12:53.343435Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b1b675f124322401","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:12:53.348923Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T15:12:53.349023Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T15:16:44.932888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28559} {"level":"info","ts":"2026-10-15T15:16:45.097154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28559,"took":"159.478098ms","hash":3999726707,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":31662080,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:16:45.097248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3999726707,"revision":28559,"compact-revision":25678} {"level":"info","ts":"2026-10-15T15:21:44.939081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30797} {"level":"info","ts":"2026-10-15T15:21:45.083118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30797,"took":"139.407444ms","hash":3560577851,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":30543872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:21:45.083189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3560577851,"revision":30797,"compact-revision":28559} {"level":"warn","ts":"2026-10-15T15:24:21.819901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.61142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-10-15T15:24:21.819988Z","caller":"traceutil/trace.go:171","msg":"trace[109814730] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev; range_end:; response_count:1; response_revision:34199; }","duration":"100.734821ms","start":"2026-10-15T15:24:21.719238Z","end":"2026-10-15T15:24:21.819973Z","steps":["trace[109814730] 'range keys from in-memory index tree' (duration: 100.467885ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:25:03.523477Z","caller":"traceutil/trace.go:171","msg":"trace[2133193089] transaction","detail":"{read_only:false; response_revision:34515; number_of_response:1; }","duration":"116.964397ms","start":"2026-10-15T15:25:03.406488Z","end":"2026-10-15T15:25:03.523453Z","steps":["trace[2133193089] 'process raft request' (duration: 55.651724ms)","trace[2133193089] 'compare' (duration: 61.224251ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:26:30.628113Z","caller":"traceutil/trace.go:171","msg":"trace[1877586225] transaction","detail":"{read_only:false; response_revision:35169; number_of_response:1; }","duration":"130.80718ms","start":"2026-10-15T15:26:30.497268Z","end":"2026-10-15T15:26:30.628075Z","steps":["trace[1877586225] 'process raft request' (duration: 77.463766ms)","trace[1877586225] 'compare' (duration: 53.042558ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:26:30.630121Z","caller":"traceutil/trace.go:171","msg":"trace[1852181946] transaction","detail":"{read_only:false; response_revision:35170; number_of_response:1; }","duration":"131.897364ms","start":"2026-10-15T15:26:30.498206Z","end":"2026-10-15T15:26:30.630104Z","steps":["trace[1852181946] 'process raft request' (duration: 131.420144ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:26:30.630297Z","caller":"traceutil/trace.go:171","msg":"trace[1816790606] transaction","detail":"{read_only:false; response_revision:35171; number_of_response:1; }","duration":"111.773986ms","start":"2026-10-15T15:26:30.518513Z","end":"2026-10-15T15:26:30.630287Z","steps":["trace[1816790606] 'process raft request' (duration: 111.189263ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:26:44.971583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33051} {"level":"info","ts":"2026-10-15T15:26:45.288822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33051,"took":"313.920344ms","hash":4237087236,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":30232576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:26:45.288898Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4237087236,"revision":33051,"compact-revision":30797} {"level":"info","ts":"2026-10-15T15:27:03.184294Z","caller":"traceutil/trace.go:171","msg":"trace[714956768] transaction","detail":"{read_only:false; response_revision:35438; number_of_response:1; }","duration":"154.311682ms","start":"2026-10-15T15:27:03.029956Z","end":"2026-10-15T15:27:03.184268Z","steps":["trace[714956768] 'process raft request' (duration: 154.095337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:27:36.454023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.244856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:27:36.455065Z","caller":"traceutil/trace.go:171","msg":"trace[573200263] transaction","detail":"{read_only:false; response_revision:35708; number_of_response:1; }","duration":"189.766953ms","start":"2026-10-15T15:27:36.264535Z","end":"2026-10-15T15:27:36.454302Z","steps":["trace[573200263] 'process raft request' (duration: 86.104158ms)","trace[573200263] 'compare' (duration: 103.040522ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:27:36.737955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.86133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:27:36.738101Z","caller":"traceutil/trace.go:171","msg":"trace[1640096536] linearizableReadLoop","detail":"{readStateIndex:36830; appliedIndex:36828; }","duration":"265.694114ms","start":"2026-10-15T15:27:36.472363Z","end":"2026-10-15T15:27:36.738057Z","steps":["trace[1640096536] 'read index received' (duration: 34.760263ms)","trace[1640096536] 'applied index is now lower than readState.Index' (duration: 230.931771ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:27:36.738154Z","caller":"traceutil/trace.go:171","msg":"trace[1684895925] transaction","detail":"{read_only:false; response_revision:35710; number_of_response:1; }","duration":"276.831063ms","start":"2026-10-15T15:27:36.461276Z","end":"2026-10-15T15:27:36.738107Z","steps":["trace[1684895925] 'process raft request' (duration: 171.708487ms)","trace[1684895925] 'compare' (duration: 104.39555ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:27:36.738419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.039192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:27:36.738484Z","caller":"traceutil/trace.go:171","msg":"trace[1332176624] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:35710; }","duration":"266.105373ms","start":"2026-10-15T15:27:36.472357Z","end":"2026-10-15T15:27:36.738462Z","steps":["trace[1332176624] 'agreement among raft nodes before linearized reading' (duration: 265.988211ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:36.830417Z","caller":"traceutil/trace.go:171","msg":"trace[277829704] transaction","detail":"{read_only:false; response_revision:35711; number_of_response:1; }","duration":"108.971537ms","start":"2026-10-15T15:27:36.721421Z","end":"2026-10-15T15:27:36.830393Z","steps":["trace[277829704] 'process raft request' (duration: 108.840815ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:36.977074Z","caller":"traceutil/trace.go:171","msg":"trace[2136052048] linearizableReadLoop","detail":"{readStateIndex:36832; appliedIndex:36831; }","duration":"238.909622ms","start":"2026-10-15T15:27:36.738142Z","end":"2026-10-15T15:27:36.977052Z","steps":["trace[2136052048] 'read index received' (duration: 92.717309ms)","trace[2136052048] 'applied index is now lower than readState.Index' (duration: 146.190983ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:27:36.977153Z","caller":"traceutil/trace.go:171","msg":"trace[1280877185] transaction","detail":"{read_only:false; response_revision:35712; number_of_response:1; }","duration":"252.643306ms","start":"2026-10-15T15:27:36.724464Z","end":"2026-10-15T15:27:36.977107Z","steps":["trace[1280877185] 'process raft request' (duration: 167.17016ms)","trace[1280877185] 'compare' (duration: 85.246411ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:27:36.977598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.326929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:27:36.977757Z","caller":"traceutil/trace.go:171","msg":"trace[1311819559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:35713; }","duration":"281.623844ms","start":"2026-10-15T15:27:36.69611Z","end":"2026-10-15T15:27:36.977734Z","steps":["trace[1311819559] 'agreement among raft nodes before linearized reading' (duration: 281.094943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:27:37.012396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.635302ms","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":"warn","ts":"2026-10-15T15:27:37.012457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.407775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/tempest-run-tests-kbb5r\" ","response":"range_response_count:1 size:10132"} {"level":"info","ts":"2026-10-15T15:27:37.012483Z","caller":"traceutil/trace.go:171","msg":"trace[901511342] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:35715; }","duration":"184.776596ms","start":"2026-10-15T15:27:36.827685Z","end":"2026-10-15T15:27:37.012462Z","steps":["trace[901511342] 'agreement among raft nodes before linearized reading' (duration: 184.546641ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:37.012567Z","caller":"traceutil/trace.go:171","msg":"trace[770099490] range","detail":"{range_begin:/registry/pods/openstack/tempest-run-tests-kbb5r; range_end:; response_count:1; response_revision:35715; }","duration":"167.562889ms","start":"2026-10-15T15:27:36.844987Z","end":"2026-10-15T15:27:37.01255Z","steps":["trace[770099490] 'agreement among raft nodes before linearized reading' (duration: 167.363694ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:37.161413Z","caller":"traceutil/trace.go:171","msg":"trace[2098954993] transaction","detail":"{read_only:false; response_revision:35716; number_of_response:1; }","duration":"158.791881ms","start":"2026-10-15T15:27:37.002599Z","end":"2026-10-15T15:27:37.161391Z","steps":["trace[2098954993] 'process raft request' (duration: 158.664278ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:37.269737Z","caller":"traceutil/trace.go:171","msg":"trace[1959382947] linearizableReadLoop","detail":"{readStateIndex:36838; appliedIndex:36835; }","duration":"136.864533ms","start":"2026-10-15T15:27:37.132849Z","end":"2026-10-15T15:27:37.269713Z","steps":["trace[1959382947] 'read index received' (duration: 28.496988ms)","trace[1959382947] 'applied index is now lower than readState.Index' (duration: 108.366475ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:27:37.269884Z","caller":"traceutil/trace.go:171","msg":"trace[492676536] transaction","detail":"{read_only:false; response_revision:35718; number_of_response:1; }","duration":"242.270274ms","start":"2026-10-15T15:27:37.027585Z","end":"2026-10-15T15:27:37.269855Z","steps":["trace[492676536] 'process raft request' (duration: 242.069229ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:37.269906Z","caller":"traceutil/trace.go:171","msg":"trace[1635482012] transaction","detail":"{read_only:false; response_revision:35717; number_of_response:1; }","duration":"250.823887ms","start":"2026-10-15T15:27:37.019071Z","end":"2026-10-15T15:27:37.269895Z","steps":["trace[1635482012] 'process raft request' (duration: 242.479279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:27:37.27012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.451844ms","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-10-15T15:27:37.270215Z","caller":"traceutil/trace.go:171","msg":"trace[233624022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:35718; }","duration":"129.579396ms","start":"2026-10-15T15:27:37.140619Z","end":"2026-10-15T15:27:37.270199Z","steps":["trace[233624022] 'agreement among raft nodes before linearized reading' (duration: 129.346851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:27:37.270299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.815643ms","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-10-15T15:27:37.270367Z","caller":"traceutil/trace.go:171","msg":"trace[1387789024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:35718; }","duration":"123.923306ms","start":"2026-10-15T15:27:37.146428Z","end":"2026-10-15T15:27:37.270351Z","steps":["trace[1387789024] 'agreement among raft nodes before linearized reading' (duration: 123.729082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:27:37.270324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.461416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T15:27:37.270509Z","caller":"traceutil/trace.go:171","msg":"trace[2050857265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:35718; }","duration":"137.695241ms","start":"2026-10-15T15:27:37.132787Z","end":"2026-10-15T15:27:37.270483Z","steps":["trace[2050857265] 'agreement among raft nodes before linearized reading' (duration: 137.309943ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:37.434956Z","caller":"traceutil/trace.go:171","msg":"trace[1205174068] transaction","detail":"{read_only:false; response_revision:35719; number_of_response:1; }","duration":"159.412915ms","start":"2026-10-15T15:27:37.275496Z","end":"2026-10-15T15:27:37.434908Z","steps":["trace[1205174068] 'process raft request' (duration: 123.660431ms)","trace[1205174068] 'compare' (duration: 35.332624ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:27:37.43507Z","caller":"traceutil/trace.go:171","msg":"trace[631901098] linearizableReadLoop","detail":"{readStateIndex:36841; appliedIndex:36838; }","duration":"145.246022ms","start":"2026-10-15T15:27:37.289812Z","end":"2026-10-15T15:27:37.435058Z","steps":["trace[631901098] 'read index received' (duration: 109.319975ms)","trace[631901098] 'applied index is now lower than readState.Index' (duration: 35.924927ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:27:37.434951Z","caller":"traceutil/trace.go:171","msg":"trace[1513876718] transaction","detail":"{read_only:false; response_revision:35720; number_of_response:1; }","duration":"158.158638ms","start":"2026-10-15T15:27:37.276768Z","end":"2026-10-15T15:27:37.434927Z","steps":["trace[1513876718] 'process raft request' (duration: 157.941973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:27:37.435255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.401846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:27:37.43526Z","caller":"traceutil/trace.go:171","msg":"trace[2097064267] transaction","detail":"{read_only:false; response_revision:35721; number_of_response:1; }","duration":"157.990534ms","start":"2026-10-15T15:27:37.277238Z","end":"2026-10-15T15:27:37.435229Z","steps":["trace[2097064267] 'process raft request' (duration: 157.618007ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:37.435322Z","caller":"traceutil/trace.go:171","msg":"trace[141940646] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:35721; }","duration":"145.520097ms","start":"2026-10-15T15:27:37.289784Z","end":"2026-10-15T15:27:37.435305Z","steps":["trace[141940646] 'agreement among raft nodes before linearized reading' (duration: 145.340114ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:31:44.979686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35278} {"level":"info","ts":"2026-10-15T15:31:45.12285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35278,"took":"138.876786ms","hash":1540373083,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":32890880,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T15:31:45.122924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1540373083,"revision":35278,"compact-revision":33051} {"level":"info","ts":"2026-10-15T15:34:25.84139Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b1b675f124322401","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:34:25.848509Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T15:34:25.848676Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T15:36:44.986521Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37572} {"level":"info","ts":"2026-10-15T15:36:45.133611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37572,"took":"140.747798ms","hash":1941791300,"current-db-size-bytes":61566976,"current-db-size":"62 MB","current-db-size-in-use-bytes":33099776,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T15:36:45.133688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1941791300,"revision":37572,"compact-revision":35278} {"level":"info","ts":"2026-10-15T15:41:44.995424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39846} {"level":"info","ts":"2026-10-15T15:41:45.148417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39846,"took":"148.496156ms","hash":4111065536,"current-db-size-bytes":61566976,"current-db-size":"62 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:41:45.148501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4111065536,"revision":39846,"compact-revision":37572}