{"level":"warn","ts":"2026-08-22T14:34:52.88352Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:34:52.883619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-08-22T14:34:52.883686Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:34:52.8837Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-22T14:34:52.88372Z","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-08-22T14:34:52.884347Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-08-22T14:34:52.88442Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-08-22T14:34:52.887158Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.525792ms"} {"level":"info","ts":"2026-08-22T14:34:52.890925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-08-22T14:34:52.890988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:34:52.891005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-08-22T14:34:52.891012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:34:52.891025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-08-22T14:34:52.891054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-08-22T14:34:52.895604Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:34:52.896852Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:34:52.898039Z","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-08-22T14:34:52.901166Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:34:52.901293Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:34:52.901456Z","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-08-22T14:34:52.901645Z","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-08-22T14:34:52.90166Z","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-08-22T14:34:52.902879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-08-22T14:34:52.903111Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-22T14:34:52.905334Z","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-08-22T14:34:52.905425Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-22T14:34:52.905474Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-22T14:34:52.905768Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:34:52.905819Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:34:53.492146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:34:53.492224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:34:53.492271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-08-22T14:34:53.492293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:34:53.492303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-22T14:34:53.492319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-08-22T14:34:53.492339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-22T14:34:53.493391Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:53.493566Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:34:53.493682Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:34:53.493733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:34:53.493917Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:34:53.494031Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:34:53.496692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:34:53.503194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:53.503341Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:53.503372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:53.504385Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-08-22T14:36:06.020811Z","caller":"traceutil/trace.go:171","msg":"trace[373368135] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"154.787653ms","start":"2026-08-22T14:36:05.865971Z","end":"2026-08-22T14:36:06.020759Z","steps":["trace[373368135] 'read index received' (duration: 150.388445ms)","trace[373368135] 'applied index is now lower than readState.Index' (duration: 4.398328ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:06.021005Z","caller":"traceutil/trace.go:171","msg":"trace[1321768732] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"355.146187ms","start":"2026-08-22T14:36:05.665817Z","end":"2026-08-22T14:36:06.020963Z","steps":["trace[1321768732] 'process raft request' (duration: 350.79597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:06.021026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.032328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:06.02121Z","caller":"traceutil/trace.go:171","msg":"trace[1907413211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"155.262424ms","start":"2026-08-22T14:36:05.865925Z","end":"2026-08-22T14:36:06.021188Z","steps":["trace[1907413211] 'agreement among raft nodes before linearized reading' (duration: 155.038449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:06.021607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:05.665787Z","time spent":"355.306611ms","remote":"127.0.0.1:41572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:36:06.477108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.176365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:36:06.477197Z","caller":"traceutil/trace.go:171","msg":"trace[109547141] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"110.10972ms","start":"2026-08-22T14:36:06.367073Z","end":"2026-08-22T14:36:06.477182Z","steps":["trace[109547141] 'read index received' (duration: 31.231µs)","trace[109547141] 'applied index is now lower than readState.Index' (duration: 110.077289ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:06.47724Z","caller":"traceutil/trace.go:171","msg":"trace[1247423645] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"401.003258ms","start":"2026-08-22T14:36:06.076193Z","end":"2026-08-22T14:36:06.477197Z","steps":["trace[1247423645] 'process raft request' (duration: 260.498024ms)","trace[1247423645] 'compare' (duration: 139.940239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:06.477325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:06.076171Z","time spent":"401.11642ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:36:06.477339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.260254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T14:36:06.477367Z","caller":"traceutil/trace.go:171","msg":"trace[881255967] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:697; }","duration":"110.322816ms","start":"2026-08-22T14:36:06.367035Z","end":"2026-08-22T14:36:06.477358Z","steps":["trace[881255967] 'agreement among raft nodes before linearized reading' (duration: 110.216843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:21.92526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.909323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T14:36:21.925367Z","caller":"traceutil/trace.go:171","msg":"trace[847123635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"177.063326ms","start":"2026-08-22T14:36:21.748283Z","end":"2026-08-22T14:36:21.925346Z","steps":["trace[847123635] 'range keys from in-memory index tree' (duration: 176.762789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:29.087059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.261984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:37:29.087237Z","caller":"traceutil/trace.go:171","msg":"trace[1227707024] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"247.888896ms","start":"2026-08-22T14:37:28.839327Z","end":"2026-08-22T14:37:29.087216Z","steps":["trace[1227707024] 'read index received' (duration: 13.21µs)","trace[1227707024] 'applied index is now lower than readState.Index' (duration: 247.874356ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:29.087371Z","caller":"traceutil/trace.go:171","msg":"trace[437100009] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"293.040114ms","start":"2026-08-22T14:37:28.794302Z","end":"2026-08-22T14:37:29.087342Z","steps":["trace[437100009] 'process raft request' (duration: 24.386791ms)","trace[437100009] 'compare' (duration: 268.119491ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:29.087534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.739641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:29.087636Z","caller":"traceutil/trace.go:171","msg":"trace[599132740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1890; }","duration":"223.874543ms","start":"2026-08-22T14:37:28.863744Z","end":"2026-08-22T14:37:29.087619Z","steps":["trace[599132740] 'agreement among raft nodes before linearized reading' (duration: 223.72804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:29.087669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.317587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3285e91-03fb-43e0-b4c4-512c65e10357\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T14:37:29.087758Z","caller":"traceutil/trace.go:171","msg":"trace[1834506742] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e3285e91-03fb-43e0-b4c4-512c65e10357; range_end:; response_count:1; response_revision:1890; }","duration":"248.425349ms","start":"2026-08-22T14:37:28.839312Z","end":"2026-08-22T14:37:29.087738Z","steps":["trace[1834506742] 'agreement among raft nodes before linearized reading' (duration: 248.158183ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:29.477434Z","caller":"traceutil/trace.go:171","msg":"trace[565637503] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"113.953479ms","start":"2026-08-22T14:37:29.363453Z","end":"2026-08-22T14:37:29.477406Z","steps":["trace[565637503] 'read index received' (duration: 113.754805ms)","trace[565637503] 'applied index is now lower than readState.Index' (duration: 197.934µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:29.477551Z","caller":"traceutil/trace.go:171","msg":"trace[96247091] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"129.506794ms","start":"2026-08-22T14:37:29.348006Z","end":"2026-08-22T14:37:29.477512Z","steps":["trace[96247091] 'process raft request' (duration: 129.283519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:29.477637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.174865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3285e91-03fb-43e0-b4c4-512c65e10357\" ","response":"range_response_count:1 size:3741"} {"level":"info","ts":"2026-08-22T14:37:29.477672Z","caller":"traceutil/trace.go:171","msg":"trace[1967544511] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e3285e91-03fb-43e0-b4c4-512c65e10357; range_end:; response_count:1; response_revision:1892; }","duration":"114.241406ms","start":"2026-08-22T14:37:29.36342Z","end":"2026-08-22T14:37:29.477661Z","steps":["trace[1967544511] 'agreement among raft nodes before linearized reading' (duration: 114.121633ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:43.979275Z","caller":"traceutil/trace.go:171","msg":"trace[1979376920] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"102.270146ms","start":"2026-08-22T14:37:43.876972Z","end":"2026-08-22T14:37:43.979242Z","steps":["trace[1979376920] 'process raft request' (duration: 102.059851ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:04.225717Z","caller":"traceutil/trace.go:171","msg":"trace[1441286920] linearizableReadLoop","detail":"{readStateIndex:2603; appliedIndex:2602; }","duration":"151.071281ms","start":"2026-08-22T14:39:04.074625Z","end":"2026-08-22T14:39:04.225697Z","steps":["trace[1441286920] 'read index received' (duration: 150.912907ms)","trace[1441286920] 'applied index is now lower than readState.Index' (duration: 157.614µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:04.22579Z","caller":"traceutil/trace.go:171","msg":"trace[1153866002] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"169.139919ms","start":"2026-08-22T14:39:04.056626Z","end":"2026-08-22T14:39:04.225766Z","steps":["trace[1153866002] 'process raft request' (duration: 168.923184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:04.225962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.203165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T14:39:04.226046Z","caller":"traceutil/trace.go:171","msg":"trace[1488342258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2509; }","duration":"149.295978ms","start":"2026-08-22T14:39:04.076731Z","end":"2026-08-22T14:39:04.226027Z","steps":["trace[1488342258] 'agreement among raft nodes before linearized reading' (duration: 149.097773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:04.226128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.47034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T14:39:04.226222Z","caller":"traceutil/trace.go:171","msg":"trace[2040068291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2509; }","duration":"151.602314ms","start":"2026-08-22T14:39:04.074598Z","end":"2026-08-22T14:39:04.226201Z","steps":["trace[2040068291] 'agreement among raft nodes before linearized reading' (duration: 151.198274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:36.273238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.067402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T14:39:36.273427Z","caller":"traceutil/trace.go:171","msg":"trace[1463380149] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2684; }","duration":"113.301937ms","start":"2026-08-22T14:39:36.160092Z","end":"2026-08-22T14:39:36.273394Z","steps":["trace[1463380149] 'range keys from in-memory index tree' (duration: 112.840616ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:36.488867Z","caller":"traceutil/trace.go:171","msg":"trace[951403198] linearizableReadLoop","detail":"{readStateIndex:2785; appliedIndex:2784; }","duration":"122.194245ms","start":"2026-08-22T14:39:36.366645Z","end":"2026-08-22T14:39:36.48884Z","steps":["trace[951403198] 'read index received' (duration: 121.936809ms)","trace[951403198] 'applied index is now lower than readState.Index' (duration: 255.906µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:39:36.488954Z","caller":"traceutil/trace.go:171","msg":"trace[1384174476] transaction","detail":"{read_only:false; response_revision:2685; number_of_response:1; }","duration":"136.546664ms","start":"2026-08-22T14:39:36.352376Z","end":"2026-08-22T14:39:36.488923Z","steps":["trace[1384174476] 'process raft request' (duration: 136.290778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:36.489101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.44109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T14:39:36.489168Z","caller":"traceutil/trace.go:171","msg":"trace[783826429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2685; }","duration":"122.546193ms","start":"2026-08-22T14:39:36.366608Z","end":"2026-08-22T14:39:36.489154Z","steps":["trace[783826429] 'agreement among raft nodes before linearized reading' (duration: 122.329408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:36.489188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.966972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T14:39:36.489252Z","caller":"traceutil/trace.go:171","msg":"trace[1909041810] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:2685; }","duration":"109.078955ms","start":"2026-08-22T14:39:36.380157Z","end":"2026-08-22T14:39:36.489236Z","steps":["trace[1909041810] 'agreement among raft nodes before linearized reading' (duration: 108.897151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:36.489255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.295725ms","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-08-22T14:39:36.489328Z","caller":"traceutil/trace.go:171","msg":"trace[1266607057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2685; }","duration":"117.392827ms","start":"2026-08-22T14:39:36.371918Z","end":"2026-08-22T14:39:36.489311Z","steps":["trace[1266607057] 'agreement among raft nodes before linearized reading' (duration: 117.190392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:36.489333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.560025ms","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-08-22T14:39:36.489425Z","caller":"traceutil/trace.go:171","msg":"trace[293391771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2685; }","duration":"120.747549ms","start":"2026-08-22T14:39:36.368666Z","end":"2026-08-22T14:39:36.489413Z","steps":["trace[293391771] 'agreement among raft nodes before linearized reading' (duration: 120.328618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:41.931868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.085465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T14:42:11.337926Z","caller":"traceutil/trace.go:171","msg":"trace[288136715] transaction","detail":"{read_only:false; response_revision:3692; number_of_response:1; }","duration":"105.870142ms","start":"2026-08-22T14:42:11.232028Z","end":"2026-08-22T14:42:11.337898Z","steps":["trace[288136715] 'process raft request' (duration: 105.700678ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:11.443311Z","caller":"traceutil/trace.go:171","msg":"trace[481833575] linearizableReadLoop","detail":"{readStateIndex:3830; appliedIndex:3828; }","duration":"209.443259ms","start":"2026-08-22T14:42:11.233837Z","end":"2026-08-22T14:42:11.44328Z","steps":["trace[481833575] 'read index received' (duration: 103.958006ms)","trace[481833575] 'applied index is now lower than readState.Index' (duration: 105.483783ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:42:11.443406Z","caller":"traceutil/trace.go:171","msg":"trace[1350742508] transaction","detail":"{read_only:false; response_revision:3693; number_of_response:1; }","duration":"210.392581ms","start":"2026-08-22T14:42:11.232981Z","end":"2026-08-22T14:42:11.443374Z","steps":["trace[1350742508] 'process raft request' (duration: 209.853067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:42:11.443564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.708875ms","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-08-22T14:42:11.443648Z","caller":"traceutil/trace.go:171","msg":"trace[1049655369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3693; }","duration":"209.808677ms","start":"2026-08-22T14:42:11.233822Z","end":"2026-08-22T14:42:11.443631Z","steps":["trace[1049655369] 'agreement among raft nodes before linearized reading' (duration: 209.578282ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:29.500296Z","caller":"traceutil/trace.go:171","msg":"trace[770517290] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"102.173673ms","start":"2026-08-22T14:43:29.398058Z","end":"2026-08-22T14:43:29.500232Z","steps":["trace[770517290] 'process raft request' (duration: 101.911297ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:34.012444Z","caller":"traceutil/trace.go:171","msg":"trace[982371107] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"161.34048ms","start":"2026-08-22T14:43:33.851081Z","end":"2026-08-22T14:43:34.012421Z","steps":["trace[982371107] 'process raft request' (duration: 124.993323ms)","trace[982371107] 'compare' (duration: 36.192683ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:34.012662Z","caller":"traceutil/trace.go:171","msg":"trace[1105102705] linearizableReadLoop","detail":"{readStateIndex:5044; appliedIndex:5042; }","duration":"160.180862ms","start":"2026-08-22T14:43:33.852457Z","end":"2026-08-22T14:43:34.012637Z","steps":["trace[1105102705] 'read index received' (duration: 123.561339ms)","trace[1105102705] 'applied index is now lower than readState.Index' (duration: 36.618213ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:43:34.012872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.792699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:43:34.012905Z","caller":"traceutil/trace.go:171","msg":"trace[638470254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4861; }","duration":"146.86032ms","start":"2026-08-22T14:43:33.866036Z","end":"2026-08-22T14:43:34.012896Z","steps":["trace[638470254] 'agreement among raft nodes before linearized reading' (duration: 146.799208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:34.012902Z","caller":"traceutil/trace.go:171","msg":"trace[1601680026] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"160.94009ms","start":"2026-08-22T14:43:33.85195Z","end":"2026-08-22T14:43:34.01289Z","steps":["trace[1601680026] 'process raft request' (duration: 160.440908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:34.012925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.474998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-d4w5x-tls\" ","response":"range_response_count:1 size:4432"} {"level":"info","ts":"2026-08-22T14:43:34.012953Z","caller":"traceutil/trace.go:171","msg":"trace[1634381558] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-d4w5x-tls; range_end:; response_count:1; response_revision:4861; }","duration":"160.55411ms","start":"2026-08-22T14:43:33.852391Z","end":"2026-08-22T14:43:34.012945Z","steps":["trace[1634381558] 'agreement among raft nodes before linearized reading' (duration: 160.431017ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:45.879665Z","caller":"traceutil/trace.go:171","msg":"trace[1679469348] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"133.764094ms","start":"2026-08-22T14:43:45.745865Z","end":"2026-08-22T14:43:45.879629Z","steps":["trace[1679469348] 'process raft request' (duration: 99.566185ms)","trace[1679469348] 'compare' (duration: 34.032715ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:45.879805Z","caller":"traceutil/trace.go:171","msg":"trace[698063277] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"124.697644ms","start":"2026-08-22T14:43:45.755071Z","end":"2026-08-22T14:43:45.879768Z","steps":["trace[698063277] 'process raft request' (duration: 124.607312ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:45.879839Z","caller":"traceutil/trace.go:171","msg":"trace[636343681] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5314; }","duration":"130.801162ms","start":"2026-08-22T14:43:45.749022Z","end":"2026-08-22T14:43:45.879823Z","steps":["trace[636343681] 'read index received' (duration: 170.714µs)","trace[636343681] 'applied index is now lower than readState.Index' (duration: 130.629708ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:45.879905Z","caller":"traceutil/trace.go:171","msg":"trace[139970128] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"126.298514ms","start":"2026-08-22T14:43:45.753584Z","end":"2026-08-22T14:43:45.879882Z","steps":["trace[139970128] 'process raft request' (duration: 126.042478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:45.88003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.989097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:43:45.880054Z","caller":"traceutil/trace.go:171","msg":"trace[1583533385] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5128; }","duration":"131.056668ms","start":"2026-08-22T14:43:45.748991Z","end":"2026-08-22T14:43:45.880048Z","steps":["trace[1583533385] 'agreement among raft nodes before linearized reading' (duration: 130.979296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:46.63371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.596591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:43:46.633807Z","caller":"traceutil/trace.go:171","msg":"trace[536894738] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5332; }","duration":"522.132205ms","start":"2026-08-22T14:43:46.111659Z","end":"2026-08-22T14:43:46.633791Z","steps":["trace[536894738] 'read index received' (duration: 122.282827ms)","trace[536894738] 'applied index is now lower than readState.Index' (duration: 399.848128ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:46.633874Z","caller":"traceutil/trace.go:171","msg":"trace[1444316526] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"523.96507ms","start":"2026-08-22T14:43:46.109897Z","end":"2026-08-22T14:43:46.633862Z","steps":["trace[1444316526] 'process raft request' (duration: 124.120582ms)","trace[1444316526] 'compare' (duration: 399.370196ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:43:46.633936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:46.109876Z","time spent":"524.029681ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:43:46.634068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.399902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-22T14:43:46.634141Z","caller":"traceutil/trace.go:171","msg":"trace[1790475316] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5143; }","duration":"522.501384ms","start":"2026-08-22T14:43:46.111623Z","end":"2026-08-22T14:43:46.634124Z","steps":["trace[1790475316] 'agreement among raft nodes before linearized reading' (duration: 522.308979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:46.634181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:46.111611Z","time spent":"522.562376ms","remote":"127.0.0.1:41676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-22T14:43:46.648381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.774677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T14:43:46.648415Z","caller":"traceutil/trace.go:171","msg":"trace[795653673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5144; }","duration":"345.848119ms","start":"2026-08-22T14:43:46.302559Z","end":"2026-08-22T14:43:46.648407Z","steps":["trace[795653673] 'agreement among raft nodes before linearized reading' (duration: 345.766937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:46.648435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:46.302538Z","time spent":"345.891631ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T14:43:46.648467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.854452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T14:43:46.648531Z","caller":"traceutil/trace.go:171","msg":"trace[1165221341] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:5144; }","duration":"254.963035ms","start":"2026-08-22T14:43:46.39355Z","end":"2026-08-22T14:43:46.648513Z","steps":["trace[1165221341] 'agreement among raft nodes before linearized reading' (duration: 254.7814ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:46.648679Z","caller":"traceutil/trace.go:171","msg":"trace[151219890] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"246.658712ms","start":"2026-08-22T14:43:46.402012Z","end":"2026-08-22T14:43:46.648671Z","steps":["trace[151219890] 'process raft request' (duration: 246.14923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:52.958485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.933874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-932f9e34-2208-4a53-aef7-74b5257a3beb\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-22T14:43:52.958507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.118587ms","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-08-22T14:43:52.958576Z","caller":"traceutil/trace.go:171","msg":"trace[1349417439] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-932f9e34-2208-4a53-aef7-74b5257a3beb; range_end:; response_count:1; response_revision:5295; }","duration":"147.070547ms","start":"2026-08-22T14:43:52.811487Z","end":"2026-08-22T14:43:52.958558Z","steps":["trace[1349417439] 'range keys from in-memory index tree' (duration: 146.78953ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:52.958602Z","caller":"traceutil/trace.go:171","msg":"trace[1780974644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5295; }","duration":"121.244541ms","start":"2026-08-22T14:43:52.837334Z","end":"2026-08-22T14:43:52.958579Z","steps":["trace[1780974644] 'range keys from in-memory index tree' (duration: 120.955233ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:53.182275Z","caller":"traceutil/trace.go:171","msg":"trace[627794450] linearizableReadLoop","detail":"{readStateIndex:5490; appliedIndex:5489; }","duration":"217.572018ms","start":"2026-08-22T14:43:52.964675Z","end":"2026-08-22T14:43:53.182247Z","steps":["trace[627794450] 'read index received' (duration: 217.473366ms)","trace[627794450] 'applied index is now lower than readState.Index' (duration: 97.292µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:53.182382Z","caller":"traceutil/trace.go:171","msg":"trace[833893355] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"219.135575ms","start":"2026-08-22T14:43:52.96321Z","end":"2026-08-22T14:43:53.182345Z","steps":["trace[833893355] 'process raft request' (duration: 218.860679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:53.182649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.953927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-22T14:43:53.182719Z","caller":"traceutil/trace.go:171","msg":"trace[1578269491] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5296; }","duration":"218.037279ms","start":"2026-08-22T14:43:52.964661Z","end":"2026-08-22T14:43:53.182698Z","steps":["trace[1578269491] 'agreement among raft nodes before linearized reading' (duration: 217.838334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:53.229364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.766343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-932f9e34-2208-4a53-aef7-74b5257a3beb\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-08-22T14:43:53.229417Z","caller":"traceutil/trace.go:171","msg":"trace[456024886] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"263.0029ms","start":"2026-08-22T14:43:52.966387Z","end":"2026-08-22T14:43:53.22939Z","steps":["trace[456024886] 'process raft request' (duration: 262.699812ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:53.229435Z","caller":"traceutil/trace.go:171","msg":"trace[1634095452] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-932f9e34-2208-4a53-aef7-74b5257a3beb; range_end:; response_count:1; response_revision:5297; }","duration":"203.878365ms","start":"2026-08-22T14:43:53.025541Z","end":"2026-08-22T14:43:53.229419Z","steps":["trace[1634095452] 'agreement among raft nodes before linearized reading' (duration: 203.749012ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:40.417735Z","caller":"traceutil/trace.go:171","msg":"trace[1456886198] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"104.777842ms","start":"2026-08-22T14:44:40.312931Z","end":"2026-08-22T14:44:40.417709Z","steps":["trace[1456886198] 'process raft request' (duration: 104.450163ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:53.525701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2777} {"level":"info","ts":"2026-08-22T14:44:53.60005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2777,"took":"72.827257ms","hash":973010460,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":33030144,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T14:44:53.600105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":973010460,"revision":2777,"compact-revision":-1} {"level":"warn","ts":"2026-08-22T14:49:38.653661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.206313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sbhn4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T14:49:38.653795Z","caller":"traceutil/trace.go:171","msg":"trace[1370391975] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sbhn4; range_end:; response_count:1; response_revision:7978; }","duration":"199.385907ms","start":"2026-08-22T14:49:38.454382Z","end":"2026-08-22T14:49:38.653768Z","steps":["trace[1370391975] 'range keys from in-memory index tree' (duration: 198.958578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:49:38.653921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.697894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-22T14:49:38.654022Z","caller":"traceutil/trace.go:171","msg":"trace[1137264838] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7978; }","duration":"366.841518ms","start":"2026-08-22T14:49:38.287156Z","end":"2026-08-22T14:49:38.653997Z","steps":["trace[1137264838] 'range keys from in-memory index tree' (duration: 366.589541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:49:38.654071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:49:38.287134Z","time spent":"366.927919ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T14:49:38.654143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.973318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-22T14:49:38.65421Z","caller":"traceutil/trace.go:171","msg":"trace[1469713446] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:7978; }","duration":"309.047759ms","start":"2026-08-22T14:49:38.345135Z","end":"2026-08-22T14:49:38.654183Z","steps":["trace[1469713446] 'range keys from in-memory index tree' (duration: 308.875796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:49:38.654255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:49:38.345124Z","time spent":"309.116081ms","remote":"127.0.0.1:41640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-22T14:49:38.654294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.295086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T14:49:38.654328Z","caller":"traceutil/trace.go:171","msg":"trace[1560220911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7978; }","duration":"364.349896ms","start":"2026-08-22T14:49:38.289968Z","end":"2026-08-22T14:49:38.654318Z","steps":["trace[1560220911] 'range keys from in-memory index tree' (duration: 364.240684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:49:38.654362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:49:38.289957Z","time spent":"364.392478ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T14:49:38.654614Z","caller":"traceutil/trace.go:171","msg":"trace[596858792] transaction","detail":"{read_only:false; response_revision:7979; number_of_response:1; }","duration":"189.348523ms","start":"2026-08-22T14:49:38.465237Z","end":"2026-08-22T14:49:38.654586Z","steps":["trace[596858792] 'process raft request' (duration: 102.700442ms)","trace[596858792] 'compare' (duration: 85.917613ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:49:50.398378Z","caller":"traceutil/trace.go:171","msg":"trace[687130775] transaction","detail":"{read_only:false; response_revision:8041; number_of_response:1; }","duration":"151.107501ms","start":"2026-08-22T14:49:50.247248Z","end":"2026-08-22T14:49:50.398356Z","steps":["trace[687130775] 'process raft request' (duration: 150.967907ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:51.210415Z","caller":"traceutil/trace.go:171","msg":"trace[247327585] transaction","detail":"{read_only:false; response_revision:8046; number_of_response:1; }","duration":"292.696821ms","start":"2026-08-22T14:49:50.917675Z","end":"2026-08-22T14:49:51.210371Z","steps":["trace[247327585] 'process raft request' (duration: 273.439773ms)","trace[247327585] 'compare' (duration: 18.91716ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:49:53.532972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5841} {"level":"info","ts":"2026-08-22T14:49:53.616074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5841,"took":"81.384813ms","hash":196057288,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":27152384,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-22T14:49:53.616135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":196057288,"revision":5841,"compact-revision":2777} {"level":"warn","ts":"2026-08-22T14:50:18.031552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.711732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:50:18.031644Z","caller":"traceutil/trace.go:171","msg":"trace[960878303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8243; }","duration":"127.839275ms","start":"2026-08-22T14:50:17.903786Z","end":"2026-08-22T14:50:18.031625Z","steps":["trace[960878303] 'range keys from in-memory index tree' (duration: 127.601259ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:30.751219Z","caller":"traceutil/trace.go:171","msg":"trace[1951557392] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"155.262292ms","start":"2026-08-22T14:50:30.595933Z","end":"2026-08-22T14:50:30.751196Z","steps":["trace[1951557392] 'process raft request' (duration: 155.032076ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:30.942304Z","caller":"traceutil/trace.go:171","msg":"trace[431472937] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"198.903675ms","start":"2026-08-22T14:50:30.743383Z","end":"2026-08-22T14:50:30.942286Z","steps":["trace[431472937] 'process raft request' (duration: 131.720059ms)","trace[431472937] 'compare' (duration: 66.997772ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:30.943479Z","caller":"traceutil/trace.go:171","msg":"trace[160212374] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"184.758212ms","start":"2026-08-22T14:50:30.758702Z","end":"2026-08-22T14:50:30.94346Z","steps":["trace[160212374] 'process raft request' (duration: 184.67685ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:41.483359Z","caller":"traceutil/trace.go:171","msg":"trace[1571183897] transaction","detail":"{read_only:false; response_revision:9011; number_of_response:1; }","duration":"121.872617ms","start":"2026-08-22T14:51:41.36143Z","end":"2026-08-22T14:51:41.483303Z","steps":["trace[1571183897] 'process raft request' (duration: 121.56756ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:41.646676Z","caller":"traceutil/trace.go:171","msg":"trace[1656777516] transaction","detail":"{read_only:false; response_revision:9012; number_of_response:1; }","duration":"284.71943ms","start":"2026-08-22T14:51:41.361914Z","end":"2026-08-22T14:51:41.646634Z","steps":["trace[1656777516] 'process raft request' (duration: 216.078703ms)","trace[1656777516] 'compare' (duration: 68.438511ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:51:41.646715Z","caller":"traceutil/trace.go:171","msg":"trace[2083322824] linearizableReadLoop","detail":"{readStateIndex:9322; appliedIndex:9320; }","duration":"278.862558ms","start":"2026-08-22T14:51:41.367804Z","end":"2026-08-22T14:51:41.646666Z","steps":["trace[2083322824] 'read index received' (duration: 115.186116ms)","trace[2083322824] 'applied index is now lower than readState.Index' (duration: 163.670652ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:51:41.757904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.149758ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T14:51:41.758005Z","caller":"traceutil/trace.go:171","msg":"trace[570916320] linearizableReadLoop","detail":"{readStateIndex:9323; appliedIndex:9322; }","duration":"111.011373ms","start":"2026-08-22T14:51:41.646976Z","end":"2026-08-22T14:51:41.757987Z","steps":["trace[570916320] 'read index received' (duration: 63.801µs)","trace[570916320] 'applied index is now lower than readState.Index' (duration: 110.945902ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:51:41.758344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.534547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sbhn4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T14:51:41.758381Z","caller":"traceutil/trace.go:171","msg":"trace[648300482] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sbhn4; range_end:; response_count:1; response_revision:9012; }","duration":"390.598998ms","start":"2026-08-22T14:51:41.367768Z","end":"2026-08-22T14:51:41.758367Z","steps":["trace[648300482] 'agreement among raft nodes before linearized reading' (duration: 279.002561ms)","trace[648300482] 'range keys from in-memory index tree' (duration: 111.461524ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:51:41.758421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:41.367756Z","time spent":"390.65618ms","remote":"127.0.0.1:41676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-sbhn4\" "} {"level":"warn","ts":"2026-08-22T14:51:41.758533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.274781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:51:41.758629Z","caller":"traceutil/trace.go:171","msg":"trace[1056122771] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:9012; }","duration":"144.433735ms","start":"2026-08-22T14:51:41.614174Z","end":"2026-08-22T14:51:41.758608Z","steps":["trace[1056122771] 'agreement among raft nodes before linearized reading' (duration: 144.24812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:41.75866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.66077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T14:51:41.758698Z","caller":"traceutil/trace.go:171","msg":"trace[352588871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9012; }","duration":"371.718761ms","start":"2026-08-22T14:51:41.38697Z","end":"2026-08-22T14:51:41.758689Z","steps":["trace[352588871] 'agreement among raft nodes before linearized reading' (duration: 371.615637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:51:41.758732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:51:41.38696Z","time spent":"371.765382ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:51:41.758837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.333672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:51:41.758912Z","caller":"traceutil/trace.go:171","msg":"trace[1119346534] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:9012; }","duration":"118.465606ms","start":"2026-08-22T14:51:41.640431Z","end":"2026-08-22T14:51:41.758897Z","steps":["trace[1119346534] 'agreement among raft nodes before linearized reading' (duration: 118.251201ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:43.078493Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002474.wal"} {"level":"info","ts":"2026-08-22T14:53:01.291022Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T14:53:01.296373Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T14:53:01.296477Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T14:54:53.54069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8063} {"level":"info","ts":"2026-08-22T14:54:53.61212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8063,"took":"69.235874ms","hash":86344248,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":25124864,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-22T14:54:53.612184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":86344248,"revision":8063,"compact-revision":5841} {"level":"info","ts":"2026-08-22T14:59:53.547323Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10599} {"level":"info","ts":"2026-08-22T14:59:53.622348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10599,"took":"72.686025ms","hash":2342518562,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":27672576,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T14:59:53.622428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2342518562,"revision":10599,"compact-revision":8063} {"level":"info","ts":"2026-08-22T15:01:36.564491Z","caller":"traceutil/trace.go:171","msg":"trace[35006424] linearizableReadLoop","detail":"{readStateIndex:13921; appliedIndex:13920; }","duration":"111.167631ms","start":"2026-08-22T15:01:36.453303Z","end":"2026-08-22T15:01:36.56447Z","steps":["trace[35006424] 'read index received' (duration: 108.085136ms)","trace[35006424] 'applied index is now lower than readState.Index' (duration: 3.081495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:01:36.564669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.348755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-08-22T15:01:36.564706Z","caller":"traceutil/trace.go:171","msg":"trace[1591963225] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:13467; }","duration":"111.415466ms","start":"2026-08-22T15:01:36.453281Z","end":"2026-08-22T15:01:36.564696Z","steps":["trace[1591963225] 'agreement among raft nodes before linearized reading' (duration: 111.277813ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:36.564741Z","caller":"traceutil/trace.go:171","msg":"trace[2061436632] transaction","detail":"{read_only:false; response_revision:13467; number_of_response:1; }","duration":"125.061418ms","start":"2026-08-22T15:01:36.439646Z","end":"2026-08-22T15:01:36.564707Z","steps":["trace[2061436632] 'process raft request' (duration: 121.754478ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:39.303913Z","caller":"traceutil/trace.go:171","msg":"trace[409487269] transaction","detail":"{read_only:false; response_revision:13481; number_of_response:1; }","duration":"106.677821ms","start":"2026-08-22T15:01:39.197214Z","end":"2026-08-22T15:01:39.303892Z","steps":["trace[409487269] 'process raft request' (duration: 106.25049ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:57.395574Z","caller":"traceutil/trace.go:171","msg":"trace[993253570] transaction","detail":"{read_only:false; response_revision:13574; number_of_response:1; }","duration":"107.137563ms","start":"2026-08-22T15:01:57.288418Z","end":"2026-08-22T15:01:57.395556Z","steps":["trace[993253570] 'process raft request' (duration: 107.027941ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:01:57.396568Z","caller":"traceutil/trace.go:171","msg":"trace[1521231839] transaction","detail":"{read_only:false; response_revision:13575; number_of_response:1; }","duration":"108.114867ms","start":"2026-08-22T15:01:57.288428Z","end":"2026-08-22T15:01:57.396543Z","steps":["trace[1521231839] 'process raft request' (duration: 107.864051ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:10.713365Z","caller":"traceutil/trace.go:171","msg":"trace[1285105867] linearizableReadLoop","detail":"{readStateIndex:14117; appliedIndex:14116; }","duration":"169.21702ms","start":"2026-08-22T15:02:10.544126Z","end":"2026-08-22T15:02:10.713343Z","steps":["trace[1285105867] 'read index received' (duration: 147.81298ms)","trace[1285105867] 'applied index is now lower than readState.Index' (duration: 21.4031ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:02:10.713617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.473166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-22T15:02:10.713689Z","caller":"traceutil/trace.go:171","msg":"trace[157142106] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13655; }","duration":"169.572779ms","start":"2026-08-22T15:02:10.544098Z","end":"2026-08-22T15:02:10.713671Z","steps":["trace[157142106] 'agreement among raft nodes before linearized reading' (duration: 169.383214ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:10.713804Z","caller":"traceutil/trace.go:171","msg":"trace[1860254440] transaction","detail":"{read_only:false; response_revision:13655; number_of_response:1; }","duration":"170.298997ms","start":"2026-08-22T15:02:10.543478Z","end":"2026-08-22T15:02:10.713777Z","steps":["trace[1860254440] 'process raft request' (duration: 148.519248ms)","trace[1860254440] 'compare' (duration: 21.086212ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:02:11.420347Z","caller":"traceutil/trace.go:171","msg":"trace[642379478] linearizableReadLoop","detail":"{readStateIndex:14121; appliedIndex:14120; }","duration":"200.349316ms","start":"2026-08-22T15:02:11.21996Z","end":"2026-08-22T15:02:11.420309Z","steps":["trace[642379478] 'read index received' (duration: 200.207182ms)","trace[642379478] 'applied index is now lower than readState.Index' (duration: 141.064µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:02:11.420544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.579071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-22T15:02:11.420571Z","caller":"traceutil/trace.go:171","msg":"trace[1353587773] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:13659; }","duration":"200.683604ms","start":"2026-08-22T15:02:11.219879Z","end":"2026-08-22T15:02:11.420563Z","steps":["trace[1353587773] 'agreement among raft nodes before linearized reading' (duration: 200.5225ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:11.420563Z","caller":"traceutil/trace.go:171","msg":"trace[806228884] transaction","detail":"{read_only:false; response_revision:13659; number_of_response:1; }","duration":"206.805743ms","start":"2026-08-22T15:02:11.21372Z","end":"2026-08-22T15:02:11.420526Z","steps":["trace[806228884] 'process raft request' (duration: 206.438104ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:20.187428Z","caller":"traceutil/trace.go:171","msg":"trace[15027598] transaction","detail":"{read_only:false; response_revision:13815; number_of_response:1; }","duration":"130.567582ms","start":"2026-08-22T15:02:20.056833Z","end":"2026-08-22T15:02:20.1874Z","steps":["trace[15027598] 'process raft request' (duration: 130.311606ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:20.315615Z","caller":"traceutil/trace.go:171","msg":"trace[824313462] transaction","detail":"{read_only:false; response_revision:13816; number_of_response:1; }","duration":"122.664119ms","start":"2026-08-22T15:02:20.192923Z","end":"2026-08-22T15:02:20.315587Z","steps":["trace[824313462] 'process raft request' (duration: 122.237358ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:58.835089Z","caller":"traceutil/trace.go:171","msg":"trace[1944503184] transaction","detail":"{read_only:false; response_revision:14898; number_of_response:1; }","duration":"208.531045ms","start":"2026-08-22T15:03:58.626533Z","end":"2026-08-22T15:03:58.835064Z","steps":["trace[1944503184] 'process raft request' (duration: 176.315452ms)","trace[1944503184] 'compare' (duration: 32.067979ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:03:59.605283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.56688ms","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-08-22T15:03:59.605379Z","caller":"traceutil/trace.go:171","msg":"trace[611827841] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14902; }","duration":"125.713023ms","start":"2026-08-22T15:03:59.479647Z","end":"2026-08-22T15:03:59.60536Z","steps":["trace[611827841] 'range keys from in-memory index tree' (duration: 125.389376ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:04:53.554196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12791} {"level":"info","ts":"2026-08-22T15:04:53.634651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12791,"took":"77.832708ms","hash":3568737716,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":29855744,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T15:04:53.634763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3568737716,"revision":12791,"compact-revision":10599} {"level":"info","ts":"2026-08-22T15:07:33.689808Z","caller":"traceutil/trace.go:171","msg":"trace[311664662] linearizableReadLoop","detail":"{readStateIndex:17156; appliedIndex:17155; }","duration":"125.29979ms","start":"2026-08-22T15:07:33.564489Z","end":"2026-08-22T15:07:33.689789Z","steps":["trace[311664662] 'read index received' (duration: 124.972002ms)","trace[311664662] 'applied index is now lower than readState.Index' (duration: 327.108µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:07:33.69019Z","caller":"traceutil/trace.go:171","msg":"trace[1309705678] transaction","detail":"{read_only:false; response_revision:16605; number_of_response:1; }","duration":"173.99123ms","start":"2026-08-22T15:07:33.516188Z","end":"2026-08-22T15:07:33.690179Z","steps":["trace[1309705678] 'process raft request' (duration: 173.475528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:07:33.69044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.932215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:07:33.690473Z","caller":"traceutil/trace.go:171","msg":"trace[578823816] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:16605; }","duration":"125.981026ms","start":"2026-08-22T15:07:33.564483Z","end":"2026-08-22T15:07:33.690464Z","steps":["trace[578823816] 'agreement among raft nodes before linearized reading' (duration: 125.907524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:07:38.639285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.719055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:07:38.639415Z","caller":"traceutil/trace.go:171","msg":"trace[388295131] linearizableReadLoop","detail":"{readStateIndex:17213; appliedIndex:17212; }","duration":"151.620479ms","start":"2026-08-22T15:07:38.487774Z","end":"2026-08-22T15:07:38.639394Z","steps":["trace[388295131] 'read index received' (duration: 38.585036ms)","trace[388295131] 'applied index is now lower than readState.Index' (duration: 113.033803ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:07:38.639495Z","caller":"traceutil/trace.go:171","msg":"trace[1012929409] transaction","detail":"{read_only:false; response_revision:16661; number_of_response:1; }","duration":"199.680345ms","start":"2026-08-22T15:07:38.43978Z","end":"2026-08-22T15:07:38.639461Z","steps":["trace[1012929409] 'process raft request' (duration: 86.665642ms)","trace[1012929409] 'compare' (duration: 112.405877ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:07:38.639663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.884395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-service\" ","response":"range_response_count:1 size:6436"} {"level":"info","ts":"2026-08-22T15:07:38.639698Z","caller":"traceutil/trace.go:171","msg":"trace[1982405761] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-service; range_end:; response_count:1; response_revision:16661; }","duration":"151.919716ms","start":"2026-08-22T15:07:38.487767Z","end":"2026-08-22T15:07:38.639686Z","steps":["trace[1982405761] 'agreement among raft nodes before linearized reading' (duration: 151.731611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:07:38.639804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.184627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:676"} {"level":"info","ts":"2026-08-22T15:07:38.639921Z","caller":"traceutil/trace.go:171","msg":"trace[185647893] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:16661; }","duration":"151.29368ms","start":"2026-08-22T15:07:38.488576Z","end":"2026-08-22T15:07:38.639869Z","steps":["trace[185647893] 'agreement among raft nodes before linearized reading' (duration: 151.050155ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:07:39.021356Z","caller":"traceutil/trace.go:171","msg":"trace[1827938283] transaction","detail":"{read_only:false; response_revision:16662; number_of_response:1; }","duration":"327.152516ms","start":"2026-08-22T15:07:38.694161Z","end":"2026-08-22T15:07:39.021314Z","steps":["trace[1827938283] 'process raft request' (duration: 326.930311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:07:39.021603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:07:38.694132Z","time spent":"327.338831ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:07:39.046513Z","caller":"traceutil/trace.go:171","msg":"trace[1693088663] linearizableReadLoop","detail":"{readStateIndex:17215; appliedIndex:17213; }","duration":"307.776946ms","start":"2026-08-22T15:07:38.738714Z","end":"2026-08-22T15:07:39.046491Z","steps":["trace[1693088663] 'read index received' (duration: 282.39798ms)","trace[1693088663] 'applied index is now lower than readState.Index' (duration: 25.378106ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:07:39.046754Z","caller":"traceutil/trace.go:171","msg":"trace[973777771] transaction","detail":"{read_only:false; response_revision:16663; number_of_response:1; }","duration":"315.196576ms","start":"2026-08-22T15:07:38.731546Z","end":"2026-08-22T15:07:39.046743Z","steps":["trace[973777771] 'process raft request' (duration: 314.848988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:07:39.046833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:07:38.731523Z","time spent":"315.268157ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T15:07:39.047262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.538564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ks-endpoints-g625s\" ","response":"range_response_count:1 size:14863"} {"level":"info","ts":"2026-08-22T15:07:39.047325Z","caller":"traceutil/trace.go:171","msg":"trace[798781916] range","detail":"{range_begin:/registry/pods/openstack/neutron-ks-endpoints-g625s; range_end:; response_count:1; response_revision:16663; }","duration":"308.640596ms","start":"2026-08-22T15:07:38.738669Z","end":"2026-08-22T15:07:39.047309Z","steps":["trace[798781916] 'agreement among raft nodes before linearized reading' (duration: 308.433812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:07:39.047377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:07:38.73865Z","time spent":"308.717189ms","remote":"127.0.0.1:41676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":14886,"request content":"key:\"/registry/pods/openstack/neutron-ks-endpoints-g625s\" "} {"level":"warn","ts":"2026-08-22T15:07:39.047536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.808736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:07:39.047639Z","caller":"traceutil/trace.go:171","msg":"trace[1101496730] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:16663; }","duration":"213.957019ms","start":"2026-08-22T15:07:38.83364Z","end":"2026-08-22T15:07:39.047597Z","steps":["trace[1101496730] 'agreement among raft nodes before linearized reading' (duration: 213.761205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:07:39.047724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.17361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:07:39.047769Z","caller":"traceutil/trace.go:171","msg":"trace[1500414159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16663; }","duration":"180.255452ms","start":"2026-08-22T15:07:38.867503Z","end":"2026-08-22T15:07:39.047758Z","steps":["trace[1500414159] 'agreement among raft nodes before linearized reading' (duration: 180.191141ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:45.355935Z","caller":"traceutil/trace.go:171","msg":"trace[2007119632] transaction","detail":"{read_only:false; response_revision:17277; number_of_response:1; }","duration":"122.939998ms","start":"2026-08-22T15:08:45.23297Z","end":"2026-08-22T15:08:45.35591Z","steps":["trace[2007119632] 'process raft request' (duration: 110.465466ms)","trace[2007119632] 'compare' (duration: 11.827217ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:08:45.356036Z","caller":"traceutil/trace.go:171","msg":"trace[1042623980] transaction","detail":"{read_only:false; response_revision:17278; number_of_response:1; }","duration":"122.416826ms","start":"2026-08-22T15:08:45.233598Z","end":"2026-08-22T15:08:45.356015Z","steps":["trace[1042623980] 'process raft request' (duration: 122.030467ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:09:53.560912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15275} {"level":"info","ts":"2026-08-22T15:09:53.655931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15275,"took":"92.04386ms","hash":1293142118,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":33615872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T15:09:53.656029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1293142118,"revision":15275,"compact-revision":12791} {"level":"info","ts":"2026-08-22T15:12:38.157769Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T15:12:38.163682Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T15:12:38.163786Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T15:14:53.56779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17914} {"level":"info","ts":"2026-08-22T15:14:53.676694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17914,"took":"106.318346ms","hash":2810777204,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":32133120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T15:14:53.67676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2810777204,"revision":17914,"compact-revision":15275} {"level":"warn","ts":"2026-08-22T15:15:52.728137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.280734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-22T15:15:52.728233Z","caller":"traceutil/trace.go:171","msg":"trace[24491038] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20760; }","duration":"101.422438ms","start":"2026-08-22T15:15:52.62679Z","end":"2026-08-22T15:15:52.728212Z","steps":["trace[24491038] 'range keys from in-memory index tree' (duration: 101.11562ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:15:58.70481Z","caller":"traceutil/trace.go:171","msg":"trace[146113587] transaction","detail":"{read_only:false; response_revision:20793; number_of_response:1; }","duration":"136.476826ms","start":"2026-08-22T15:15:58.568308Z","end":"2026-08-22T15:15:58.704785Z","steps":["trace[146113587] 'process raft request' (duration: 136.305442ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:15:58.964722Z","caller":"traceutil/trace.go:171","msg":"trace[1615114781] transaction","detail":"{read_only:false; response_revision:20794; number_of_response:1; }","duration":"174.372964ms","start":"2026-08-22T15:15:58.790327Z","end":"2026-08-22T15:15:58.9647Z","steps":["trace[1615114781] 'process raft request' (duration: 174.222161ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:15:59.091164Z","caller":"traceutil/trace.go:171","msg":"trace[1182314964] linearizableReadLoop","detail":"{readStateIndex:21478; appliedIndex:21476; }","duration":"127.751346ms","start":"2026-08-22T15:15:58.963388Z","end":"2026-08-22T15:15:59.091139Z","steps":["trace[1182314964] 'read index received' (duration: 1.130027ms)","trace[1182314964] 'applied index is now lower than readState.Index' (duration: 126.620139ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:15:59.091288Z","caller":"traceutil/trace.go:171","msg":"trace[1782767109] transaction","detail":"{read_only:false; response_revision:20795; number_of_response:1; }","duration":"275.598858ms","start":"2026-08-22T15:15:58.815671Z","end":"2026-08-22T15:15:59.09127Z","steps":["trace[1782767109] 'process raft request' (duration: 259.963198ms)","trace[1782767109] 'compare' (duration: 15.394623ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:15:59.091465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.058733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T15:15:59.091523Z","caller":"traceutil/trace.go:171","msg":"trace[787795666] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:20795; }","duration":"128.168845ms","start":"2026-08-22T15:15:58.963341Z","end":"2026-08-22T15:15:59.09151Z","steps":["trace[787795666] 'agreement among raft nodes before linearized reading' (duration: 127.883049ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:15:59.303493Z","caller":"traceutil/trace.go:171","msg":"trace[811409306] transaction","detail":"{read_only:false; response_revision:20796; number_of_response:1; }","duration":"175.271576ms","start":"2026-08-22T15:15:59.128192Z","end":"2026-08-22T15:15:59.303464Z","steps":["trace[811409306] 'process raft request' (duration: 87.018208ms)","trace[811409306] 'compare' (duration: 88.134145ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:15:59.560433Z","caller":"traceutil/trace.go:171","msg":"trace[2025845374] transaction","detail":"{read_only:false; response_revision:20799; number_of_response:1; }","duration":"147.75476ms","start":"2026-08-22T15:15:59.412648Z","end":"2026-08-22T15:15:59.560403Z","steps":["trace[2025845374] 'process raft request' (duration: 147.596346ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:19:18.645612Z","caller":"traceutil/trace.go:171","msg":"trace[525148977] transaction","detail":"{read_only:false; response_revision:22651; number_of_response:1; }","duration":"156.257071ms","start":"2026-08-22T15:19:18.489317Z","end":"2026-08-22T15:19:18.645574Z","steps":["trace[525148977] 'process raft request' (duration: 155.787119ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:19:18.645632Z","caller":"traceutil/trace.go:171","msg":"trace[447653034] transaction","detail":"{read_only:false; response_revision:22652; number_of_response:1; }","duration":"150.544221ms","start":"2026-08-22T15:19:18.495056Z","end":"2026-08-22T15:19:18.6456Z","steps":["trace[447653034] 'process raft request' (duration: 150.363917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:19:28.089639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.095238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-22T15:19:28.089715Z","caller":"traceutil/trace.go:171","msg":"trace[1810503466] transaction","detail":"{read_only:false; response_revision:23062; number_of_response:1; }","duration":"108.240865ms","start":"2026-08-22T15:19:27.981445Z","end":"2026-08-22T15:19:28.089686Z","steps":["trace[1810503466] 'process raft request' (duration: 107.808705ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:19:28.089746Z","caller":"traceutil/trace.go:171","msg":"trace[1065366392] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23062; }","duration":"100.207611ms","start":"2026-08-22T15:19:27.989513Z","end":"2026-08-22T15:19:28.08972Z","steps":["trace[1065366392] 'agreement among raft nodes before linearized reading' (duration: 99.905914ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:19:53.57376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20368} {"level":"info","ts":"2026-08-22T15:19:53.678126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20368,"took":"101.491461ms","hash":1242614589,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":43753472,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-22T15:19:53.678181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1242614589,"revision":20368,"compact-revision":17914} {"level":"info","ts":"2026-08-22T15:20:53.608711Z","caller":"traceutil/trace.go:171","msg":"trace[335615244] transaction","detail":"{read_only:false; response_revision:24033; number_of_response:1; }","duration":"110.674085ms","start":"2026-08-22T15:20:53.498014Z","end":"2026-08-22T15:20:53.608688Z","steps":["trace[335615244] 'process raft request' (duration: 110.534312ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:45.430368Z","caller":"traceutil/trace.go:171","msg":"trace[123322825] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25942; }","duration":"183.492891ms","start":"2026-08-22T15:22:45.246856Z","end":"2026-08-22T15:22:45.430349Z","steps":["trace[123322825] 'read index received' (duration: 183.327337ms)","trace[123322825] 'applied index is now lower than readState.Index' (duration: 165.064µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:45.430605Z","caller":"traceutil/trace.go:171","msg":"trace[1987987309] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"189.473025ms","start":"2026-08-22T15:22:45.241122Z","end":"2026-08-22T15:22:45.430595Z","steps":["trace[1987987309] 'process raft request' (duration: 189.114686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:45.430753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.850691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-08-22T15:22:45.430839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.958602ms","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-08-22T15:22:45.430862Z","caller":"traceutil/trace.go:171","msg":"trace[772323481] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25140; }","duration":"121.006856ms","start":"2026-08-22T15:22:45.309839Z","end":"2026-08-22T15:22:45.430846Z","steps":["trace[772323481] 'agreement among raft nodes before linearized reading' (duration: 120.745558ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:45.4309Z","caller":"traceutil/trace.go:171","msg":"trace[5088778] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25140; }","duration":"184.052194ms","start":"2026-08-22T15:22:45.24683Z","end":"2026-08-22T15:22:45.430882Z","steps":["trace[5088778] 'agreement among raft nodes before linearized reading' (duration: 183.778027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:45.765062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.225905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:22:45.76533Z","caller":"traceutil/trace.go:171","msg":"trace[1285509586] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"329.268666ms","start":"2026-08-22T15:22:45.436039Z","end":"2026-08-22T15:22:45.765308Z","steps":["trace[1285509586] 'process raft request' (duration: 72.695573ms)","trace[1285509586] 'compare' (duration: 256.108752ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:45.76544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:45.43603Z","time spent":"329.349589ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:22:45.765669Z","caller":"traceutil/trace.go:171","msg":"trace[1115963445] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"329.629606ms","start":"2026-08-22T15:22:45.43603Z","end":"2026-08-22T15:22:45.76566Z","steps":["trace[1115963445] 'process raft request' (duration: 329.227536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:45.765722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:45.436007Z","time spent":"329.692857ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:22:46.034489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.892602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:46.034565Z","caller":"traceutil/trace.go:171","msg":"trace[770888330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25143; }","duration":"168.011115ms","start":"2026-08-22T15:22:45.866537Z","end":"2026-08-22T15:22:46.034548Z","steps":["trace[770888330] 'range keys from in-memory index tree' (duration: 167.81365ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:46.155359Z","caller":"traceutil/trace.go:171","msg":"trace[712524347] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"147.234081ms","start":"2026-08-22T15:22:46.008106Z","end":"2026-08-22T15:22:46.15534Z","steps":["trace[712524347] 'process raft request' (duration: 147.127488ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:46.155678Z","caller":"traceutil/trace.go:171","msg":"trace[1951130656] linearizableReadLoop","detail":"{readStateIndex:25947; appliedIndex:25947; }","duration":"143.133132ms","start":"2026-08-22T15:22:46.01254Z","end":"2026-08-22T15:22:46.155673Z","steps":["trace[1951130656] 'read index received' (duration: 143.129982ms)","trace[1951130656] 'applied index is now lower than readState.Index' (duration: 2.73µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:46.155743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.088463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:46.155758Z","caller":"traceutil/trace.go:171","msg":"trace[883864954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25144; }","duration":"120.144994ms","start":"2026-08-22T15:22:46.035609Z","end":"2026-08-22T15:22:46.155754Z","steps":["trace[883864954] 'agreement among raft nodes before linearized reading' (duration: 120.102883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:46.157144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.586067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:22:46.157223Z","caller":"traceutil/trace.go:171","msg":"trace[1838040848] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25144; }","duration":"144.70583ms","start":"2026-08-22T15:22:46.012498Z","end":"2026-08-22T15:22:46.157204Z","steps":["trace[1838040848] 'agreement among raft nodes before linearized reading' (duration: 143.330457ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:46.399755Z","caller":"traceutil/trace.go:171","msg":"trace[1655372503] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"200.492212ms","start":"2026-08-22T15:22:46.19923Z","end":"2026-08-22T15:22:46.399722Z","steps":["trace[1655372503] 'process raft request' (duration: 188.791409ms)","trace[1655372503] 'compare' (duration: 11.051508ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:46.651492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.355059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:22:46.651558Z","caller":"traceutil/trace.go:171","msg":"trace[1437182510] linearizableReadLoop","detail":"{readStateIndex:25949; appliedIndex:25948; }","duration":"125.74049ms","start":"2026-08-22T15:22:46.525808Z","end":"2026-08-22T15:22:46.651549Z","steps":["trace[1437182510] 'read index received' (duration: 5.276658ms)","trace[1437182510] 'applied index is now lower than readState.Index' (duration: 120.463122ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:46.651614Z","caller":"traceutil/trace.go:171","msg":"trace[1940351178] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"238.867863ms","start":"2026-08-22T15:22:46.412724Z","end":"2026-08-22T15:22:46.651592Z","steps":["trace[1940351178] 'process raft request' (duration: 118.376271ms)","trace[1940351178] 'compare' (duration: 120.262857ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:46.651678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.878153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T15:22:46.651695Z","caller":"traceutil/trace.go:171","msg":"trace[1740327873] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:25146; }","duration":"125.931505ms","start":"2026-08-22T15:22:46.525759Z","end":"2026-08-22T15:22:46.65169Z","steps":["trace[1740327873] 'agreement among raft nodes before linearized reading' (duration: 125.845482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:46.651832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.449086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:22:46.651851Z","caller":"traceutil/trace.go:171","msg":"trace[511497386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25146; }","duration":"107.468396ms","start":"2026-08-22T15:22:46.544379Z","end":"2026-08-22T15:22:46.651847Z","steps":["trace[511497386] 'agreement among raft nodes before linearized reading' (duration: 107.408335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:47.009014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.827438ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-22T15:22:47.009257Z","caller":"traceutil/trace.go:171","msg":"trace[80980190] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"226.172856ms","start":"2026-08-22T15:22:46.783067Z","end":"2026-08-22T15:22:47.00924Z","steps":["trace[80980190] 'process raft request' (duration: 226.056153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:47.009256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:46.656571Z","time spent":"352.673354ms","remote":"127.0.0.1:41522","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-22T15:22:47.00939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.767917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:22:47.009425Z","caller":"traceutil/trace.go:171","msg":"trace[1346814916] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25148; }","duration":"331.864649ms","start":"2026-08-22T15:22:46.677554Z","end":"2026-08-22T15:22:47.009418Z","steps":["trace[1346814916] 'agreement among raft nodes before linearized reading' (duration: 331.738996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:47.00945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:46.677527Z","time spent":"331.91718ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-22T15:22:47.009256Z","caller":"traceutil/trace.go:171","msg":"trace[1936370492] linearizableReadLoop","detail":"{readStateIndex:25951; appliedIndex:25950; }","duration":"331.633444ms","start":"2026-08-22T15:22:46.677602Z","end":"2026-08-22T15:22:47.009235Z","steps":["trace[1936370492] 'read index received' (duration: 119.57055ms)","trace[1936370492] 'applied index is now lower than readState.Index' (duration: 212.060974ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:47.22249Z","caller":"traceutil/trace.go:171","msg":"trace[1400866432] linearizableReadLoop","detail":"{readStateIndex:25954; appliedIndex:25953; }","duration":"163.518046ms","start":"2026-08-22T15:22:47.058957Z","end":"2026-08-22T15:22:47.222475Z","steps":["trace[1400866432] 'read index received' (duration: 20.494807ms)","trace[1400866432] 'applied index is now lower than readState.Index' (duration: 143.022089ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:47.222567Z","caller":"traceutil/trace.go:171","msg":"trace[465278647] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"212.040593ms","start":"2026-08-22T15:22:47.010517Z","end":"2026-08-22T15:22:47.222558Z","steps":["trace[465278647] 'process raft request' (duration: 186.667647ms)","trace[465278647] 'compare' (duration: 25.098519ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:47.222854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.882504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T15:22:47.222883Z","caller":"traceutil/trace.go:171","msg":"trace[1325423976] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25150; }","duration":"163.956636ms","start":"2026-08-22T15:22:47.05892Z","end":"2026-08-22T15:22:47.222877Z","steps":["trace[1325423976] 'agreement among raft nodes before linearized reading' (duration: 163.765752ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:47.48459Z","caller":"traceutil/trace.go:171","msg":"trace[877300773] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"325.744492ms","start":"2026-08-22T15:22:47.158805Z","end":"2026-08-22T15:22:47.484549Z","steps":["trace[877300773] 'process raft request' (duration: 279.805267ms)","trace[877300773] 'compare' (duration: 45.780971ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:47.484588Z","caller":"traceutil/trace.go:171","msg":"trace[1901303740] linearizableReadLoop","detail":"{readStateIndex:25955; appliedIndex:25954; }","duration":"262.009126ms","start":"2026-08-22T15:22:47.222541Z","end":"2026-08-22T15:22:47.48455Z","steps":["trace[1901303740] 'read index received' (duration: 216.02907ms)","trace[1901303740] 'applied index is now lower than readState.Index' (duration: 45.978466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:47.484737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:47.158778Z","time spent":"325.884915ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:22:47.484806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.395152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-08-22T15:22:47.484794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.787361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-22T15:22:47.484794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.905907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-22T15:22:47.48483Z","caller":"traceutil/trace.go:171","msg":"trace[664382671] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25151; }","duration":"259.452503ms","start":"2026-08-22T15:22:47.22537Z","end":"2026-08-22T15:22:47.484823Z","steps":["trace[664382671] 'agreement among raft nodes before linearized reading' (duration: 259.380822ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:47.484849Z","caller":"traceutil/trace.go:171","msg":"trace[2142250988] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25151; }","duration":"285.876134ms","start":"2026-08-22T15:22:47.198956Z","end":"2026-08-22T15:22:47.484832Z","steps":["trace[2142250988] 'agreement among raft nodes before linearized reading' (duration: 285.639708ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:47.484855Z","caller":"traceutil/trace.go:171","msg":"trace[481117108] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25151; }","duration":"410.974359ms","start":"2026-08-22T15:22:47.073865Z","end":"2026-08-22T15:22:47.48484Z","steps":["trace[481117108] 'agreement among raft nodes before linearized reading' (duration: 410.781634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:47.484818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.283886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-22T15:22:47.484939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.445641ms","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-08-22T15:22:47.484959Z","caller":"traceutil/trace.go:171","msg":"trace[533477541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25151; }","duration":"256.490701ms","start":"2026-08-22T15:22:47.228463Z","end":"2026-08-22T15:22:47.484954Z","steps":["trace[533477541] 'agreement among raft nodes before linearized reading' (duration: 256.43675ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:47.484955Z","caller":"traceutil/trace.go:171","msg":"trace[282903790] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25151; }","duration":"284.45409ms","start":"2026-08-22T15:22:47.200473Z","end":"2026-08-22T15:22:47.484927Z","steps":["trace[282903790] 'agreement among raft nodes before linearized reading' (duration: 284.089361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:47.484979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.886416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-22T15:22:47.484888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:47.073837Z","time spent":"411.04437ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T15:22:47.485012Z","caller":"traceutil/trace.go:171","msg":"trace[487714852] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25151; }","duration":"264.938557ms","start":"2026-08-22T15:22:47.220063Z","end":"2026-08-22T15:22:47.485002Z","steps":["trace[487714852] 'agreement among raft nodes before linearized reading' (duration: 264.551507ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:47.638092Z","caller":"traceutil/trace.go:171","msg":"trace[1741621715] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"144.101435ms","start":"2026-08-22T15:22:47.493964Z","end":"2026-08-22T15:22:47.638065Z","steps":["trace[1741621715] 'process raft request' (duration: 131.022909ms)","trace[1741621715] 'compare' (duration: 12.868361ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:47.638105Z","caller":"traceutil/trace.go:171","msg":"trace[1743938449] linearizableReadLoop","detail":"{readStateIndex:25958; appliedIndex:25955; }","duration":"141.919731ms","start":"2026-08-22T15:22:47.496163Z","end":"2026-08-22T15:22:47.638082Z","steps":["trace[1743938449] 'read index received' (duration: 128.835455ms)","trace[1743938449] 'applied index is now lower than readState.Index' (duration: 13.083006ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:47.63814Z","caller":"traceutil/trace.go:171","msg":"trace[490234536] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"143.385127ms","start":"2026-08-22T15:22:47.494725Z","end":"2026-08-22T15:22:47.63811Z","steps":["trace[490234536] 'process raft request' (duration: 143.269124ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:47.638213Z","caller":"traceutil/trace.go:171","msg":"trace[1026864683] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"142.235149ms","start":"2026-08-22T15:22:47.495963Z","end":"2026-08-22T15:22:47.638199Z","steps":["trace[1026864683] 'process raft request' (duration: 142.082346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:47.638352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.169778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:22:47.638388Z","caller":"traceutil/trace.go:171","msg":"trace[45214869] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25154; }","duration":"142.251041ms","start":"2026-08-22T15:22:47.496126Z","end":"2026-08-22T15:22:47.638377Z","steps":["trace[45214869] 'agreement among raft nodes before linearized reading' (duration: 142.077895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:47.893225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.066278ms","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-08-22T15:22:47.893301Z","caller":"traceutil/trace.go:171","msg":"trace[297003311] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25154; }","duration":"124.211023ms","start":"2026-08-22T15:22:47.769073Z","end":"2026-08-22T15:22:47.893284Z","steps":["trace[297003311] 'range keys from in-memory index tree' (duration: 123.893804ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:47.893423Z","caller":"traceutil/trace.go:171","msg":"trace[2001307783] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"121.683942ms","start":"2026-08-22T15:22:47.771729Z","end":"2026-08-22T15:22:47.893413Z","steps":["trace[2001307783] 'process raft request' (duration: 119.400767ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:48.251516Z","caller":"traceutil/trace.go:171","msg":"trace[1661340087] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"352.417647ms","start":"2026-08-22T15:22:47.899066Z","end":"2026-08-22T15:22:48.251484Z","steps":["trace[1661340087] 'process raft request' (duration: 352.138301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:48.25172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:47.899049Z","time spent":"352.579031ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:22:48.441393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.310754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:22:48.441558Z","caller":"traceutil/trace.go:171","msg":"trace[1258466559] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"281.065407ms","start":"2026-08-22T15:22:48.160455Z","end":"2026-08-22T15:22:48.44152Z","steps":["trace[1258466559] 'process raft request' (duration: 128.564898ms)","trace[1258466559] 'compare' (duration: 152.205052ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:48.692031Z","caller":"traceutil/trace.go:171","msg":"trace[1181982311] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"246.997102ms","start":"2026-08-22T15:22:48.445002Z","end":"2026-08-22T15:22:48.692Z","steps":["trace[1181982311] 'process raft request' (duration: 166.927299ms)","trace[1181982311] 'compare' (duration: 79.890028ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:48.69215Z","caller":"traceutil/trace.go:171","msg":"trace[1264333261] linearizableReadLoop","detail":"{readStateIndex:25963; appliedIndex:25962; }","duration":"246.637722ms","start":"2026-08-22T15:22:48.445498Z","end":"2026-08-22T15:22:48.692136Z","steps":["trace[1264333261] 'read index received' (duration: 166.381725ms)","trace[1264333261] 'applied index is now lower than readState.Index' (duration: 80.254867ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:48.692573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.060893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ce2b2860-6a04-4ff9-97f8-add2256b5fb8\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-22T15:22:48.692627Z","caller":"traceutil/trace.go:171","msg":"trace[724860499] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ce2b2860-6a04-4ff9-97f8-add2256b5fb8; range_end:; response_count:1; response_revision:25160; }","duration":"247.140094ms","start":"2026-08-22T15:22:48.445473Z","end":"2026-08-22T15:22:48.692613Z","steps":["trace[724860499] 'agreement among raft nodes before linearized reading' (duration: 246.751325ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:48.976506Z","caller":"traceutil/trace.go:171","msg":"trace[1932266529] linearizableReadLoop","detail":"{readStateIndex:25966; appliedIndex:25965; }","duration":"149.835874ms","start":"2026-08-22T15:22:48.826647Z","end":"2026-08-22T15:22:48.976483Z","steps":["trace[1932266529] 'read index received' (duration: 149.68537ms)","trace[1932266529] 'applied index is now lower than readState.Index' (duration: 149.534µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:48.976598Z","caller":"traceutil/trace.go:171","msg":"trace[1716631014] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"196.755362ms","start":"2026-08-22T15:22:48.77982Z","end":"2026-08-22T15:22:48.976576Z","steps":["trace[1716631014] 'process raft request' (duration: 196.519927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:48.976683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.019318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:48.976769Z","caller":"traceutil/trace.go:171","msg":"trace[1793615963] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25162; }","duration":"150.178292ms","start":"2026-08-22T15:22:48.826571Z","end":"2026-08-22T15:22:48.97675Z","steps":["trace[1793615963] 'agreement among raft nodes before linearized reading' (duration: 150.019729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:49.300208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.289084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:22:49.300353Z","caller":"traceutil/trace.go:171","msg":"trace[93540430] linearizableReadLoop","detail":"{readStateIndex:25967; appliedIndex:25966; }","duration":"258.338875ms","start":"2026-08-22T15:22:49.041952Z","end":"2026-08-22T15:22:49.300291Z","steps":["trace[93540430] 'read index received' (duration: 112.867657ms)","trace[93540430] 'applied index is now lower than readState.Index' (duration: 145.469548ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:49.301242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.261148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:49.301373Z","caller":"traceutil/trace.go:171","msg":"trace[1872514931] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25163; }","duration":"259.416221ms","start":"2026-08-22T15:22:49.041904Z","end":"2026-08-22T15:22:49.30132Z","steps":["trace[1872514931] 'agreement among raft nodes before linearized reading' (duration: 258.873318ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:49.306478Z","caller":"traceutil/trace.go:171","msg":"trace[962836217] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"293.003367ms","start":"2026-08-22T15:22:49.013443Z","end":"2026-08-22T15:22:49.306447Z","steps":["trace[962836217] 'process raft request' (duration: 141.361659ms)","trace[962836217] 'compare' (duration: 143.669365ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:49.36374Z","caller":"traceutil/trace.go:171","msg":"trace[875616334] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"280.070643ms","start":"2026-08-22T15:22:49.083623Z","end":"2026-08-22T15:22:49.363694Z","steps":["trace[875616334] 'process raft request' (duration: 279.870979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:49.363832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.623011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-22T15:22:49.36405Z","caller":"traceutil/trace.go:171","msg":"trace[1931909320] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25164; }","duration":"105.889898ms","start":"2026-08-22T15:22:49.258142Z","end":"2026-08-22T15:22:49.364032Z","steps":["trace[1931909320] 'agreement among raft nodes before linearized reading' (duration: 105.496598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:49.651539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.476283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:49.651605Z","caller":"traceutil/trace.go:171","msg":"trace[1339825395] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25164; }","duration":"233.563504ms","start":"2026-08-22T15:22:49.418027Z","end":"2026-08-22T15:22:49.65159Z","steps":["trace[1339825395] 'range keys from in-memory index tree' (duration: 233.441751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:49.652348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.040528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:22:49.652436Z","caller":"traceutil/trace.go:171","msg":"trace[820671738] linearizableReadLoop","detail":"{readStateIndex:25969; appliedIndex:25968; }","duration":"153.43149ms","start":"2026-08-22T15:22:49.49899Z","end":"2026-08-22T15:22:49.652421Z","steps":["trace[820671738] 'read index received' (duration: 15.157427ms)","trace[820671738] 'applied index is now lower than readState.Index' (duration: 138.273103ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:49.65247Z","caller":"traceutil/trace.go:171","msg":"trace[534339294] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"154.964029ms","start":"2026-08-22T15:22:49.497472Z","end":"2026-08-22T15:22:49.652436Z","steps":["trace[534339294] 'process raft request' (duration: 16.756707ms)","trace[534339294] 'compare' (duration: 137.366591ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:22:49.652624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.606986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-22T15:22:49.652624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.619807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:22:49.652681Z","caller":"traceutil/trace.go:171","msg":"trace[1243678602] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25165; }","duration":"153.688818ms","start":"2026-08-22T15:22:49.498976Z","end":"2026-08-22T15:22:49.652664Z","steps":["trace[1243678602] 'agreement among raft nodes before linearized reading' (duration: 153.552635ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:49.652682Z","caller":"traceutil/trace.go:171","msg":"trace[1149272577] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25165; }","duration":"153.704988ms","start":"2026-08-22T15:22:49.498965Z","end":"2026-08-22T15:22:49.65267Z","steps":["trace[1149272577] 'agreement among raft nodes before linearized reading' (duration: 153.525783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:49.652704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.668488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:22:49.652738Z","caller":"traceutil/trace.go:171","msg":"trace[766078128] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25165; }","duration":"153.717399ms","start":"2026-08-22T15:22:49.49901Z","end":"2026-08-22T15:22:49.652727Z","steps":["trace[766078128] 'agreement among raft nodes before linearized reading' (duration: 153.577365ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:49.983787Z","caller":"traceutil/trace.go:171","msg":"trace[1749779076] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25969; }","duration":"323.440426ms","start":"2026-08-22T15:22:49.660329Z","end":"2026-08-22T15:22:49.983769Z","steps":["trace[1749779076] 'read index received' (duration: 44.013178ms)","trace[1749779076] 'applied index is now lower than readState.Index' (duration: 279.426648ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:49.983979Z","caller":"traceutil/trace.go:171","msg":"trace[1472309559] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"324.444139ms","start":"2026-08-22T15:22:49.659523Z","end":"2026-08-22T15:22:49.983967Z","steps":["trace[1472309559] 'process raft request' (duration: 251.528291ms)","trace[1472309559] 'compare' (duration: 72.55208ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:49.984032Z","caller":"traceutil/trace.go:171","msg":"trace[77564931] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"318.392532ms","start":"2026-08-22T15:22:49.665621Z","end":"2026-08-22T15:22:49.984013Z","steps":["trace[77564931] 'process raft request' (duration: 318.108606ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:49.984048Z","caller":"traceutil/trace.go:171","msg":"trace[1388707317] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"324.399898ms","start":"2026-08-22T15:22:49.65961Z","end":"2026-08-22T15:22:49.98401Z","steps":["trace[1388707317] 'process raft request' (duration: 324.088131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:49.98413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:49.6656Z","time spent":"318.488625ms","remote":"127.0.0.1:41676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8450,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:22:49.984155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.826095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18144"} {"level":"info","ts":"2026-08-22T15:22:49.984181Z","caller":"traceutil/trace.go:171","msg":"trace[1675820749] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25169; }","duration":"323.855105ms","start":"2026-08-22T15:22:49.660315Z","end":"2026-08-22T15:22:49.98417Z","steps":["trace[1675820749] 'agreement among raft nodes before linearized reading' (duration: 323.765463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:49.984161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:49.659602Z","time spent":"324.517361ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:22:49.984059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:49.659506Z","time spent":"324.499401ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:22:49.984203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:22:49.660309Z","time spent":"323.890087ms","remote":"127.0.0.1:41666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18167,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-22T15:22:49.997009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.92283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:49.997069Z","caller":"traceutil/trace.go:171","msg":"trace[1802433952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25170; }","duration":"130.022773ms","start":"2026-08-22T15:22:49.867032Z","end":"2026-08-22T15:22:49.997055Z","steps":["trace[1802433952] 'agreement among raft nodes before linearized reading' (duration: 129.940691ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.278206Z","caller":"traceutil/trace.go:171","msg":"trace[363296193] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"286.999521ms","start":"2026-08-22T15:22:49.991183Z","end":"2026-08-22T15:22:50.278183Z","steps":["trace[363296193] 'process raft request' (duration: 192.698364ms)","trace[363296193] 'compare' (duration: 94.095422ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:50.278927Z","caller":"traceutil/trace.go:171","msg":"trace[2138230459] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"287.543125ms","start":"2026-08-22T15:22:49.99137Z","end":"2026-08-22T15:22:50.278913Z","steps":["trace[2138230459] 'process raft request' (duration: 286.767686ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.278829Z","caller":"traceutil/trace.go:171","msg":"trace[1188219076] linearizableReadLoop","detail":"{readStateIndex:25978; appliedIndex:25974; }","duration":"282.068332ms","start":"2026-08-22T15:22:49.996733Z","end":"2026-08-22T15:22:50.278801Z","steps":["trace[1188219076] 'read index received' (duration: 187.127869ms)","trace[1188219076] 'applied index is now lower than readState.Index' (duration: 94.939293ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:22:50.279065Z","caller":"traceutil/trace.go:171","msg":"trace[1410769316] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"287.632027ms","start":"2026-08-22T15:22:49.991416Z","end":"2026-08-22T15:22:50.279048Z","steps":["trace[1410769316] 'process raft request' (duration: 287.129205ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.279126Z","caller":"traceutil/trace.go:171","msg":"trace[1438407184] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"285.160116ms","start":"2026-08-22T15:22:49.993937Z","end":"2026-08-22T15:22:50.279097Z","steps":["trace[1438407184] 'process raft request' (duration: 284.735826ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.279316Z","caller":"traceutil/trace.go:171","msg":"trace[2078606722] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"276.24498ms","start":"2026-08-22T15:22:50.003046Z","end":"2026-08-22T15:22:50.27929Z","steps":["trace[2078606722] 'process raft request' (duration: 275.715677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:50.279448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.600692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-08-22T15:22:50.279537Z","caller":"traceutil/trace.go:171","msg":"trace[147342918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25174; }","duration":"284.714025ms","start":"2026-08-22T15:22:49.994804Z","end":"2026-08-22T15:22:50.279518Z","steps":["trace[147342918] 'agreement among raft nodes before linearized reading' (duration: 284.239874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:50.280252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.686247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:22:50.280328Z","caller":"traceutil/trace.go:171","msg":"trace[1674000472] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"259.753391ms","start":"2026-08-22T15:22:50.02055Z","end":"2026-08-22T15:22:50.280304Z","steps":["trace[1674000472] 'process raft request' (duration: 258.667364ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.280368Z","caller":"traceutil/trace.go:171","msg":"trace[121582721] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"224.27742ms","start":"2026-08-22T15:22:50.05606Z","end":"2026-08-22T15:22:50.280338Z","steps":["trace[121582721] 'process raft request' (duration: 224.003493ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.280559Z","caller":"traceutil/trace.go:171","msg":"trace[909487915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25177; }","duration":"282.795059ms","start":"2026-08-22T15:22:49.997524Z","end":"2026-08-22T15:22:50.280319Z","steps":["trace[909487915] 'agreement among raft nodes before linearized reading' (duration: 282.666816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:22:50.280636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.441849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:22:50.280798Z","caller":"traceutil/trace.go:171","msg":"trace[1473287223] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25177; }","duration":"272.615053ms","start":"2026-08-22T15:22:50.008165Z","end":"2026-08-22T15:22:50.28078Z","steps":["trace[1473287223] 'agreement among raft nodes before linearized reading' (duration: 272.324026ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.420179Z","caller":"traceutil/trace.go:171","msg":"trace[133729965] transaction","detail":"{read_only:false; response_revision:25178; number_of_response:1; }","duration":"133.078148ms","start":"2026-08-22T15:22:50.28708Z","end":"2026-08-22T15:22:50.420158Z","steps":["trace[133729965] 'process raft request' (duration: 132.934334ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:22:50.424103Z","caller":"traceutil/trace.go:171","msg":"trace[788404641] transaction","detail":"{read_only:false; response_revision:25179; number_of_response:1; }","duration":"133.679603ms","start":"2026-08-22T15:22:50.290398Z","end":"2026-08-22T15:22:50.424078Z","steps":["trace[788404641] 'process raft request' (duration: 133.195801ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:01.092124Z","caller":"traceutil/trace.go:171","msg":"trace[1826166730] linearizableReadLoop","detail":"{readStateIndex:26060; appliedIndex:26059; }","duration":"348.104352ms","start":"2026-08-22T15:23:00.743997Z","end":"2026-08-22T15:23:01.092101Z","steps":["trace[1826166730] 'read index received' (duration: 347.914918ms)","trace[1826166730] 'applied index is now lower than readState.Index' (duration: 188.544µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:01.09241Z","caller":"traceutil/trace.go:171","msg":"trace[2040733890] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"352.783286ms","start":"2026-08-22T15:23:00.739603Z","end":"2026-08-22T15:23:01.092386Z","steps":["trace[2040733890] 'process raft request' (duration: 352.360156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:01.092538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:00.739579Z","time spent":"352.889669ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:01.092669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.552375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:23:01.092778Z","caller":"traceutil/trace.go:171","msg":"trace[1672531216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25254; }","duration":"318.663268ms","start":"2026-08-22T15:23:00.774092Z","end":"2026-08-22T15:23:01.092755Z","steps":["trace[1672531216] 'agreement among raft nodes before linearized reading' (duration: 318.268489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:01.09287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:00.774008Z","time spent":"318.844872ms","remote":"127.0.0.1:41762","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-08-22T15:23:01.095176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.152417ms","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-08-22T15:23:01.095264Z","caller":"traceutil/trace.go:171","msg":"trace[1487920464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25254; }","duration":"351.307511ms","start":"2026-08-22T15:23:00.743936Z","end":"2026-08-22T15:23:01.095244Z","steps":["trace[1487920464] 'agreement among raft nodes before linearized reading' (duration: 348.327968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:01.095341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:00.743923Z","time spent":"351.409183ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T15:23:03.458066Z","caller":"traceutil/trace.go:171","msg":"trace[1946016624] transaction","detail":"{read_only:false; response_revision:25331; number_of_response:1; }","duration":"105.482309ms","start":"2026-08-22T15:23:03.352558Z","end":"2026-08-22T15:23:03.45804Z","steps":["trace[1946016624] 'process raft request' (duration: 105.302655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:03.635004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.535656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:03.635116Z","caller":"traceutil/trace.go:171","msg":"trace[2068390497] linearizableReadLoop","detail":"{readStateIndex:26139; appliedIndex:26138; }","duration":"130.746901ms","start":"2026-08-22T15:23:03.504349Z","end":"2026-08-22T15:23:03.635096Z","steps":["trace[2068390497] 'read index received' (duration: 25.952159ms)","trace[2068390497] 'applied index is now lower than readState.Index' (duration: 104.793162ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:03.635188Z","caller":"traceutil/trace.go:171","msg":"trace[897495009] transaction","detail":"{read_only:false; response_revision:25332; number_of_response:1; }","duration":"220.594251ms","start":"2026-08-22T15:23:03.414567Z","end":"2026-08-22T15:23:03.635161Z","steps":["trace[897495009] 'process raft request' (duration: 115.807279ms)","trace[897495009] 'compare' (duration: 104.405803ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:03.635262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.910585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-066a3811-520b-474e-a83a-d77e9cda1390\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-22T15:23:03.635299Z","caller":"traceutil/trace.go:171","msg":"trace[377897190] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-066a3811-520b-474e-a83a-d77e9cda1390; range_end:; response_count:1; response_revision:25332; }","duration":"130.989757ms","start":"2026-08-22T15:23:03.504299Z","end":"2026-08-22T15:23:03.635289Z","steps":["trace[377897190] 'agreement among raft nodes before linearized reading' (duration: 130.850944ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:03.909816Z","caller":"traceutil/trace.go:171","msg":"trace[297274168] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"209.84784ms","start":"2026-08-22T15:23:03.699902Z","end":"2026-08-22T15:23:03.909749Z","steps":["trace[297274168] 'process raft request' (duration: 120.898382ms)","trace[297274168] 'compare' (duration: 88.819735ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:04.186712Z","caller":"traceutil/trace.go:171","msg":"trace[913225803] transaction","detail":"{read_only:false; response_revision:25336; number_of_response:1; }","duration":"189.64556ms","start":"2026-08-22T15:23:03.997035Z","end":"2026-08-22T15:23:04.18668Z","steps":["trace[913225803] 'process raft request' (duration: 95.277941ms)","trace[913225803] 'compare' (duration: 94.031741ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:04.446152Z","caller":"traceutil/trace.go:171","msg":"trace[244945687] linearizableReadLoop","detail":"{readStateIndex:26144; appliedIndex:26142; }","duration":"406.979741ms","start":"2026-08-22T15:23:04.039148Z","end":"2026-08-22T15:23:04.446128Z","steps":["trace[244945687] 'read index received' (duration: 53.16973ms)","trace[244945687] 'applied index is now lower than readState.Index' (duration: 353.809361ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:04.446193Z","caller":"traceutil/trace.go:171","msg":"trace[1927632626] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"411.198072ms","start":"2026-08-22T15:23:04.034977Z","end":"2026-08-22T15:23:04.446175Z","steps":["trace[1927632626] 'process raft request' (duration: 314.028596ms)","trace[1927632626] 'compare' (duration: 97.040193ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:04.446304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:04.034949Z","time spent":"411.285875ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:04.446382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.390158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:04.446459Z","caller":"traceutil/trace.go:171","msg":"trace[1193326955] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25337; }","duration":"364.495851ms","start":"2026-08-22T15:23:04.081946Z","end":"2026-08-22T15:23:04.446442Z","steps":["trace[1193326955] 'agreement among raft nodes before linearized reading' (duration: 364.373238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:04.446404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.525997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-22T15:23:04.446511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:04.081937Z","time spent":"364.554282ms","remote":"127.0.0.1:35622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-08-22T15:23:04.446515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.854313ms","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-08-22T15:23:04.446616Z","caller":"traceutil/trace.go:171","msg":"trace[1765790603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25337; }","duration":"400.997027ms","start":"2026-08-22T15:23:04.045596Z","end":"2026-08-22T15:23:04.446593Z","steps":["trace[1765790603] 'agreement among raft nodes before linearized reading' (duration: 400.688599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:04.446665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:04.045575Z","time spent":"401.0812ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T15:23:04.446549Z","caller":"traceutil/trace.go:171","msg":"trace[161356055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25337; }","duration":"107.751843ms","start":"2026-08-22T15:23:04.338787Z","end":"2026-08-22T15:23:04.446539Z","steps":["trace[161356055] 'agreement among raft nodes before linearized reading' (duration: 107.525267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:04.446798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.661868ms","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-08-22T15:23:04.446821Z","caller":"traceutil/trace.go:171","msg":"trace[1922205242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25337; }","duration":"407.723779ms","start":"2026-08-22T15:23:04.039092Z","end":"2026-08-22T15:23:04.446816Z","steps":["trace[1922205242] 'agreement among raft nodes before linearized reading' (duration: 407.140825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:04.446834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:04.039076Z","time spent":"407.75489ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T15:23:04.674387Z","caller":"traceutil/trace.go:171","msg":"trace[669216810] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"224.358222ms","start":"2026-08-22T15:23:04.450002Z","end":"2026-08-22T15:23:04.674361Z","steps":["trace[669216810] 'process raft request' (duration: 146.867032ms)","trace[669216810] 'compare' (duration: 77.328106ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:04.674764Z","caller":"traceutil/trace.go:171","msg":"trace[1755708258] linearizableReadLoop","detail":"{readStateIndex:26147; appliedIndex:26144; }","duration":"192.327765ms","start":"2026-08-22T15:23:04.482409Z","end":"2026-08-22T15:23:04.674737Z","steps":["trace[1755708258] 'read index received' (duration: 114.559628ms)","trace[1755708258] 'applied index is now lower than readState.Index' (duration: 77.766597ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:04.674876Z","caller":"traceutil/trace.go:171","msg":"trace[1627736894] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"224.28777ms","start":"2026-08-22T15:23:04.450576Z","end":"2026-08-22T15:23:04.674864Z","steps":["trace[1627736894] 'process raft request' (duration: 223.972682ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:04.675035Z","caller":"traceutil/trace.go:171","msg":"trace[1285120234] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"224.958205ms","start":"2026-08-22T15:23:04.450051Z","end":"2026-08-22T15:23:04.675009Z","steps":["trace[1285120234] 'process raft request' (duration: 224.268309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:04.67513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.711304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T15:23:04.675202Z","caller":"traceutil/trace.go:171","msg":"trace[798743460] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25341; }","duration":"192.791436ms","start":"2026-08-22T15:23:04.482395Z","end":"2026-08-22T15:23:04.675186Z","steps":["trace[798743460] 'agreement among raft nodes before linearized reading' (duration: 192.57244ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:04.675297Z","caller":"traceutil/trace.go:171","msg":"trace[1229128119] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"172.225376ms","start":"2026-08-22T15:23:04.50303Z","end":"2026-08-22T15:23:04.675256Z","steps":["trace[1229128119] 'process raft request' (duration: 171.620092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.049861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.045418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"warn","ts":"2026-08-22T15:23:05.049892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.491075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:05.049925Z","caller":"traceutil/trace.go:171","msg":"trace[1734018893] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:25342; }","duration":"119.169031ms","start":"2026-08-22T15:23:04.93074Z","end":"2026-08-22T15:23:05.049909Z","steps":["trace[1734018893] 'range keys from in-memory index tree' (duration: 118.870624ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:05.049966Z","caller":"traceutil/trace.go:171","msg":"trace[938149605] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25342; }","duration":"224.607658ms","start":"2026-08-22T15:23:04.825337Z","end":"2026-08-22T15:23:05.049944Z","steps":["trace[938149605] 'count revisions from in-memory index tree' (duration: 224.403113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.050012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.003478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:05.050096Z","caller":"traceutil/trace.go:171","msg":"trace[353654442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25342; }","duration":"183.148852ms","start":"2026-08-22T15:23:04.866928Z","end":"2026-08-22T15:23:05.050077Z","steps":["trace[353654442] 'range keys from in-memory index tree' (duration: 182.906856ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:05.524398Z","caller":"traceutil/trace.go:171","msg":"trace[328816183] linearizableReadLoop","detail":"{readStateIndex:26150; appliedIndex:26149; }","duration":"407.77875ms","start":"2026-08-22T15:23:05.116594Z","end":"2026-08-22T15:23:05.524373Z","steps":["trace[328816183] 'read index received' (duration: 407.683078ms)","trace[328816183] 'applied index is now lower than readState.Index' (duration: 94.762µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:05.524505Z","caller":"traceutil/trace.go:171","msg":"trace[901190363] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"421.912323ms","start":"2026-08-22T15:23:05.102567Z","end":"2026-08-22T15:23:05.524479Z","steps":["trace[901190363] 'process raft request' (duration: 421.639846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.524666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.102547Z","time spent":"422.035675ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:05.524713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.092078ms","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-08-22T15:23:05.524781Z","caller":"traceutil/trace.go:171","msg":"trace[1368837110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25343; }","duration":"408.19714ms","start":"2026-08-22T15:23:05.116568Z","end":"2026-08-22T15:23:05.524766Z","steps":["trace[1368837110] 'agreement among raft nodes before linearized reading' (duration: 407.936803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.524819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.116558Z","time spent":"408.253871ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T15:23:05.803012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.726551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:05.803234Z","caller":"traceutil/trace.go:171","msg":"trace[260599408] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"562.7779ms","start":"2026-08-22T15:23:05.240435Z","end":"2026-08-22T15:23:05.803213Z","steps":["trace[260599408] 'process raft request' (duration: 431.745741ms)","trace[260599408] 'compare' (duration: 130.596048ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:05.803316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.240405Z","time spent":"562.877982ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:23:05.804791Z","caller":"traceutil/trace.go:171","msg":"trace[2114181285] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"507.54853ms","start":"2026-08-22T15:23:05.297214Z","end":"2026-08-22T15:23:05.804763Z","steps":["trace[2114181285] 'process raft request' (duration: 505.9027ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:05.804836Z","caller":"traceutil/trace.go:171","msg":"trace[794160074] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"338.872539ms","start":"2026-08-22T15:23:05.465942Z","end":"2026-08-22T15:23:05.804814Z","steps":["trace[794160074] 'process raft request' (duration: 338.716675ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:05.804892Z","caller":"traceutil/trace.go:171","msg":"trace[429629631] linearizableReadLoop","detail":"{readStateIndex:26153; appliedIndex:26150; }","duration":"280.393221ms","start":"2026-08-22T15:23:05.524463Z","end":"2026-08-22T15:23:05.804857Z","steps":["trace[429629631] 'read index received' (duration: 147.894518ms)","trace[429629631] 'applied index is now lower than readState.Index' (duration: 132.494873ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:05.804941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.297189Z","time spent":"507.675923ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:05.805004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.465905Z","time spent":"339.002542ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:05.805051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.835868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-22T15:23:05.805014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.527728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-08-22T15:23:05.805123Z","caller":"traceutil/trace.go:171","msg":"trace[777421908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25347; }","duration":"686.921571ms","start":"2026-08-22T15:23:05.118179Z","end":"2026-08-22T15:23:05.8051Z","steps":["trace[777421908] 'agreement among raft nodes before linearized reading' (duration: 686.770777ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:05.805139Z","caller":"traceutil/trace.go:171","msg":"trace[2001923743] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"273.454442ms","start":"2026-08-22T15:23:05.531662Z","end":"2026-08-22T15:23:05.805116Z","steps":["trace[2001923743] 'process raft request' (duration: 273.078563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.805175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.118168Z","time spent":"686.995792ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T15:23:05.805131Z","caller":"traceutil/trace.go:171","msg":"trace[597199058] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:25347; }","duration":"502.644241ms","start":"2026-08-22T15:23:05.302467Z","end":"2026-08-22T15:23:05.805112Z","steps":["trace[597199058] 'agreement among raft nodes before linearized reading' (duration: 502.405255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.805271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.302427Z","time spent":"502.792175ms","remote":"127.0.0.1:41640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1589,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" "} {"level":"warn","ts":"2026-08-22T15:23:05.805317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.531066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-08-22T15:23:05.805371Z","caller":"traceutil/trace.go:171","msg":"trace[687319269] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25347; }","duration":"332.613368ms","start":"2026-08-22T15:23:05.472742Z","end":"2026-08-22T15:23:05.805355Z","steps":["trace[687319269] 'agreement among raft nodes before linearized reading' (duration: 332.134486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.805414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:05.47273Z","time spent":"332.677439ms","remote":"127.0.0.1:41676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-22T15:23:05.805787Z","caller":"traceutil/trace.go:171","msg":"trace[1390561421] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"166.015728ms","start":"2026-08-22T15:23:05.639752Z","end":"2026-08-22T15:23:05.805768Z","steps":["trace[1390561421] 'process raft request' (duration: 165.018603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.805862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.645815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-22T15:23:05.805807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.60402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-22T15:23:05.805866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.33794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:23:05.805923Z","caller":"traceutil/trace.go:171","msg":"trace[1702746032] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25348; }","duration":"110.717696ms","start":"2026-08-22T15:23:05.695191Z","end":"2026-08-22T15:23:05.805909Z","steps":["trace[1702746032] 'agreement among raft nodes before linearized reading' (duration: 110.569573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:05.805755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.071934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-066a3811-520b-474e-a83a-d77e9cda1390\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-22T15:23:05.805951Z","caller":"traceutil/trace.go:171","msg":"trace[93113164] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25348; }","duration":"113.459493ms","start":"2026-08-22T15:23:05.692477Z","end":"2026-08-22T15:23:05.805936Z","steps":["trace[93113164] 'agreement among raft nodes before linearized reading' (duration: 113.246938ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:05.80595Z","caller":"traceutil/trace.go:171","msg":"trace[1721341615] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25348; }","duration":"114.757804ms","start":"2026-08-22T15:23:05.691167Z","end":"2026-08-22T15:23:05.805925Z","steps":["trace[1721341615] 'agreement among raft nodes before linearized reading' (duration: 114.59685ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:05.806077Z","caller":"traceutil/trace.go:171","msg":"trace[864584723] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-066a3811-520b-474e-a83a-d77e9cda1390; range_end:; response_count:1; response_revision:25348; }","duration":"125.352331ms","start":"2026-08-22T15:23:05.680629Z","end":"2026-08-22T15:23:05.805981Z","steps":["trace[864584723] 'agreement among raft nodes before linearized reading' (duration: 125.067274ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:06.070866Z","caller":"traceutil/trace.go:171","msg":"trace[1893449848] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"260.297424ms","start":"2026-08-22T15:23:05.810528Z","end":"2026-08-22T15:23:06.070825Z","steps":["trace[1893449848] 'process raft request' (duration: 226.862453ms)","trace[1893449848] 'compare' (duration: 33.233866ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:06.070952Z","caller":"traceutil/trace.go:171","msg":"trace[588128298] linearizableReadLoop","detail":"{readStateIndex:26156; appliedIndex:26155; }","duration":"260.061998ms","start":"2026-08-22T15:23:05.810685Z","end":"2026-08-22T15:23:06.070747Z","steps":["trace[588128298] 'read index received' (duration: 226.681928ms)","trace[588128298] 'applied index is now lower than readState.Index' (duration: 33.37889ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:06.071204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.490358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18144"} {"level":"info","ts":"2026-08-22T15:23:06.071277Z","caller":"traceutil/trace.go:171","msg":"trace[324900585] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25349; }","duration":"260.58079ms","start":"2026-08-22T15:23:05.81068Z","end":"2026-08-22T15:23:06.07126Z","steps":["trace[324900585] 'agreement among raft nodes before linearized reading' (duration: 260.293384ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:06.08246Z","caller":"traceutil/trace.go:171","msg":"trace[1842168328] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"167.184264ms","start":"2026-08-22T15:23:05.915238Z","end":"2026-08-22T15:23:06.082422Z","steps":["trace[1842168328] 'process raft request' (duration: 166.851316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:06.082486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.889831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-22T15:23:06.082708Z","caller":"traceutil/trace.go:171","msg":"trace[819451881] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25350; }","duration":"116.036524ms","start":"2026-08-22T15:23:05.966532Z","end":"2026-08-22T15:23:06.082569Z","steps":["trace[819451881] 'agreement among raft nodes before linearized reading' (duration: 115.717087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:06.436259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.435146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:06.43634Z","caller":"traceutil/trace.go:171","msg":"trace[1768019853] linearizableReadLoop","detail":"{readStateIndex:26158; appliedIndex:26157; }","duration":"306.469043ms","start":"2026-08-22T15:23:06.129858Z","end":"2026-08-22T15:23:06.436327Z","steps":["trace[1768019853] 'read index received' (duration: 130.836613ms)","trace[1768019853] 'applied index is now lower than readState.Index' (duration: 175.63102ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:06.436516Z","caller":"traceutil/trace.go:171","msg":"trace[1374934528] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"350.172434ms","start":"2026-08-22T15:23:06.086334Z","end":"2026-08-22T15:23:06.436506Z","steps":["trace[1374934528] 'process raft request' (duration: 174.415911ms)","trace[1374934528] 'compare' (duration: 175.322412ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:06.436565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:06.086311Z","time spent":"350.231225ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:06.436651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.251109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:06.436702Z","caller":"traceutil/trace.go:171","msg":"trace[722597685] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25351; }","duration":"224.31284ms","start":"2026-08-22T15:23:06.212377Z","end":"2026-08-22T15:23:06.436689Z","steps":["trace[722597685] 'agreement among raft nodes before linearized reading' (duration: 224.224418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:06.436957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.079608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:06.437059Z","caller":"traceutil/trace.go:171","msg":"trace[416442417] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25351; }","duration":"307.245272ms","start":"2026-08-22T15:23:06.129794Z","end":"2026-08-22T15:23:06.437039Z","steps":["trace[416442417] 'agreement among raft nodes before linearized reading' (duration: 307.019117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:06.437278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:06.129776Z","time spent":"307.488368ms","remote":"127.0.0.1:41968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-22T15:23:06.609425Z","caller":"traceutil/trace.go:171","msg":"trace[444294351] linearizableReadLoop","detail":"{readStateIndex:26159; appliedIndex:26158; }","duration":"154.714393ms","start":"2026-08-22T15:23:06.45468Z","end":"2026-08-22T15:23:06.609395Z","steps":["trace[444294351] 'read index received' (duration: 151.108565ms)","trace[444294351] 'applied index is now lower than readState.Index' (duration: 3.604338ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:06.609469Z","caller":"traceutil/trace.go:171","msg":"trace[620410188] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"158.027273ms","start":"2026-08-22T15:23:06.451411Z","end":"2026-08-22T15:23:06.609438Z","steps":["trace[620410188] 'process raft request' (duration: 154.370124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:06.609734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.030411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-08-22T15:23:06.609821Z","caller":"traceutil/trace.go:171","msg":"trace[158208956] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25352; }","duration":"155.128483ms","start":"2026-08-22T15:23:06.454671Z","end":"2026-08-22T15:23:06.609799Z","steps":["trace[158208956] 'agreement among raft nodes before linearized reading' (duration: 154.859066ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:06.863668Z","caller":"traceutil/trace.go:171","msg":"trace[397413838] linearizableReadLoop","detail":"{readStateIndex:26162; appliedIndex:26161; }","duration":"180.865936ms","start":"2026-08-22T15:23:06.682782Z","end":"2026-08-22T15:23:06.863647Z","steps":["trace[397413838] 'read index received' (duration: 180.773794ms)","trace[397413838] 'applied index is now lower than readState.Index' (duration: 91.262µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:06.863714Z","caller":"traceutil/trace.go:171","msg":"trace[184977587] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"182.714501ms","start":"2026-08-22T15:23:06.680972Z","end":"2026-08-22T15:23:06.863687Z","steps":["trace[184977587] 'process raft request' (duration: 182.510276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:06.864601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.799369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-22T15:23:06.864809Z","caller":"traceutil/trace.go:171","msg":"trace[1533575463] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25354; }","duration":"182.041376ms","start":"2026-08-22T15:23:06.682754Z","end":"2026-08-22T15:23:06.864795Z","steps":["trace[1533575463] 'agreement among raft nodes before linearized reading' (duration: 181.00587ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:06.87712Z","caller":"traceutil/trace.go:171","msg":"trace[659944020] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"191.426893ms","start":"2026-08-22T15:23:06.68568Z","end":"2026-08-22T15:23:06.877107Z","steps":["trace[659944020] 'process raft request' (duration: 191.369422ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:06.877127Z","caller":"traceutil/trace.go:171","msg":"trace[332633684] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"191.465084ms","start":"2026-08-22T15:23:06.685629Z","end":"2026-08-22T15:23:06.877094Z","steps":["trace[332633684] 'process raft request' (duration: 191.370661ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:06.877292Z","caller":"traceutil/trace.go:171","msg":"trace[663445236] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"191.551976ms","start":"2026-08-22T15:23:06.68573Z","end":"2026-08-22T15:23:06.877282Z","steps":["trace[663445236] 'process raft request' (duration: 191.347321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:06.877561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.745304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T15:23:06.877634Z","caller":"traceutil/trace.go:171","msg":"trace[464177588] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25357; }","duration":"173.854227ms","start":"2026-08-22T15:23:06.703763Z","end":"2026-08-22T15:23:06.877617Z","steps":["trace[464177588] 'agreement among raft nodes before linearized reading' (duration: 173.584751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:07.253666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.31424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:23:07.253822Z","caller":"traceutil/trace.go:171","msg":"trace[575086535] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25358; }","duration":"172.577166ms","start":"2026-08-22T15:23:07.081223Z","end":"2026-08-22T15:23:07.2538Z","steps":["trace[575086535] 'count revisions from in-memory index tree' (duration: 172.231967ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:12.746833Z","caller":"traceutil/trace.go:171","msg":"trace[843950334] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"121.849934ms","start":"2026-08-22T15:23:12.624961Z","end":"2026-08-22T15:23:12.746811Z","steps":["trace[843950334] 'process raft request' (duration: 121.711681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:12.965836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.01133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:12.965901Z","caller":"traceutil/trace.go:171","msg":"trace[1265165258] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25418; }","duration":"169.097962ms","start":"2026-08-22T15:23:12.796788Z","end":"2026-08-22T15:23:12.965886Z","steps":["trace[1265165258] 'count revisions from in-memory index tree' (duration: 168.950698ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:13.075631Z","caller":"traceutil/trace.go:171","msg":"trace[1592999112] linearizableReadLoop","detail":"{readStateIndex:26228; appliedIndex:26227; }","duration":"180.816616ms","start":"2026-08-22T15:23:12.894791Z","end":"2026-08-22T15:23:13.075607Z","steps":["trace[1592999112] 'read index received' (duration: 180.59397ms)","trace[1592999112] 'applied index is now lower than readState.Index' (duration: 221.836µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:13.075957Z","caller":"traceutil/trace.go:171","msg":"trace[764589433] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"194.091667ms","start":"2026-08-22T15:23:12.881849Z","end":"2026-08-22T15:23:13.075941Z","steps":["trace[764589433] 'process raft request' (duration: 193.612555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:13.076259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.457582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T15:23:13.076299Z","caller":"traceutil/trace.go:171","msg":"trace[55929163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25419; }","duration":"181.543713ms","start":"2026-08-22T15:23:12.894747Z","end":"2026-08-22T15:23:13.07629Z","steps":["trace[55929163] 'agreement among raft nodes before linearized reading' (duration: 181.39829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:13.076522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.127649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T15:23:13.076556Z","caller":"traceutil/trace.go:171","msg":"trace[570865191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25419; }","duration":"178.202201ms","start":"2026-08-22T15:23:12.898345Z","end":"2026-08-22T15:23:13.076547Z","steps":["trace[570865191] 'agreement among raft nodes before linearized reading' (duration: 178.091299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:13.077202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.441388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:13.077242Z","caller":"traceutil/trace.go:171","msg":"trace[852362953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25419; }","duration":"110.50899ms","start":"2026-08-22T15:23:12.966724Z","end":"2026-08-22T15:23:13.077233Z","steps":["trace[852362953] 'agreement among raft nodes before linearized reading' (duration: 110.455328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:13.077415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.881603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T15:23:13.077452Z","caller":"traceutil/trace.go:171","msg":"trace[461351134] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25419; }","duration":"144.937075ms","start":"2026-08-22T15:23:12.932504Z","end":"2026-08-22T15:23:13.077441Z","steps":["trace[461351134] 'agreement among raft nodes before linearized reading' (duration: 144.854653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:13.077646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.917886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T15:23:13.077682Z","caller":"traceutil/trace.go:171","msg":"trace[1516219922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25419; }","duration":"170.976648ms","start":"2026-08-22T15:23:12.906697Z","end":"2026-08-22T15:23:13.077674Z","steps":["trace[1516219922] 'agreement among raft nodes before linearized reading' (duration: 170.893575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:13.308664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.788758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:13.308873Z","caller":"traceutil/trace.go:171","msg":"trace[2070591008] transaction","detail":"{read_only:false; response_revision:25420; number_of_response:1; }","duration":"223.765618ms","start":"2026-08-22T15:23:13.084998Z","end":"2026-08-22T15:23:13.308763Z","steps":["trace[2070591008] 'process raft request' (duration: 112.773295ms)","trace[2070591008] 'compare' (duration: 110.614584ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:13.331365Z","caller":"traceutil/trace.go:171","msg":"trace[218948151] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"244.634713ms","start":"2026-08-22T15:23:13.086708Z","end":"2026-08-22T15:23:13.331343Z","steps":["trace[218948151] 'process raft request' (duration: 244.436229ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:13.331401Z","caller":"traceutil/trace.go:171","msg":"trace[1306138121] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"244.170522ms","start":"2026-08-22T15:23:13.087204Z","end":"2026-08-22T15:23:13.331374Z","steps":["trace[1306138121] 'process raft request' (duration: 244.06328ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:13.331632Z","caller":"traceutil/trace.go:171","msg":"trace[1472691142] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"240.036783ms","start":"2026-08-22T15:23:13.091577Z","end":"2026-08-22T15:23:13.331614Z","steps":["trace[1472691142] 'process raft request' (duration: 239.743916ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:13.331642Z","caller":"traceutil/trace.go:171","msg":"trace[1840867999] linearizableReadLoop","detail":"{readStateIndex:26233; appliedIndex:26232; }","duration":"103.130382ms","start":"2026-08-22T15:23:13.228491Z","end":"2026-08-22T15:23:13.331622Z","steps":["trace[1840867999] 'read index received' (duration: 103.085591ms)","trace[1840867999] 'applied index is now lower than readState.Index' (duration: 43.471µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:13.331831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.325616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-08-22T15:23:13.3319Z","caller":"traceutil/trace.go:171","msg":"trace[619016947] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25424; }","duration":"103.454629ms","start":"2026-08-22T15:23:13.228427Z","end":"2026-08-22T15:23:13.331882Z","steps":["trace[619016947] 'agreement among raft nodes before linearized reading' (duration: 103.252504ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:13.331775Z","caller":"traceutil/trace.go:171","msg":"trace[2094382250] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"238.488504ms","start":"2026-08-22T15:23:13.093222Z","end":"2026-08-22T15:23:13.33171Z","steps":["trace[2094382250] 'process raft request' (duration: 238.30466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:45.099329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.60872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:45.099435Z","caller":"traceutil/trace.go:171","msg":"trace[1252964526] linearizableReadLoop","detail":"{readStateIndex:26494; appliedIndex:26493; }","duration":"233.405741ms","start":"2026-08-22T15:23:44.866014Z","end":"2026-08-22T15:23:45.09942Z","steps":["trace[1252964526] 'read index received' (duration: 20.47µs)","trace[1252964526] 'applied index is now lower than readState.Index' (duration: 233.384361ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:45.099497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.480162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:45.099529Z","caller":"traceutil/trace.go:171","msg":"trace[2122686952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25679; }","duration":"233.526734ms","start":"2026-08-22T15:23:44.865993Z","end":"2026-08-22T15:23:45.09952Z","steps":["trace[2122686952] 'agreement among raft nodes before linearized reading' (duration: 233.471143ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:45.099516Z","caller":"traceutil/trace.go:171","msg":"trace[1770691825] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"254.549354ms","start":"2026-08-22T15:23:44.844941Z","end":"2026-08-22T15:23:45.09949Z","steps":["trace[1770691825] 'compare' (duration: 248.467207ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:45.35307Z","caller":"traceutil/trace.go:171","msg":"trace[808691842] linearizableReadLoop","detail":"{readStateIndex:26495; appliedIndex:26494; }","duration":"161.236941ms","start":"2026-08-22T15:23:45.1918Z","end":"2026-08-22T15:23:45.353037Z","steps":["trace[808691842] 'read index received' (duration: 130.70437ms)","trace[808691842] 'applied index is now lower than readState.Index' (duration: 30.531381ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:45.353123Z","caller":"traceutil/trace.go:171","msg":"trace[1634173494] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"171.758427ms","start":"2026-08-22T15:23:45.181305Z","end":"2026-08-22T15:23:45.353064Z","steps":["trace[1634173494] 'process raft request' (duration: 141.243207ms)","trace[1634173494] 'compare' (duration: 30.217083ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:45.353226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.420925ms","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-08-22T15:23:45.353288Z","caller":"traceutil/trace.go:171","msg":"trace[1122129778] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25680; }","duration":"161.515748ms","start":"2026-08-22T15:23:45.191757Z","end":"2026-08-22T15:23:45.353273Z","steps":["trace[1122129778] 'agreement among raft nodes before linearized reading' (duration: 161.352174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:45.621422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.482667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T15:23:45.621525Z","caller":"traceutil/trace.go:171","msg":"trace[899027000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25680; }","duration":"163.61177ms","start":"2026-08-22T15:23:45.457873Z","end":"2026-08-22T15:23:45.621485Z","steps":["trace[899027000] 'range keys from in-memory index tree' (duration: 163.318163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:45.621428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.569386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T15:23:45.621568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.846931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T15:23:45.621633Z","caller":"traceutil/trace.go:171","msg":"trace[1697452884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25680; }","duration":"167.945284ms","start":"2026-08-22T15:23:45.45367Z","end":"2026-08-22T15:23:45.621616Z","steps":["trace[1697452884] 'range keys from in-memory index tree' (duration: 167.528033ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:45.621642Z","caller":"traceutil/trace.go:171","msg":"trace[1909673700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25680; }","duration":"139.800321ms","start":"2026-08-22T15:23:45.481807Z","end":"2026-08-22T15:23:45.621607Z","steps":["trace[1909673700] 'range keys from in-memory index tree' (duration: 139.36828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:45.621784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.60309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T15:23:45.621882Z","caller":"traceutil/trace.go:171","msg":"trace[1915518816] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25680; }","duration":"135.739103ms","start":"2026-08-22T15:23:45.486126Z","end":"2026-08-22T15:23:45.621865Z","steps":["trace[1915518816] 'range keys from in-memory index tree' (duration: 135.142659ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:45.812647Z","caller":"traceutil/trace.go:171","msg":"trace[666606645] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"186.924084ms","start":"2026-08-22T15:23:45.625697Z","end":"2026-08-22T15:23:45.812621Z","steps":["trace[666606645] 'process raft request' (duration: 186.795751ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:46.114958Z","caller":"traceutil/trace.go:171","msg":"trace[881909335] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"488.494069ms","start":"2026-08-22T15:23:45.626424Z","end":"2026-08-22T15:23:46.114918Z","steps":["trace[881909335] 'process raft request' (duration: 488.223813ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:46.115114Z","caller":"traceutil/trace.go:171","msg":"trace[1819712421] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"488.097659ms","start":"2026-08-22T15:23:45.62696Z","end":"2026-08-22T15:23:46.115057Z","steps":["trace[1819712421] 'process raft request' (duration: 487.876693ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:46.115132Z","caller":"traceutil/trace.go:171","msg":"trace[819761187] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"463.977634ms","start":"2026-08-22T15:23:45.651143Z","end":"2026-08-22T15:23:46.115121Z","steps":["trace[819761187] 'process raft request' (duration: 463.729298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.115221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:45.626953Z","time spent":"488.211921ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:23:46.114958Z","caller":"traceutil/trace.go:171","msg":"trace[1565761919] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"488.040397ms","start":"2026-08-22T15:23:45.626887Z","end":"2026-08-22T15:23:46.114927Z","steps":["trace[1565761919] 'process raft request' (duration: 487.912393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.115262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:45.651111Z","time spent":"464.070367ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:46.115322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:45.626877Z","time spent":"488.390796ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:46.115385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.56981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:23:46.115226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:45.626388Z","time spent":"488.658182ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:23:46.115465Z","caller":"traceutil/trace.go:171","msg":"trace[469425718] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25685; }","duration":"401.709443ms","start":"2026-08-22T15:23:45.71373Z","end":"2026-08-22T15:23:46.11544Z","steps":["trace[469425718] 'agreement among raft nodes before linearized reading' (duration: 401.57378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.115521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:45.713704Z","time spent":"401.803655ms","remote":"127.0.0.1:47764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"info","ts":"2026-08-22T15:23:46.11568Z","caller":"traceutil/trace.go:171","msg":"trace[705033729] linearizableReadLoop","detail":"{readStateIndex:26500; appliedIndex:26495; }","duration":"401.267172ms","start":"2026-08-22T15:23:45.713801Z","end":"2026-08-22T15:23:46.115069Z","steps":["trace[705033729] 'read index received' (duration: 98.747874ms)","trace[705033729] 'applied index is now lower than readState.Index' (duration: 302.518368ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:46.128933Z","caller":"traceutil/trace.go:171","msg":"trace[372029495] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"194.795596ms","start":"2026-08-22T15:23:45.934121Z","end":"2026-08-22T15:23:46.128916Z","steps":["trace[372029495] 'process raft request' (duration: 194.414416ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:46.129126Z","caller":"traceutil/trace.go:171","msg":"trace[1715819829] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"193.748869ms","start":"2026-08-22T15:23:45.935364Z","end":"2026-08-22T15:23:46.129113Z","steps":["trace[1715819829] 'process raft request' (duration: 193.281668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.129321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.601597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:23:46.129359Z","caller":"traceutil/trace.go:171","msg":"trace[618615155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25687; }","duration":"158.677399ms","start":"2026-08-22T15:23:45.970672Z","end":"2026-08-22T15:23:46.12935Z","steps":["trace[618615155] 'agreement among raft nodes before linearized reading' (duration: 158.569466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.129692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.542008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:23:46.129733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.311788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:23:46.129761Z","caller":"traceutil/trace.go:171","msg":"trace[1453052821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25687; }","duration":"262.65478ms","start":"2026-08-22T15:23:45.867089Z","end":"2026-08-22T15:23:46.129744Z","steps":["trace[1453052821] 'agreement among raft nodes before linearized reading' (duration: 262.526117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.129759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.571043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:23:46.129795Z","caller":"traceutil/trace.go:171","msg":"trace[680755472] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25687; }","duration":"240.423411ms","start":"2026-08-22T15:23:45.889356Z","end":"2026-08-22T15:23:46.12978Z","steps":["trace[680755472] 'agreement among raft nodes before linearized reading' (duration: 240.206436ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:46.129798Z","caller":"traceutil/trace.go:171","msg":"trace[918611422] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25687; }","duration":"235.610254ms","start":"2026-08-22T15:23:45.894178Z","end":"2026-08-22T15:23:46.129788Z","steps":["trace[918611422] 'agreement among raft nodes before linearized reading' (duration: 235.492472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.12983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.494582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:23:46.129874Z","caller":"traceutil/trace.go:171","msg":"trace[910199942] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25687; }","duration":"242.559093ms","start":"2026-08-22T15:23:45.887302Z","end":"2026-08-22T15:23:46.129861Z","steps":["trace[910199942] 'agreement among raft nodes before linearized reading' (duration: 242.41219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.412142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.132377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:46.41236Z","caller":"traceutil/trace.go:171","msg":"trace[1225505012] linearizableReadLoop","detail":"{readStateIndex:26504; appliedIndex:26502; }","duration":"174.018101ms","start":"2026-08-22T15:23:46.238326Z","end":"2026-08-22T15:23:46.412344Z","steps":["trace[1225505012] 'read index received' (duration: 160.844761ms)","trace[1225505012] 'applied index is now lower than readState.Index' (duration: 13.17233ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:46.41238Z","caller":"traceutil/trace.go:171","msg":"trace[652904388] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"278.622358ms","start":"2026-08-22T15:23:46.133726Z","end":"2026-08-22T15:23:46.412348Z","steps":["trace[652904388] 'process raft request' (duration: 68.184754ms)","trace[652904388] 'compare' (duration: 209.880411ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:46.412965Z","caller":"traceutil/trace.go:171","msg":"trace[1821878350] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"201.089317ms","start":"2026-08-22T15:23:46.211856Z","end":"2026-08-22T15:23:46.412945Z","steps":["trace[1821878350] 'process raft request' (duration: 200.394411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.413137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.798649ms","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-08-22T15:23:46.413217Z","caller":"traceutil/trace.go:171","msg":"trace[121176003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25689; }","duration":"174.922182ms","start":"2026-08-22T15:23:46.238274Z","end":"2026-08-22T15:23:46.413196Z","steps":["trace[121176003] 'agreement among raft nodes before linearized reading' (duration: 174.691377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.412644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.600156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:23:46.413336Z","caller":"traceutil/trace.go:171","msg":"trace[300749621] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25689; }","duration":"166.328424ms","start":"2026-08-22T15:23:46.246991Z","end":"2026-08-22T15:23:46.41332Z","steps":["trace[300749621] 'agreement among raft nodes before linearized reading' (duration: 165.568006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.680719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.220652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:46.6808Z","caller":"traceutil/trace.go:171","msg":"trace[1459091603] linearizableReadLoop","detail":"{readStateIndex:26505; appliedIndex:26504; }","duration":"152.258084ms","start":"2026-08-22T15:23:46.528529Z","end":"2026-08-22T15:23:46.680787Z","steps":["trace[1459091603] 'read index received' (duration: 25.91895ms)","trace[1459091603] 'applied index is now lower than readState.Index' (duration: 126.338074ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:46.680935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.414437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T15:23:46.680959Z","caller":"traceutil/trace.go:171","msg":"trace[1791636936] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:25690; }","duration":"152.482629ms","start":"2026-08-22T15:23:46.52847Z","end":"2026-08-22T15:23:46.680952Z","steps":["trace[1791636936] 'agreement among raft nodes before linearized reading' (duration: 152.381996ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:46.680963Z","caller":"traceutil/trace.go:171","msg":"trace[1449906084] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"263.663654ms","start":"2026-08-22T15:23:46.417273Z","end":"2026-08-22T15:23:46.680936Z","steps":["trace[1449906084] 'process raft request' (duration: 137.162316ms)","trace[1449906084] 'compare' (duration: 126.098049ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:46.989783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.772848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:46.990189Z","caller":"traceutil/trace.go:171","msg":"trace[735529029] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"297.513565ms","start":"2026-08-22T15:23:46.692654Z","end":"2026-08-22T15:23:46.990167Z","steps":["trace[735529029] 'process raft request' (duration: 134.274746ms)","trace[735529029] 'compare' (duration: 162.249336ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:46.990221Z","caller":"traceutil/trace.go:171","msg":"trace[255541738] linearizableReadLoop","detail":"{readStateIndex:26508; appliedIndex:26506; }","duration":"245.566755ms","start":"2026-08-22T15:23:46.744636Z","end":"2026-08-22T15:23:46.990202Z","steps":["trace[255541738] 'read index received' (duration: 82.304866ms)","trace[255541738] 'applied index is now lower than readState.Index' (duration: 163.260659ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:46.990246Z","caller":"traceutil/trace.go:171","msg":"trace[61799421] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"255.717733ms","start":"2026-08-22T15:23:46.734496Z","end":"2026-08-22T15:23:46.990214Z","steps":["trace[61799421] 'process raft request' (duration: 255.383815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.990358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.704909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:23:46.990402Z","caller":"traceutil/trace.go:171","msg":"trace[274433779] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25693; }","duration":"245.781821ms","start":"2026-08-22T15:23:46.744609Z","end":"2026-08-22T15:23:46.990391Z","steps":["trace[274433779] 'agreement among raft nodes before linearized reading' (duration: 245.689009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:46.990491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.766477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:46.990536Z","caller":"traceutil/trace.go:171","msg":"trace[1302563249] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25693; }","duration":"216.815608ms","start":"2026-08-22T15:23:46.773711Z","end":"2026-08-22T15:23:46.990527Z","steps":["trace[1302563249] 'agreement among raft nodes before linearized reading' (duration: 216.731186ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:47.242987Z","caller":"traceutil/trace.go:171","msg":"trace[740050726] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"139.721218ms","start":"2026-08-22T15:23:47.103248Z","end":"2026-08-22T15:23:47.24297Z","steps":["trace[740050726] 'process raft request' (duration: 139.330318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:47.506489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.664824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:47.506571Z","caller":"traceutil/trace.go:171","msg":"trace[606973720] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25694; }","duration":"209.764198ms","start":"2026-08-22T15:23:47.296789Z","end":"2026-08-22T15:23:47.506553Z","steps":["trace[606973720] 'range keys from in-memory index tree' (duration: 209.603843ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:47.50688Z","caller":"traceutil/trace.go:171","msg":"trace[504864983] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"146.115835ms","start":"2026-08-22T15:23:47.360741Z","end":"2026-08-22T15:23:47.506857Z","steps":["trace[504864983] 'process raft request' (duration: 140.084128ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:47.939519Z","caller":"traceutil/trace.go:171","msg":"trace[2128115401] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26511; }","duration":"102.021384ms","start":"2026-08-22T15:23:47.837478Z","end":"2026-08-22T15:23:47.9395Z","steps":["trace[2128115401] 'read index received' (duration: 101.86153ms)","trace[2128115401] 'applied index is now lower than readState.Index' (duration: 159.094µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:47.939752Z","caller":"traceutil/trace.go:171","msg":"trace[84609112] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"118.860253ms","start":"2026-08-22T15:23:47.820878Z","end":"2026-08-22T15:23:47.939738Z","steps":["trace[84609112] 'process raft request' (duration: 118.508104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:47.939958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.458554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:47.939996Z","caller":"traceutil/trace.go:171","msg":"trace[903889211] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25696; }","duration":"102.527037ms","start":"2026-08-22T15:23:47.837455Z","end":"2026-08-22T15:23:47.939982Z","steps":["trace[903889211] 'agreement among raft nodes before linearized reading' (duration: 102.456565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.285684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.067713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:48.285778Z","caller":"traceutil/trace.go:171","msg":"trace[1673889962] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25696; }","duration":"179.189336ms","start":"2026-08-22T15:23:48.106555Z","end":"2026-08-22T15:23:48.285744Z","steps":["trace[1673889962] 'count revisions from in-memory index tree' (duration: 178.969871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.286082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.632122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T15:23:48.286142Z","caller":"traceutil/trace.go:171","msg":"trace[616770560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25696; }","duration":"166.738944ms","start":"2026-08-22T15:23:48.119389Z","end":"2026-08-22T15:23:48.286128Z","steps":["trace[616770560] 'range keys from in-memory index tree' (duration: 166.487579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.286252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.559274ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:48.28638Z","caller":"traceutil/trace.go:171","msg":"trace[370661605] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25696; }","duration":"146.687557ms","start":"2026-08-22T15:23:48.139681Z","end":"2026-08-22T15:23:48.286368Z","steps":["trace[370661605] 'range keys from in-memory index tree' (duration: 146.549384ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:48.334874Z","caller":"traceutil/trace.go:171","msg":"trace[563887323] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"214.987525ms","start":"2026-08-22T15:23:48.119872Z","end":"2026-08-22T15:23:48.334859Z","steps":["trace[563887323] 'process raft request' (duration: 214.891673ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:48.334874Z","caller":"traceutil/trace.go:171","msg":"trace[1566207733] linearizableReadLoop","detail":"{readStateIndex:26513; appliedIndex:26513; }","duration":"214.338648ms","start":"2026-08-22T15:23:48.120519Z","end":"2026-08-22T15:23:48.334857Z","steps":["trace[1566207733] 'read index received' (duration: 214.331158ms)","trace[1566207733] 'applied index is now lower than readState.Index' (duration: 5.82µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:48.335035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.498132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T15:23:48.335064Z","caller":"traceutil/trace.go:171","msg":"trace[1892377700] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25697; }","duration":"214.576224ms","start":"2026-08-22T15:23:48.12048Z","end":"2026-08-22T15:23:48.335057Z","steps":["trace[1892377700] 'agreement among raft nodes before linearized reading' (duration: 214.42626ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:48.613566Z","caller":"traceutil/trace.go:171","msg":"trace[1429030416] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"477.243026ms","start":"2026-08-22T15:23:48.136294Z","end":"2026-08-22T15:23:48.613537Z","steps":["trace[1429030416] 'process raft request' (duration: 477.143613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.613679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.136286Z","time spent":"477.333498ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:48.613708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.165642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T15:23:48.613559Z","caller":"traceutil/trace.go:171","msg":"trace[9981951] linearizableReadLoop","detail":"{readStateIndex:26516; appliedIndex:26513; }","duration":"278.542227ms","start":"2026-08-22T15:23:48.335Z","end":"2026-08-22T15:23:48.613542Z","steps":["trace[9981951] 'read index received' (duration: 278.327902ms)","trace[9981951] 'applied index is now lower than readState.Index' (duration: 213.495µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:48.613748Z","caller":"traceutil/trace.go:171","msg":"trace[1393444111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25700; }","duration":"493.256324ms","start":"2026-08-22T15:23:48.12048Z","end":"2026-08-22T15:23:48.613736Z","steps":["trace[1393444111] 'agreement among raft nodes before linearized reading' (duration: 493.12454ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:48.613729Z","caller":"traceutil/trace.go:171","msg":"trace[1206768436] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"478.410014ms","start":"2026-08-22T15:23:48.135285Z","end":"2026-08-22T15:23:48.613695Z","steps":["trace[1206768436] 'process raft request' (duration: 478.011004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.613784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.120468Z","time spent":"493.308985ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T15:23:48.613777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.893412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-08-22T15:23:48.61377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.726878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:23:48.613831Z","caller":"traceutil/trace.go:171","msg":"trace[1346615829] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25700; }","duration":"477.990574ms","start":"2026-08-22T15:23:48.135832Z","end":"2026-08-22T15:23:48.613823Z","steps":["trace[1346615829] 'agreement among raft nodes before linearized reading' (duration: 477.780569ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:48.613806Z","caller":"traceutil/trace.go:171","msg":"trace[73965750] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"323.859315ms","start":"2026-08-22T15:23:48.289916Z","end":"2026-08-22T15:23:48.613775Z","steps":["trace[73965750] 'process raft request' (duration: 323.564598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.613859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.135822Z","time spent":"478.030624ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-22T15:23:48.613857Z","caller":"traceutil/trace.go:171","msg":"trace[251930027] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25700; }","duration":"477.8266ms","start":"2026-08-22T15:23:48.13601Z","end":"2026-08-22T15:23:48.613837Z","steps":["trace[251930027] 'agreement among raft nodes before linearized reading' (duration: 477.552923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.613883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.135256Z","time spent":"478.541237ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:48.613878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.889076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T15:23:48.613911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.135992Z","time spent":"477.908471ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-22T15:23:48.613931Z","caller":"traceutil/trace.go:171","msg":"trace[818802207] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25700; }","duration":"410.992209ms","start":"2026-08-22T15:23:48.202924Z","end":"2026-08-22T15:23:48.613917Z","steps":["trace[818802207] 'agreement among raft nodes before linearized reading' (duration: 410.874096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.613945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.289893Z","time spent":"323.977988ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:48.613969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.202902Z","time spent":"411.05761ms","remote":"127.0.0.1:37738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-08-22T15:23:48.614163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.584008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:23:48.61444Z","caller":"traceutil/trace.go:171","msg":"trace[1356014892] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25700; }","duration":"478.870165ms","start":"2026-08-22T15:23:48.135551Z","end":"2026-08-22T15:23:48.614421Z","steps":["trace[1356014892] 'agreement among raft nodes before linearized reading' (duration: 478.092026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.614489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.135543Z","time spent":"478.931056ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-22T15:23:48.628421Z","caller":"traceutil/trace.go:171","msg":"trace[2071398838] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"287.812371ms","start":"2026-08-22T15:23:48.34059Z","end":"2026-08-22T15:23:48.628402Z","steps":["trace[2071398838] 'process raft request' (duration: 287.528804ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:48.628573Z","caller":"traceutil/trace.go:171","msg":"trace[537589217] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"210.765752ms","start":"2026-08-22T15:23:48.417765Z","end":"2026-08-22T15:23:48.628531Z","steps":["trace[537589217] 'process raft request' (duration: 210.517026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.628705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.246723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:23:48.628807Z","caller":"traceutil/trace.go:171","msg":"trace[1587298146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25702; }","duration":"211.358986ms","start":"2026-08-22T15:23:48.417409Z","end":"2026-08-22T15:23:48.628768Z","steps":["trace[1587298146] 'agreement among raft nodes before linearized reading' (duration: 211.147281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:48.919646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.980429ms","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-08-22T15:23:48.919782Z","caller":"traceutil/trace.go:171","msg":"trace[32282396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25704; }","duration":"235.159834ms","start":"2026-08-22T15:23:48.684601Z","end":"2026-08-22T15:23:48.919761Z","steps":["trace[32282396] 'range keys from in-memory index tree' (duration: 234.809215ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:49.244558Z","caller":"traceutil/trace.go:171","msg":"trace[2126935413] linearizableReadLoop","detail":"{readStateIndex:26521; appliedIndex:26520; }","duration":"249.876001ms","start":"2026-08-22T15:23:48.994658Z","end":"2026-08-22T15:23:49.244534Z","steps":["trace[2126935413] 'read index received' (duration: 249.667616ms)","trace[2126935413] 'applied index is now lower than readState.Index' (duration: 207.395µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:49.244793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.121746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-22T15:23:49.244836Z","caller":"traceutil/trace.go:171","msg":"trace[2053992] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25705; }","duration":"250.220029ms","start":"2026-08-22T15:23:48.994604Z","end":"2026-08-22T15:23:49.244824Z","steps":["trace[2053992] 'agreement among raft nodes before linearized reading' (duration: 250.037624ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:49.244837Z","caller":"traceutil/trace.go:171","msg":"trace[202838037] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"321.615171ms","start":"2026-08-22T15:23:48.92319Z","end":"2026-08-22T15:23:49.244805Z","steps":["trace[202838037] 'process raft request' (duration: 321.193591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:49.244992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:48.923152Z","time spent":"321.760014ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:49.546579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.973094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:49.546828Z","caller":"traceutil/trace.go:171","msg":"trace[1871162390] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"254.314968ms","start":"2026-08-22T15:23:49.292495Z","end":"2026-08-22T15:23:49.54681Z","steps":["trace[1871162390] 'process raft request' (duration: 254.269477ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:49.546961Z","caller":"traceutil/trace.go:171","msg":"trace[1089047758] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"296.914802ms","start":"2026-08-22T15:23:49.250036Z","end":"2026-08-22T15:23:49.546951Z","steps":["trace[1089047758] 'process raft request' (duration: 296.640915ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:49.547046Z","caller":"traceutil/trace.go:171","msg":"trace[767270478] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"298.241334ms","start":"2026-08-22T15:23:49.248777Z","end":"2026-08-22T15:23:49.547019Z","steps":["trace[767270478] 'process raft request' (duration: 129.748618ms)","trace[767270478] 'compare' (duration: 167.543594ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:49.670003Z","caller":"traceutil/trace.go:171","msg":"trace[1457729724] linearizableReadLoop","detail":"{readStateIndex:26525; appliedIndex:26524; }","duration":"102.087587ms","start":"2026-08-22T15:23:49.56789Z","end":"2026-08-22T15:23:49.669977Z","steps":["trace[1457729724] 'read index received' (duration: 96.767407ms)","trace[1457729724] 'applied index is now lower than readState.Index' (duration: 5.31892ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:49.670103Z","caller":"traceutil/trace.go:171","msg":"trace[1059880261] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"159.344775ms","start":"2026-08-22T15:23:49.51071Z","end":"2026-08-22T15:23:49.670055Z","steps":["trace[1059880261] 'process raft request' (duration: 153.913653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:49.670167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.26052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:49.670213Z","caller":"traceutil/trace.go:171","msg":"trace[1618202876] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25709; }","duration":"102.369593ms","start":"2026-08-22T15:23:49.567829Z","end":"2026-08-22T15:23:49.670199Z","steps":["trace[1618202876] 'agreement among raft nodes before linearized reading' (duration: 102.266111ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:53.414746Z","caller":"traceutil/trace.go:171","msg":"trace[33987240] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"150.180253ms","start":"2026-08-22T15:23:53.264538Z","end":"2026-08-22T15:23:53.414718Z","steps":["trace[33987240] 'process raft request' (duration: 149.939716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:53.739398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.902598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:53.739636Z","caller":"traceutil/trace.go:171","msg":"trace[1876087000] linearizableReadLoop","detail":"{readStateIndex:26552; appliedIndex:26551; }","duration":"180.267332ms","start":"2026-08-22T15:23:53.559246Z","end":"2026-08-22T15:23:53.739513Z","steps":["trace[1876087000] 'read index received' (duration: 13.067197ms)","trace[1876087000] 'applied index is now lower than readState.Index' (duration: 167.198425ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:53.740055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.791905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-22T15:23:53.740134Z","caller":"traceutil/trace.go:171","msg":"trace[252659278] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25735; }","duration":"180.919178ms","start":"2026-08-22T15:23:53.559197Z","end":"2026-08-22T15:23:53.740116Z","steps":["trace[252659278] 'agreement among raft nodes before linearized reading' (duration: 180.699843ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:53.740295Z","caller":"traceutil/trace.go:171","msg":"trace[37280210] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"182.273731ms","start":"2026-08-22T15:23:53.557966Z","end":"2026-08-22T15:23:53.74024Z","steps":["trace[37280210] 'process raft request' (duration: 14.42593ms)","trace[37280210] 'compare' (duration: 166.764824ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:53.868729Z","caller":"traceutil/trace.go:171","msg":"trace[1264654718] linearizableReadLoop","detail":"{readStateIndex:26553; appliedIndex:26552; }","duration":"128.761473ms","start":"2026-08-22T15:23:53.739935Z","end":"2026-08-22T15:23:53.868696Z","steps":["trace[1264654718] 'read index received' (duration: 106.00135ms)","trace[1264654718] 'applied index is now lower than readState.Index' (duration: 22.758963ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:53.86884Z","caller":"traceutil/trace.go:171","msg":"trace[1808909744] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"189.826184ms","start":"2026-08-22T15:23:53.678945Z","end":"2026-08-22T15:23:53.868771Z","steps":["trace[1808909744] 'process raft request' (duration: 166.969429ms)","trace[1808909744] 'compare' (duration: 22.629549ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:53.86896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.434546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:23:53.869065Z","caller":"traceutil/trace.go:171","msg":"trace[122175706] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25736; }","duration":"295.60802ms","start":"2026-08-22T15:23:53.573438Z","end":"2026-08-22T15:23:53.869046Z","steps":["trace[122175706] 'agreement among raft nodes before linearized reading' (duration: 295.403516ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:53.913888Z","caller":"traceutil/trace.go:171","msg":"trace[1589991727] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"168.824675ms","start":"2026-08-22T15:23:53.745025Z","end":"2026-08-22T15:23:53.913849Z","steps":["trace[1589991727] 'process raft request' (duration: 168.592579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.194999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.224951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:23:54.195144Z","caller":"traceutil/trace.go:171","msg":"trace[1346517711] linearizableReadLoop","detail":"{readStateIndex:26555; appliedIndex:26554; }","duration":"127.44191ms","start":"2026-08-22T15:23:54.067639Z","end":"2026-08-22T15:23:54.195081Z","steps":["trace[1346517711] 'read index received' (duration: 99.912µs)","trace[1346517711] 'applied index is now lower than readState.Index' (duration: 127.340918ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:54.195133Z","caller":"traceutil/trace.go:171","msg":"trace[1623115127] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"230.787507ms","start":"2026-08-22T15:23:53.96431Z","end":"2026-08-22T15:23:54.195098Z","steps":["trace[1623115127] 'process raft request' (duration: 71.385281ms)","trace[1623115127] 'compare' (duration: 159.051208ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:54.195332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.686167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:23:54.195358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.571332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:23:54.195404Z","caller":"traceutil/trace.go:171","msg":"trace[857275082] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25738; }","duration":"127.765599ms","start":"2026-08-22T15:23:54.067619Z","end":"2026-08-22T15:23:54.195384Z","steps":["trace[857275082] 'agreement among raft nodes before linearized reading' (duration: 127.616015ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:54.195424Z","caller":"traceutil/trace.go:171","msg":"trace[895325914] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25738; }","duration":"110.638493ms","start":"2026-08-22T15:23:54.084749Z","end":"2026-08-22T15:23:54.195387Z","steps":["trace[895325914] 'agreement among raft nodes before linearized reading' (duration: 110.50807ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:54.68208Z","caller":"traceutil/trace.go:171","msg":"trace[337161529] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"130.859624ms","start":"2026-08-22T15:23:54.551193Z","end":"2026-08-22T15:23:54.682052Z","steps":["trace[337161529] 'process raft request' (duration: 130.033494ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:54.985383Z","caller":"traceutil/trace.go:171","msg":"trace[176882316] linearizableReadLoop","detail":"{readStateIndex:26560; appliedIndex:26557; }","duration":"303.286376ms","start":"2026-08-22T15:23:54.682076Z","end":"2026-08-22T15:23:54.985363Z","steps":["trace[176882316] 'read index received' (duration: 243.312822ms)","trace[176882316] 'applied index is now lower than readState.Index' (duration: 59.972674ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:54.985576Z","caller":"traceutil/trace.go:171","msg":"trace[2049552388] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"342.926947ms","start":"2026-08-22T15:23:54.642559Z","end":"2026-08-22T15:23:54.985486Z","steps":["trace[2049552388] 'process raft request' (duration: 342.759073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.985633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.627431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T15:23:54.985649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.02076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T15:23:54.985681Z","caller":"traceutil/trace.go:171","msg":"trace[605218812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25743; }","duration":"335.707703ms","start":"2026-08-22T15:23:54.649961Z","end":"2026-08-22T15:23:54.985668Z","steps":["trace[605218812] 'agreement among raft nodes before linearized reading' (duration: 335.633121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.98572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.64994Z","time spent":"335.774654ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T15:23:54.985686Z","caller":"traceutil/trace.go:171","msg":"trace[1409605065] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25743; }","duration":"336.078141ms","start":"2026-08-22T15:23:54.649596Z","end":"2026-08-22T15:23:54.985674Z","steps":["trace[1409605065] 'agreement among raft nodes before linearized reading' (duration: 335.962978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.985868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.642547Z","time spent":"343.123482ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:23:54.985379Z","caller":"traceutil/trace.go:171","msg":"trace[1058985690] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"352.365787ms","start":"2026-08-22T15:23:54.632976Z","end":"2026-08-22T15:23:54.985342Z","steps":["trace[1058985690] 'process raft request' (duration: 292.502275ms)","trace[1058985690] 'compare' (duration: 59.680667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:23:54.985987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.632953Z","time spent":"353.009422ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:54.985896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.64959Z","time spent":"336.298597ms","remote":"127.0.0.1:41762","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":"info","ts":"2026-08-22T15:23:54.985513Z","caller":"traceutil/trace.go:171","msg":"trace[1876888907] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"345.548501ms","start":"2026-08-22T15:23:54.639932Z","end":"2026-08-22T15:23:54.985481Z","steps":["trace[1876888907] 'process raft request' (duration: 345.343296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.986051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.549924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T15:23:54.986072Z","caller":"traceutil/trace.go:171","msg":"trace[1007147623] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25743; }","duration":"299.598537ms","start":"2026-08-22T15:23:54.686468Z","end":"2026-08-22T15:23:54.986066Z","steps":["trace[1007147623] 'agreement among raft nodes before linearized reading' (duration: 299.506005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.986074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.639922Z","time spent":"346.132325ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:23:54.985546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.457083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-22T15:23:54.986125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.999064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T15:23:54.986116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.312042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-08-22T15:23:54.986159Z","caller":"traceutil/trace.go:171","msg":"trace[1189276568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25743; }","duration":"121.132758ms","start":"2026-08-22T15:23:54.865017Z","end":"2026-08-22T15:23:54.98615Z","steps":["trace[1189276568] 'agreement among raft nodes before linearized reading' (duration: 121.075637ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:54.98615Z","caller":"traceutil/trace.go:171","msg":"trace[1783014606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25743; }","duration":"319.091539ms","start":"2026-08-22T15:23:54.667037Z","end":"2026-08-22T15:23:54.986129Z","steps":["trace[1783014606] 'agreement among raft nodes before linearized reading' (duration: 318.338521ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:54.986171Z","caller":"traceutil/trace.go:171","msg":"trace[167702874] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25743; }","duration":"356.375703ms","start":"2026-08-22T15:23:54.629783Z","end":"2026-08-22T15:23:54.986158Z","steps":["trace[167702874] 'agreement among raft nodes before linearized reading' (duration: 356.22887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.986207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.629742Z","time spent":"356.457235ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-22T15:23:54.986207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.667025Z","time spent":"319.16384ms","remote":"127.0.0.1:41762","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-08-22T15:23:54.986395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.92288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T15:23:54.986506Z","caller":"traceutil/trace.go:171","msg":"trace[1469937908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25743; }","duration":"353.056543ms","start":"2026-08-22T15:23:54.633434Z","end":"2026-08-22T15:23:54.98649Z","steps":["trace[1469937908] 'agreement among raft nodes before linearized reading' (duration: 352.366526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:54.986646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:23:54.633425Z","time spent":"353.192176ms","remote":"127.0.0.1:41762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T15:23:55.143455Z","caller":"traceutil/trace.go:171","msg":"trace[2030724044] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"153.292538ms","start":"2026-08-22T15:23:54.990141Z","end":"2026-08-22T15:23:55.143433Z","steps":["trace[2030724044] 'process raft request' (duration: 141.982184ms)","trace[2030724044] 'compare' (duration: 11.16347ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:55.143778Z","caller":"traceutil/trace.go:171","msg":"trace[1864629728] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"153.479083ms","start":"2026-08-22T15:23:54.990282Z","end":"2026-08-22T15:23:55.143761Z","steps":["trace[1864629728] 'process raft request' (duration: 153.118574ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:55.143805Z","caller":"traceutil/trace.go:171","msg":"trace[107866383] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"153.445002ms","start":"2026-08-22T15:23:54.990325Z","end":"2026-08-22T15:23:55.14377Z","steps":["trace[107866383] 'process raft request' (duration: 153.264268ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:55.143887Z","caller":"traceutil/trace.go:171","msg":"trace[867729702] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"153.545585ms","start":"2026-08-22T15:23:54.990335Z","end":"2026-08-22T15:23:55.143881Z","steps":["trace[867729702] 'process raft request' (duration: 153.36375ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:55.534289Z","caller":"traceutil/trace.go:171","msg":"trace[119738339] linearizableReadLoop","detail":"{readStateIndex:26565; appliedIndex:26564; }","duration":"109.818324ms","start":"2026-08-22T15:23:55.424449Z","end":"2026-08-22T15:23:55.534267Z","steps":["trace[119738339] 'read index received' (duration: 109.579998ms)","trace[119738339] 'applied index is now lower than readState.Index' (duration: 237.396µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:23:55.534402Z","caller":"traceutil/trace.go:171","msg":"trace[68368182] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"112.024327ms","start":"2026-08-22T15:23:55.422348Z","end":"2026-08-22T15:23:55.534372Z","steps":["trace[68368182] 'process raft request' (duration: 111.750371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:23:55.534449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.964437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:23:55.534505Z","caller":"traceutil/trace.go:171","msg":"trace[292542845] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25748; }","duration":"110.07081ms","start":"2026-08-22T15:23:55.424417Z","end":"2026-08-22T15:23:55.534488Z","steps":["trace[292542845] 'agreement among raft nodes before linearized reading' (duration: 109.940297ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:24:53.579373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23334} {"level":"info","ts":"2026-08-22T15:24:53.699154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23334,"took":"116.25745ms","hash":3150048288,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":38543360,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-22T15:24:53.699212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3150048288,"revision":23334,"compact-revision":20368} {"level":"info","ts":"2026-08-22T15:25:00.15305Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069c1.wal"} {"level":"info","ts":"2026-08-22T15:25:01.390642Z","caller":"traceutil/trace.go:171","msg":"trace[1513456461] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"123.26856ms","start":"2026-08-22T15:25:01.267351Z","end":"2026-08-22T15:25:01.390619Z","steps":["trace[1513456461] 'process raft request' (duration: 123.091086ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:25:01.391469Z","caller":"traceutil/trace.go:171","msg":"trace[37585837] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"108.515082ms","start":"2026-08-22T15:25:01.282932Z","end":"2026-08-22T15:25:01.391447Z","steps":["trace[37585837] 'process raft request' (duration: 108.295066ms)"],"step_count":1}