{"level":"warn","ts":"2026-07-01T11:02:24.43528Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T11:02:24.435382Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T11:02:24.435454Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T11:02:24.435467Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-01T11:02:24.43549Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T11:02:24.43614Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-07-01T11:02:24.436215Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T11:02:24.438551Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.08442ms"} {"level":"info","ts":"2026-07-01T11:02:24.448303Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-07-01T11:02:24.448417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T11:02:24.448445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-07-01T11:02:24.44846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T11:02:24.448469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-07-01T11:02:24.44852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-07-01T11:02:24.453293Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T11:02:24.45484Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T11:02:24.455905Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T11:02:24.458009Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T11:02:24.458469Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T11:02:24.458897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-07-01T11:02:24.458841Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T11:02:24.459018Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-01T11:02:24.459074Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T11:02:24.459121Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T11:02:24.463347Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T11:02:24.463558Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T11:02:24.463584Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T11:02:24.463603Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-01T11:02:24.463726Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-01T11:02:25.44925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T11:02:25.449474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T11:02:25.449641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-07-01T11:02:25.449747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-07-01T11:02:25.449839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-01T11:02:25.44989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-07-01T11:02:25.449946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-01T11:02:25.451234Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:25.452311Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T11:02:25.452305Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T11:02:25.452412Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T11:02:25.452735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:25.452737Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T11:02:25.452812Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T11:02:25.452892Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:25.452976Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T11:02:25.45546Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-07-01T11:02:25.456826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T11:02:41.509199Z","caller":"traceutil/trace.go:171","msg":"trace[885869913] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"100.704816ms","start":"2026-07-01T11:02:41.408469Z","end":"2026-07-01T11:02:41.509174Z","steps":["trace[885869913] 'process raft request' (duration: 100.557053ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:06.191802Z","caller":"traceutil/trace.go:171","msg":"trace[512580328] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"106.364398ms","start":"2026-07-01T11:03:06.085409Z","end":"2026-07-01T11:03:06.191773Z","steps":["trace[512580328] 'process raft request' (duration: 105.735035ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:06.383079Z","caller":"traceutil/trace.go:171","msg":"trace[1387753575] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"128.354313ms","start":"2026-07-01T11:03:06.254699Z","end":"2026-07-01T11:03:06.383054Z","steps":["trace[1387753575] 'read index received' (duration: 93.267317ms)","trace[1387753575] 'applied index is now lower than readState.Index' (duration: 35.085976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:03:06.383407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.406484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T11:03:06.383456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.986538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:03:06.383534Z","caller":"traceutil/trace.go:171","msg":"trace[2143164508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:625; }","duration":"103.097401ms","start":"2026-07-01T11:03:06.280415Z","end":"2026-07-01T11:03:06.383513Z","steps":["trace[2143164508] 'agreement among raft nodes before linearized reading' (duration: 102.939878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:03:06.383548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.810292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:03:06.383688Z","caller":"traceutil/trace.go:171","msg":"trace[25367271] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:625; }","duration":"128.992125ms","start":"2026-07-01T11:03:06.254676Z","end":"2026-07-01T11:03:06.383668Z","steps":["trace[25367271] 'agreement among raft nodes before linearized reading' (duration: 128.76023ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:06.383534Z","caller":"traceutil/trace.go:171","msg":"trace[183186910] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:625; }","duration":"128.584877ms","start":"2026-07-01T11:03:06.254926Z","end":"2026-07-01T11:03:06.383511Z","steps":["trace[183186910] 'agreement among raft nodes before linearized reading' (duration: 128.348512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:03:06.383862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.197829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T11:03:06.383867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.617551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:03:06.383947Z","caller":"traceutil/trace.go:171","msg":"trace[1478183322] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:625; }","duration":"128.320541ms","start":"2026-07-01T11:03:06.255601Z","end":"2026-07-01T11:03:06.383922Z","steps":["trace[1478183322] 'agreement among raft nodes before linearized reading' (duration: 128.138828ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:06.383963Z","caller":"traceutil/trace.go:171","msg":"trace[1053953697] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:625; }","duration":"103.771235ms","start":"2026-07-01T11:03:06.280164Z","end":"2026-07-01T11:03:06.383936Z","steps":["trace[1053953697] 'agreement among raft nodes before linearized reading' (duration: 103.569961ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:06.383196Z","caller":"traceutil/trace.go:171","msg":"trace[1646841491] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"128.845542ms","start":"2026-07-01T11:03:06.25434Z","end":"2026-07-01T11:03:06.383185Z","steps":["trace[1646841491] 'process raft request' (duration: 93.617764ms)","trace[1646841491] 'compare' (duration: 34.81188ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:03:06.384155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.774194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:03:06.384271Z","caller":"traceutil/trace.go:171","msg":"trace[1045394489] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:625; }","duration":"103.958648ms","start":"2026-07-01T11:03:06.280295Z","end":"2026-07-01T11:03:06.384253Z","steps":["trace[1045394489] 'agreement among raft nodes before linearized reading' (duration: 103.270534ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:06.643181Z","caller":"traceutil/trace.go:171","msg":"trace[1577457792] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"204.002405ms","start":"2026-07-01T11:03:06.439139Z","end":"2026-07-01T11:03:06.643142Z","steps":["trace[1577457792] 'process raft request' (duration: 196.627352ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:07.11657Z","caller":"traceutil/trace.go:171","msg":"trace[1156960318] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"311.624058ms","start":"2026-07-01T11:03:06.804906Z","end":"2026-07-01T11:03:07.11653Z","steps":["trace[1156960318] 'process raft request' (duration: 311.029736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:03:07.117067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:03:06.804865Z","time spent":"311.788403ms","remote":"127.0.0.1:45980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:03:30.525277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.533049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T11:03:30.525381Z","caller":"traceutil/trace.go:171","msg":"trace[2132170604] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:812; }","duration":"193.683492ms","start":"2026-07-01T11:03:30.331678Z","end":"2026-07-01T11:03:30.525362Z","steps":["trace[2132170604] 'count revisions from in-memory index tree' (duration: 193.489948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:03:30.525535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.658064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-01T11:03:30.525535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.992452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T11:03:30.525609Z","caller":"traceutil/trace.go:171","msg":"trace[992860553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"197.092653ms","start":"2026-07-01T11:03:30.328499Z","end":"2026-07-01T11:03:30.525592Z","steps":["trace[992860553] 'range keys from in-memory index tree' (duration: 196.801927ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:03:30.525619Z","caller":"traceutil/trace.go:171","msg":"trace[1862405367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"166.781837ms","start":"2026-07-01T11:03:30.358801Z","end":"2026-07-01T11:03:30.525583Z","steps":["trace[1862405367] 'range keys from in-memory index tree' (duration: 166.509052ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:44.798955Z","caller":"traceutil/trace.go:171","msg":"trace[1496457024] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"238.021256ms","start":"2026-07-01T11:04:44.560906Z","end":"2026-07-01T11:04:44.798928Z","steps":["trace[1496457024] 'process raft request' (duration: 237.843022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:45.253742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.473456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:04:45.253845Z","caller":"traceutil/trace.go:171","msg":"trace[1684268760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"205.623289ms","start":"2026-07-01T11:04:45.048194Z","end":"2026-07-01T11:04:45.253817Z","steps":["trace[1684268760] 'range keys from in-memory index tree' (duration: 205.379515ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:04:58.415559Z","caller":"traceutil/trace.go:171","msg":"trace[642397707] linearizableReadLoop","detail":"{readStateIndex:2053; appliedIndex:2052; }","duration":"302.284318ms","start":"2026-07-01T11:04:58.113214Z","end":"2026-07-01T11:04:58.415499Z","steps":["trace[642397707] 'read index received' (duration: 302.112595ms)","trace[642397707] 'applied index is now lower than readState.Index' (duration: 170.663µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:04:58.415612Z","caller":"traceutil/trace.go:171","msg":"trace[1942931229] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"325.96806ms","start":"2026-07-01T11:04:58.089598Z","end":"2026-07-01T11:04:58.415566Z","steps":["trace[1942931229] 'process raft request' (duration: 325.710225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:58.415745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.522552ms","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:7"} {"level":"info","ts":"2026-07-01T11:04:58.415783Z","caller":"traceutil/trace.go:171","msg":"trace[1536302852] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1980; }","duration":"302.597033ms","start":"2026-07-01T11:04:58.113174Z","end":"2026-07-01T11:04:58.415771Z","steps":["trace[1536302852] 'agreement among raft nodes before linearized reading' (duration: 302.493461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:04:58.41582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:04:58.113159Z","time spent":"302.651215ms","remote":"127.0.0.1:35188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":13,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:04:58.415805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:04:58.089563Z","time spent":"326.126954ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:04:58.415847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.801545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T11:04:58.415912Z","caller":"traceutil/trace.go:171","msg":"trace[1505952830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1980; }","duration":"183.868487ms","start":"2026-07-01T11:04:58.232024Z","end":"2026-07-01T11:04:58.415893Z","steps":["trace[1505952830] 'agreement among raft nodes before linearized reading' (duration: 183.675553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:24.184699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.529878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T11:05:24.184825Z","caller":"traceutil/trace.go:171","msg":"trace[277033920] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"150.590973ms","start":"2026-07-01T11:05:24.034218Z","end":"2026-07-01T11:05:24.184809Z","steps":["trace[277033920] 'compare' (duration: 141.384766ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:05:35.363051Z","caller":"traceutil/trace.go:171","msg":"trace[993238366] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"103.894016ms","start":"2026-07-01T11:05:35.259129Z","end":"2026-07-01T11:05:35.363023Z","steps":["trace[993238366] 'process raft request' (duration: 103.03766ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:05:35.511645Z","caller":"traceutil/trace.go:171","msg":"trace[181397786] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"144.743061ms","start":"2026-07-01T11:05:35.366862Z","end":"2026-07-01T11:05:35.511605Z","steps":["trace[181397786] 'process raft request' (duration: 127.700323ms)","trace[181397786] 'compare' (duration: 16.709771ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:05:35.511653Z","caller":"traceutil/trace.go:171","msg":"trace[676271951] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"144.013997ms","start":"2026-07-01T11:05:35.36757Z","end":"2026-07-01T11:05:35.511584Z","steps":["trace[676271951] 'process raft request' (duration: 143.947836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:36.204417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.243245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T11:05:36.204549Z","caller":"traceutil/trace.go:171","msg":"trace[894865355] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"261.994283ms","start":"2026-07-01T11:05:35.942534Z","end":"2026-07-01T11:05:36.204528Z","steps":["trace[894865355] 'process raft request' (duration: 160.589705ms)","trace[894865355] 'compare' (duration: 101.046111ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:05:36.255827Z","caller":"traceutil/trace.go:171","msg":"trace[872959342] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2280; }","duration":"205.732303ms","start":"2026-07-01T11:05:36.050068Z","end":"2026-07-01T11:05:36.2558Z","steps":["trace[872959342] 'read index received' (duration: 52.929087ms)","trace[872959342] 'applied index is now lower than readState.Index' (duration: 152.802146ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:05:36.255968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.874386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:05:36.256005Z","caller":"traceutil/trace.go:171","msg":"trace[1392880022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2200; }","duration":"205.993948ms","start":"2026-07-01T11:05:36.050001Z","end":"2026-07-01T11:05:36.255995Z","steps":["trace[1392880022] 'agreement among raft nodes before linearized reading' (duration: 205.908747ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:05:36.255945Z","caller":"traceutil/trace.go:171","msg":"trace[1336260918] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"313.218848ms","start":"2026-07-01T11:05:35.942694Z","end":"2026-07-01T11:05:36.255913Z","steps":["trace[1336260918] 'process raft request' (duration: 312.930821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:05:36.256184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:05:35.942657Z","time spent":"313.408741ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:09:29.49683Z","caller":"traceutil/trace.go:171","msg":"trace[61557878] transaction","detail":"{read_only:false; response_revision:3836; number_of_response:1; }","duration":"130.598813ms","start":"2026-07-01T11:09:29.366205Z","end":"2026-07-01T11:09:29.496804Z","steps":["trace[61557878] 'process raft request' (duration: 57.475661ms)","trace[61557878] 'compare' (duration: 72.872137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:10:56.060635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.184189ms","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-07-01T11:10:56.060725Z","caller":"traceutil/trace.go:171","msg":"trace[811774672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5006; }","duration":"149.331973ms","start":"2026-07-01T11:10:55.911372Z","end":"2026-07-01T11:10:56.060704Z","steps":["trace[811774672] 'range keys from in-memory index tree' (duration: 148.960865ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:10:56.125006Z","caller":"traceutil/trace.go:171","msg":"trace[1791813075] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"142.85969ms","start":"2026-07-01T11:10:55.982116Z","end":"2026-07-01T11:10:56.124976Z","steps":["trace[1791813075] 'process raft request' (duration: 142.694357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:11:17.344873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.494252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:11:17.344989Z","caller":"traceutil/trace.go:171","msg":"trace[835566354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5426; }","duration":"296.656915ms","start":"2026-07-01T11:11:17.048302Z","end":"2026-07-01T11:11:17.344959Z","steps":["trace[835566354] 'range keys from in-memory index tree' (duration: 296.39265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:11:17.345193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.113651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:11:17.345287Z","caller":"traceutil/trace.go:171","msg":"trace[1894125082] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5426; }","duration":"272.236973ms","start":"2026-07-01T11:11:17.07303Z","end":"2026-07-01T11:11:17.345267Z","steps":["trace[1894125082] 'range keys from in-memory index tree' (duration: 272.06413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:11:17.345463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.261585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T11:11:17.345542Z","caller":"traceutil/trace.go:171","msg":"trace[1148866962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5426; }","duration":"271.366107ms","start":"2026-07-01T11:11:17.074157Z","end":"2026-07-01T11:11:17.345524Z","steps":["trace[1148866962] 'range keys from in-memory index tree' (duration: 271.110261ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:11:17.34598Z","caller":"traceutil/trace.go:171","msg":"trace[453132828] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"115.197993ms","start":"2026-07-01T11:11:17.230725Z","end":"2026-07-01T11:11:17.345923Z","steps":["trace[453132828] 'process raft request' (duration: 78.831646ms)","trace[453132828] 'compare' (duration: 35.825726ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:12:25.488867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2985} {"level":"info","ts":"2026-07-01T11:12:25.565549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2985,"took":"74.022489ms","hash":3348683566,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":34021376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T11:12:25.565615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3348683566,"revision":2985,"compact-revision":-1} {"level":"info","ts":"2026-07-01T11:17:17.735907Z","caller":"traceutil/trace.go:171","msg":"trace[1586527763] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"130.658214ms","start":"2026-07-01T11:17:17.605217Z","end":"2026-07-01T11:17:17.735875Z","steps":["trace[1586527763] 'process raft request' (duration: 130.354587ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:17.84947Z","caller":"traceutil/trace.go:171","msg":"trace[1423045800] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"108.686015ms","start":"2026-07-01T11:17:17.740708Z","end":"2026-07-01T11:17:17.849394Z","steps":["trace[1423045800] 'process raft request' (duration: 107.339288ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:17:25.51419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6066} {"level":"info","ts":"2026-07-01T11:17:25.628714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6066,"took":"112.627596ms","hash":2574144168,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":22945792,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-01T11:17:25.628788Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2574144168,"revision":6066,"compact-revision":2985} {"level":"info","ts":"2026-07-01T11:17:31.340715Z","caller":"traceutil/trace.go:171","msg":"trace[167505747] linearizableReadLoop","detail":"{readStateIndex:8603; appliedIndex:8602; }","duration":"131.763586ms","start":"2026-07-01T11:17:31.208927Z","end":"2026-07-01T11:17:31.340691Z","steps":["trace[167505747] 'read index received' (duration: 131.587552ms)","trace[167505747] 'applied index is now lower than readState.Index' (duration: 174.884µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:17:31.340805Z","caller":"traceutil/trace.go:171","msg":"trace[273288845] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"182.641902ms","start":"2026-07-01T11:17:31.158133Z","end":"2026-07-01T11:17:31.340775Z","steps":["trace[273288845] 'process raft request' (duration: 182.379597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:17:31.340983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.032432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T11:17:31.341028Z","caller":"traceutil/trace.go:171","msg":"trace[1827749878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8314; }","duration":"132.128143ms","start":"2026-07-01T11:17:31.208889Z","end":"2026-07-01T11:17:31.341017Z","steps":["trace[1827749878] 'agreement among raft nodes before linearized reading' (duration: 131.950189ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:01.656435Z","caller":"traceutil/trace.go:171","msg":"trace[1998065488] linearizableReadLoop","detail":"{readStateIndex:8828; appliedIndex:8827; }","duration":"139.756628ms","start":"2026-07-01T11:18:01.51666Z","end":"2026-07-01T11:18:01.656417Z","steps":["trace[1998065488] 'read index received' (duration: 139.671586ms)","trace[1998065488] 'applied index is now lower than readState.Index' (duration: 84.472µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:18:01.656502Z","caller":"traceutil/trace.go:171","msg":"trace[7910936] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"147.606658ms","start":"2026-07-01T11:18:01.508859Z","end":"2026-07-01T11:18:01.656466Z","steps":["trace[7910936] 'process raft request' (duration: 147.436104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:01.656577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.900101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-01T11:18:01.656599Z","caller":"traceutil/trace.go:171","msg":"trace[1399916272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8531; }","duration":"139.990753ms","start":"2026-07-01T11:18:01.516602Z","end":"2026-07-01T11:18:01.656593Z","steps":["trace[1399916272] 'agreement among raft nodes before linearized reading' (duration: 139.889421ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:11.478776Z","caller":"traceutil/trace.go:171","msg":"trace[620914393] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"115.926014ms","start":"2026-07-01T11:18:11.362814Z","end":"2026-07-01T11:18:11.47874Z","steps":["trace[620914393] 'process raft request' (duration: 115.71568ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:11.643577Z","caller":"traceutil/trace.go:171","msg":"trace[1829948399] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"272.957925ms","start":"2026-07-01T11:18:11.370589Z","end":"2026-07-01T11:18:11.643547Z","steps":["trace[1829948399] 'process raft request' (duration: 272.69197ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:11.95105Z","caller":"traceutil/trace.go:171","msg":"trace[2103670396] linearizableReadLoop","detail":"{readStateIndex:8934; appliedIndex:8933; }","duration":"110.450833ms","start":"2026-07-01T11:18:11.840576Z","end":"2026-07-01T11:18:11.951026Z","steps":["trace[2103670396] 'read index received' (duration: 110.359041ms)","trace[2103670396] 'applied index is now lower than readState.Index' (duration: 90.912µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:18:11.95112Z","caller":"traceutil/trace.go:171","msg":"trace[1433994819] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"129.6774ms","start":"2026-07-01T11:18:11.821404Z","end":"2026-07-01T11:18:11.951081Z","steps":["trace[1433994819] 'process raft request' (duration: 129.473366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:11.951188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.593426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:18:11.951397Z","caller":"traceutil/trace.go:171","msg":"trace[1071259560] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8632; }","duration":"110.677127ms","start":"2026-07-01T11:18:11.840537Z","end":"2026-07-01T11:18:11.951214Z","steps":["trace[1071259560] 'agreement among raft nodes before linearized reading' (duration: 110.591106ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:12.465735Z","caller":"traceutil/trace.go:171","msg":"trace[522179080] linearizableReadLoop","detail":"{readStateIndex:8937; appliedIndex:8936; }","duration":"169.892731ms","start":"2026-07-01T11:18:12.295818Z","end":"2026-07-01T11:18:12.46571Z","steps":["trace[522179080] 'read index received' (duration: 169.593445ms)","trace[522179080] 'applied index is now lower than readState.Index' (duration: 298.166µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:18:12.465881Z","caller":"traceutil/trace.go:171","msg":"trace[1661979553] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"195.562177ms","start":"2026-07-01T11:18:12.270254Z","end":"2026-07-01T11:18:12.465816Z","steps":["trace[1661979553] 'process raft request' (duration: 195.262671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:12.466043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.194967ms","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-07-01T11:18:12.466112Z","caller":"traceutil/trace.go:171","msg":"trace[203459720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8635; }","duration":"170.32674ms","start":"2026-07-01T11:18:12.295768Z","end":"2026-07-01T11:18:12.466095Z","steps":["trace[203459720] 'agreement among raft nodes before linearized reading' (duration: 170.115096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:12.466309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.778528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-01T11:18:12.46644Z","caller":"traceutil/trace.go:171","msg":"trace[1263272599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8635; }","duration":"169.941802ms","start":"2026-07-01T11:18:12.296474Z","end":"2026-07-01T11:18:12.466415Z","steps":["trace[1263272599] 'agreement among raft nodes before linearized reading' (duration: 169.649366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:12.466479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.740097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-01T11:18:12.466563Z","caller":"traceutil/trace.go:171","msg":"trace[562374323] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8635; }","duration":"168.894131ms","start":"2026-07-01T11:18:12.297648Z","end":"2026-07-01T11:18:12.466542Z","steps":["trace[562374323] 'agreement among raft nodes before linearized reading' (duration: 168.655866ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:19:00.507603Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002499.wal"} {"level":"info","ts":"2026-07-01T11:20:06.315075Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:20:06.321731Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T11:20:06.321856Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T11:22:25.523733Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8284} {"level":"info","ts":"2026-07-01T11:22:25.605024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8284,"took":"78.899547ms","hash":1188254381,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":26497024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-01T11:22:25.605129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1188254381,"revision":8284,"compact-revision":6066} {"level":"warn","ts":"2026-07-01T11:23:09.396442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.25198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T11:23:09.396643Z","caller":"traceutil/trace.go:171","msg":"trace[2040075141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11216; }","duration":"210.482185ms","start":"2026-07-01T11:23:09.186119Z","end":"2026-07-01T11:23:09.396601Z","steps":["trace[2040075141] 'range keys from in-memory index tree' (duration: 210.069406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:23:09.396481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.952804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T11:23:09.397473Z","caller":"traceutil/trace.go:171","msg":"trace[1484189730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11216; }","duration":"209.315011ms","start":"2026-07-01T11:23:09.187452Z","end":"2026-07-01T11:23:09.396767Z","steps":["trace[1484189730] 'range keys from in-memory index tree' (duration: 208.731249ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:23:09.533463Z","caller":"traceutil/trace.go:171","msg":"trace[1797106002] transaction","detail":"{read_only:false; response_revision:11218; number_of_response:1; }","duration":"131.481067ms","start":"2026-07-01T11:23:09.401936Z","end":"2026-07-01T11:23:09.533417Z","steps":["trace[1797106002] 'process raft request' (duration: 94.187697ms)","trace[1797106002] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:472; } (duration: 37.116737ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:27:25.532653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10777} {"level":"info","ts":"2026-07-01T11:27:25.610545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10777,"took":"74.754983ms","hash":522393403,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":27619328,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-01T11:27:25.610601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":522393403,"revision":10777,"compact-revision":8284} {"level":"info","ts":"2026-07-01T11:28:55.318045Z","caller":"traceutil/trace.go:171","msg":"trace[2106015697] transaction","detail":"{read_only:false; response_revision:13580; number_of_response:1; }","duration":"127.355635ms","start":"2026-07-01T11:28:55.190665Z","end":"2026-07-01T11:28:55.31802Z","steps":["trace[2106015697] 'process raft request' (duration: 126.444787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:29:29.391207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.427421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T11:29:29.391526Z","caller":"traceutil/trace.go:171","msg":"trace[562477152] linearizableReadLoop","detail":"{readStateIndex:14236; appliedIndex:14235; }","duration":"128.941356ms","start":"2026-07-01T11:29:29.262565Z","end":"2026-07-01T11:29:29.391506Z","steps":["trace[562477152] 'read index received' (duration: 14.030055ms)","trace[562477152] 'applied index is now lower than readState.Index' (duration: 114.909401ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:29:29.39158Z","caller":"traceutil/trace.go:171","msg":"trace[267871930] transaction","detail":"{read_only:false; response_revision:13769; number_of_response:1; }","duration":"132.101851ms","start":"2026-07-01T11:29:29.259446Z","end":"2026-07-01T11:29:29.391548Z","steps":["trace[267871930] 'process raft request' (duration: 17.227911ms)","trace[267871930] 'compare' (duration: 114.276568ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:29:29.392157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.57498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:603499"} {"level":"info","ts":"2026-07-01T11:29:29.392204Z","caller":"traceutil/trace.go:171","msg":"trace[1558836882] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:13769; }","duration":"129.670961ms","start":"2026-07-01T11:29:29.262523Z","end":"2026-07-01T11:29:29.392194Z","steps":["trace[1558836882] 'agreement among raft nodes before linearized reading' (duration: 129.048309ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:29:30.625037Z","caller":"traceutil/trace.go:171","msg":"trace[1458849277] transaction","detail":"{read_only:false; response_revision:13778; number_of_response:1; }","duration":"124.842412ms","start":"2026-07-01T11:29:30.500159Z","end":"2026-07-01T11:29:30.625001Z","steps":["trace[1458849277] 'process raft request' (duration: 113.864129ms)","trace[1458849277] 'compare' (duration: 10.525204ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:29:39.380939Z","caller":"traceutil/trace.go:171","msg":"trace[90390160] transaction","detail":"{read_only:false; response_revision:13943; number_of_response:1; }","duration":"157.054069ms","start":"2026-07-01T11:29:39.223849Z","end":"2026-07-01T11:29:39.380903Z","steps":["trace[90390160] 'process raft request' (duration: 154.761602ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:29:39.665306Z","caller":"traceutil/trace.go:171","msg":"trace[1186495614] linearizableReadLoop","detail":"{readStateIndex:14414; appliedIndex:14413; }","duration":"231.421054ms","start":"2026-07-01T11:29:39.433849Z","end":"2026-07-01T11:29:39.66527Z","steps":["trace[1186495614] 'read index received' (duration: 216.073781ms)","trace[1186495614] 'applied index is now lower than readState.Index' (duration: 15.343213ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:29:39.665551Z","caller":"traceutil/trace.go:171","msg":"trace[1307539718] transaction","detail":"{read_only:false; response_revision:13945; number_of_response:1; }","duration":"183.887306ms","start":"2026-07-01T11:29:39.481432Z","end":"2026-07-01T11:29:39.66532Z","steps":["trace[1307539718] 'process raft request' (duration: 183.718552ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:29:39.665521Z","caller":"traceutil/trace.go:171","msg":"trace[2090714840] transaction","detail":"{read_only:false; response_revision:13944; number_of_response:1; }","duration":"270.986821ms","start":"2026-07-01T11:29:39.394262Z","end":"2026-07-01T11:29:39.665249Z","steps":["trace[2090714840] 'process raft request' (duration: 255.685329ms)","trace[2090714840] 'compare' (duration: 15.030076ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:29:39.665793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.70405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T11:29:39.665835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.953345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-01T11:29:39.665892Z","caller":"traceutil/trace.go:171","msg":"trace[192472096] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:13946; }","duration":"176.856843ms","start":"2026-07-01T11:29:39.489006Z","end":"2026-07-01T11:29:39.665863Z","steps":["trace[192472096] 'agreement among raft nodes before linearized reading' (duration: 176.7013ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:29:39.665898Z","caller":"traceutil/trace.go:171","msg":"trace[31710623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13946; }","duration":"232.064388ms","start":"2026-07-01T11:29:39.433815Z","end":"2026-07-01T11:29:39.66588Z","steps":["trace[31710623] 'agreement among raft nodes before linearized reading' (duration: 231.824113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:29:39.66593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.406573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-khxks\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-07-01T11:29:39.666016Z","caller":"traceutil/trace.go:171","msg":"trace[1555286608] range","detail":"{range_begin:/registry/pods/openstack/keepalived-khxks; range_end:; response_count:1; response_revision:13946; }","duration":"201.524205ms","start":"2026-07-01T11:29:39.46447Z","end":"2026-07-01T11:29:39.665994Z","steps":["trace[1555286608] 'agreement among raft nodes before linearized reading' (duration: 201.29438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:29:39.666016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.236205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-01T11:29:39.666132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.171566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T11:29:39.666182Z","caller":"traceutil/trace.go:171","msg":"trace[2003134592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13946; }","duration":"113.264137ms","start":"2026-07-01T11:29:39.5529Z","end":"2026-07-01T11:29:39.666165Z","steps":["trace[2003134592] 'agreement among raft nodes before linearized reading' (duration: 113.070224ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:29:39.666164Z","caller":"traceutil/trace.go:171","msg":"trace[525050146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13946; }","duration":"214.424969ms","start":"2026-07-01T11:29:39.45172Z","end":"2026-07-01T11:29:39.666145Z","steps":["trace[525050146] 'agreement among raft nodes before linearized reading' (duration: 213.97828ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:08.198026Z","caller":"traceutil/trace.go:171","msg":"trace[2110960285] linearizableReadLoop","detail":"{readStateIndex:15474; appliedIndex:15473; }","duration":"148.869793ms","start":"2026-07-01T11:31:08.049102Z","end":"2026-07-01T11:31:08.197971Z","steps":["trace[2110960285] 'read index received' (duration: 49.550692ms)","trace[2110960285] 'applied index is now lower than readState.Index' (duration: 99.317521ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:08.19828Z","caller":"traceutil/trace.go:171","msg":"trace[1548173469] transaction","detail":"{read_only:false; response_revision:14973; number_of_response:1; }","duration":"226.681144ms","start":"2026-07-01T11:31:07.971559Z","end":"2026-07-01T11:31:08.198241Z","steps":["trace[1548173469] 'process raft request' (duration: 127.137069ms)","trace[1548173469] 'compare' (duration: 99.071056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:31:08.198437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.219776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T11:31:08.198435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.271447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-01T11:31:08.198405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.276911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:31:08.198491Z","caller":"traceutil/trace.go:171","msg":"trace[1334627289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14973; }","duration":"117.348249ms","start":"2026-07-01T11:31:08.081128Z","end":"2026-07-01T11:31:08.198476Z","steps":["trace[1334627289] 'agreement among raft nodes before linearized reading' (duration: 117.103554ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:08.198519Z","caller":"traceutil/trace.go:171","msg":"trace[591996512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14973; }","duration":"115.367438ms","start":"2026-07-01T11:31:08.083132Z","end":"2026-07-01T11:31:08.198499Z","steps":["trace[591996512] 'agreement among raft nodes before linearized reading' (duration: 115.121834ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:08.19856Z","caller":"traceutil/trace.go:171","msg":"trace[1507000496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14973; }","duration":"149.486555ms","start":"2026-07-01T11:31:08.049051Z","end":"2026-07-01T11:31:08.198537Z","steps":["trace[1507000496] 'agreement among raft nodes before linearized reading' (duration: 149.161439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:08.198719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.657914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T11:31:08.198761Z","caller":"traceutil/trace.go:171","msg":"trace[1943561312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14973; }","duration":"116.732225ms","start":"2026-07-01T11:31:08.082016Z","end":"2026-07-01T11:31:08.198748Z","steps":["trace[1943561312] 'agreement among raft nodes before linearized reading' (duration: 116.635504ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:09.153576Z","caller":"traceutil/trace.go:171","msg":"trace[2036390307] transaction","detail":"{read_only:false; response_revision:14981; number_of_response:1; }","duration":"189.273449ms","start":"2026-07-01T11:31:08.964266Z","end":"2026-07-01T11:31:09.15354Z","steps":["trace[2036390307] 'process raft request' (duration: 189.069635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:09.370947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.946859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-s6c7s\" ","response":"range_response_count:1 size:9798"} {"level":"warn","ts":"2026-07-01T11:31:09.370971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.405509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T11:31:09.371042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.292393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-01T11:31:09.371089Z","caller":"traceutil/trace.go:171","msg":"trace[1702261411] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-s6c7s; range_end:; response_count:1; response_revision:14981; }","duration":"204.162994ms","start":"2026-07-01T11:31:09.166897Z","end":"2026-07-01T11:31:09.37106Z","steps":["trace[1702261411] 'range keys from in-memory index tree' (duration: 203.706344ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:09.371122Z","caller":"traceutil/trace.go:171","msg":"trace[1559427366] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14981; }","duration":"197.429127ms","start":"2026-07-01T11:31:09.17367Z","end":"2026-07-01T11:31:09.371099Z","steps":["trace[1559427366] 'range keys from in-memory index tree' (duration: 197.078689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:09.37109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.531061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T11:31:09.371087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.599863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:31:09.371198Z","caller":"traceutil/trace.go:171","msg":"trace[1265121947] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14981; }","duration":"204.659653ms","start":"2026-07-01T11:31:09.166508Z","end":"2026-07-01T11:31:09.371168Z","steps":["trace[1265121947] 'range keys from in-memory index tree' (duration: 204.430439ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:09.371216Z","caller":"traceutil/trace.go:171","msg":"trace[886244963] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14981; }","duration":"204.797237ms","start":"2026-07-01T11:31:09.166392Z","end":"2026-07-01T11:31:09.371189Z","steps":["trace[886244963] 'range keys from in-memory index tree' (duration: 204.47402ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:09.371114Z","caller":"traceutil/trace.go:171","msg":"trace[395948000] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14981; }","duration":"204.639904ms","start":"2026-07-01T11:31:09.166446Z","end":"2026-07-01T11:31:09.371086Z","steps":["trace[395948000] 'range keys from in-memory index tree' (duration: 204.314637ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:32:25.539239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12950} {"level":"info","ts":"2026-07-01T11:32:25.612457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12950,"took":"70.360369ms","hash":2271805086,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":30978048,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T11:32:25.612527Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2271805086,"revision":12950,"compact-revision":10777} {"level":"info","ts":"2026-07-01T11:37:03.084639Z","caller":"traceutil/trace.go:171","msg":"trace[615608807] transaction","detail":"{read_only:false; response_revision:17810; number_of_response:1; }","duration":"123.319237ms","start":"2026-07-01T11:37:02.961278Z","end":"2026-07-01T11:37:03.084597Z","steps":["trace[615608807] 'process raft request' (duration: 122.807667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:37:03.426879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.540692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:37:03.427034Z","caller":"traceutil/trace.go:171","msg":"trace[1079093911] linearizableReadLoop","detail":"{readStateIndex:18402; appliedIndex:18401; }","duration":"176.435541ms","start":"2026-07-01T11:37:03.250579Z","end":"2026-07-01T11:37:03.427015Z","steps":["trace[1079093911] 'read index received' (duration: 641.042µs)","trace[1079093911] 'applied index is now lower than readState.Index' (duration: 175.793159ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:37:03.427119Z","caller":"traceutil/trace.go:171","msg":"trace[2123453463] transaction","detail":"{read_only:false; response_revision:17812; number_of_response:1; }","duration":"222.570844ms","start":"2026-07-01T11:37:03.204535Z","end":"2026-07-01T11:37:03.427106Z","steps":["trace[2123453463] 'process raft request' (duration: 46.713414ms)","trace[2123453463] 'compare' (duration: 175.392859ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:37:03.427404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.765968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:37:03.427533Z","caller":"traceutil/trace.go:171","msg":"trace[1233411118] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:17812; }","duration":"176.949272ms","start":"2026-07-01T11:37:03.250554Z","end":"2026-07-01T11:37:03.427503Z","steps":["trace[1233411118] 'agreement among raft nodes before linearized reading' (duration: 176.752348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:37:03.427523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.597634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1149"} {"level":"warn","ts":"2026-07-01T11:37:03.427595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.167176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-s6c7s\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-07-01T11:37:03.427611Z","caller":"traceutil/trace.go:171","msg":"trace[1806015755] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:17812; }","duration":"176.710406ms","start":"2026-07-01T11:37:03.25088Z","end":"2026-07-01T11:37:03.42759Z","steps":["trace[1806015755] 'agreement among raft nodes before linearized reading' (duration: 176.454521ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:37:03.427644Z","caller":"traceutil/trace.go:171","msg":"trace[1696249618] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-s6c7s; range_end:; response_count:1; response_revision:17812; }","duration":"176.220287ms","start":"2026-07-01T11:37:03.251408Z","end":"2026-07-01T11:37:03.427628Z","steps":["trace[1696249618] 'agreement among raft nodes before linearized reading' (duration: 176.077864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:37:03.427621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.943551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:37:03.427667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.070474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:37:03.427747Z","caller":"traceutil/trace.go:171","msg":"trace[476410407] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:17812; }","duration":"177.207227ms","start":"2026-07-01T11:37:03.250521Z","end":"2026-07-01T11:37:03.427728Z","steps":["trace[476410407] 'agreement among raft nodes before linearized reading' (duration: 177.092565ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:37:03.427738Z","caller":"traceutil/trace.go:171","msg":"trace[1021700284] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:17812; }","duration":"177.102995ms","start":"2026-07-01T11:37:03.250612Z","end":"2026-07-01T11:37:03.427715Z","steps":["trace[1021700284] 'agreement among raft nodes before linearized reading' (duration: 176.906751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:37:03.427843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.217197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:37:03.427889Z","caller":"traceutil/trace.go:171","msg":"trace[1099658282] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:17812; }","duration":"177.268038ms","start":"2026-07-01T11:37:03.25061Z","end":"2026-07-01T11:37:03.427878Z","steps":["trace[1099658282] 'agreement among raft nodes before linearized reading' (duration: 177.200037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:37:03.427906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.971352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:895"} {"level":"warn","ts":"2026-07-01T11:37:03.427945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.341329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:37:03.427956Z","caller":"traceutil/trace.go:171","msg":"trace[1209214344] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17812; }","duration":"177.024933ms","start":"2026-07-01T11:37:03.250917Z","end":"2026-07-01T11:37:03.427942Z","steps":["trace[1209214344] 'agreement among raft nodes before linearized reading' (duration: 176.914441ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:37:03.427986Z","caller":"traceutil/trace.go:171","msg":"trace[1155517926] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:17812; }","duration":"177.421451ms","start":"2026-07-01T11:37:03.250555Z","end":"2026-07-01T11:37:03.427977Z","steps":["trace[1155517926] 'agreement among raft nodes before linearized reading' (duration: 177.36028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:37:03.428058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.266728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-07-01T11:37:03.428128Z","caller":"traceutil/trace.go:171","msg":"trace[1827393887] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:17812; }","duration":"177.34893ms","start":"2026-07-01T11:37:03.250767Z","end":"2026-07-01T11:37:03.428116Z","steps":["trace[1827393887] 'agreement among raft nodes before linearized reading' (duration: 177.129365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:37:03.428371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.668656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:37:03.428762Z","caller":"traceutil/trace.go:171","msg":"trace[900026849] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:17812; }","duration":"178.104265ms","start":"2026-07-01T11:37:03.250644Z","end":"2026-07-01T11:37:03.428749Z","steps":["trace[900026849] 'agreement among raft nodes before linearized reading' (duration: 177.660786ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:37:25.546583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15470} {"level":"info","ts":"2026-07-01T11:37:25.652896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15470,"took":"102.900679ms","hash":742990304,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":34156544,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T11:37:25.652952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":742990304,"revision":15470,"compact-revision":12950} {"level":"info","ts":"2026-07-01T11:40:19.943895Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:40:19.949479Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-01T11:40:19.949744Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-07-01T11:41:54.314792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.728959ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-01T11:41:54.314932Z","caller":"traceutil/trace.go:171","msg":"trace[774470578] linearizableReadLoop","detail":"{readStateIndex:20635; appliedIndex:20634; }","duration":"266.395058ms","start":"2026-07-01T11:41:54.048513Z","end":"2026-07-01T11:41:54.314908Z","steps":["trace[774470578] 'read index received' (duration: 40.601µs)","trace[774470578] 'applied index is now lower than readState.Index' (duration: 266.352147ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:41:54.315098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.579232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:41:54.315156Z","caller":"traceutil/trace.go:171","msg":"trace[502877573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19969; }","duration":"266.694394ms","start":"2026-07-01T11:41:54.048444Z","end":"2026-07-01T11:41:54.315139Z","steps":["trace[502877573] 'agreement among raft nodes before linearized reading' (duration: 266.586902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:41:54.315318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.727238ms","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-07-01T11:41:54.315428Z","caller":"traceutil/trace.go:171","msg":"trace[387632098] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19969; }","duration":"144.83688ms","start":"2026-07-01T11:41:54.17053Z","end":"2026-07-01T11:41:54.315367Z","steps":["trace[387632098] 'agreement among raft nodes before linearized reading' (duration: 144.547364ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:41:54.603101Z","caller":"traceutil/trace.go:171","msg":"trace[1641883534] transaction","detail":"{read_only:false; response_revision:19971; number_of_response:1; }","duration":"130.946428ms","start":"2026-07-01T11:41:54.472129Z","end":"2026-07-01T11:41:54.603076Z","steps":["trace[1641883534] 'process raft request' (duration: 130.791894ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:41:54.63563Z","caller":"traceutil/trace.go:171","msg":"trace[1098153926] transaction","detail":"{read_only:false; response_revision:19972; number_of_response:1; }","duration":"163.353216ms","start":"2026-07-01T11:41:54.472245Z","end":"2026-07-01T11:41:54.635598Z","steps":["trace[1098153926] 'process raft request' (duration: 163.186663ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:42:25.559052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18073} {"level":"info","ts":"2026-07-01T11:42:25.681206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18073,"took":"118.488886ms","hash":1350458784,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":29728768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T11:42:25.681265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1350458784,"revision":18073,"compact-revision":15470} {"level":"warn","ts":"2026-07-01T11:45:41.898303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.71379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-01T11:45:41.898405Z","caller":"traceutil/trace.go:171","msg":"trace[134583636] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:22378; }","duration":"118.869144ms","start":"2026-07-01T11:45:41.779514Z","end":"2026-07-01T11:45:41.898383Z","steps":["trace[134583636] 'range keys from in-memory index tree' (duration: 118.538067ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:47:25.566692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20211} {"level":"info","ts":"2026-07-01T11:47:25.685517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20211,"took":"115.193948ms","hash":3349829766,"current-db-size-bytes":58163200,"current-db-size":"58 MB","current-db-size-in-use-bytes":43986944,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-01T11:47:25.685586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3349829766,"revision":20211,"compact-revision":18073} {"level":"info","ts":"2026-07-01T11:49:13.730932Z","caller":"traceutil/trace.go:171","msg":"trace[98277990] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"131.835592ms","start":"2026-07-01T11:49:13.599077Z","end":"2026-07-01T11:49:13.730913Z","steps":["trace[98277990] 'process raft request' (duration: 131.553236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:14.005681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.923769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:14.005802Z","caller":"traceutil/trace.go:171","msg":"trace[1019762215] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"271.432962ms","start":"2026-07-01T11:49:13.734349Z","end":"2026-07-01T11:49:14.005782Z","steps":["trace[1019762215] 'process raft request' (duration: 121.283488ms)","trace[1019762215] 'compare' (duration: 149.46924ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:14.057262Z","caller":"traceutil/trace.go:171","msg":"trace[806884452] transaction","detail":"{read_only:false; response_revision:24498; number_of_response:1; }","duration":"322.272462ms","start":"2026-07-01T11:49:13.734957Z","end":"2026-07-01T11:49:14.057229Z","steps":["trace[806884452] 'process raft request' (duration: 322.029797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:14.05748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:13.734948Z","time spent":"322.414676ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:14.057584Z","caller":"traceutil/trace.go:171","msg":"trace[1963498825] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"322.087529ms","start":"2026-07-01T11:49:13.735467Z","end":"2026-07-01T11:49:14.057555Z","steps":["trace[1963498825] 'process raft request' (duration: 321.707251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:14.057786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:13.735436Z","time spent":"322.269233ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:14.057821Z","caller":"traceutil/trace.go:171","msg":"trace[1891118732] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"322.275923ms","start":"2026-07-01T11:49:13.735481Z","end":"2026-07-01T11:49:14.057757Z","steps":["trace[1891118732] 'process raft request' (duration: 322.015157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:14.058156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:13.735459Z","time spent":"322.498217ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:18.568666Z","caller":"traceutil/trace.go:171","msg":"trace[1812979267] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"116.352599ms","start":"2026-07-01T11:49:18.452281Z","end":"2026-07-01T11:49:18.568634Z","steps":["trace[1812979267] 'process raft request' (duration: 116.039232ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:18.793333Z","caller":"traceutil/trace.go:171","msg":"trace[945530636] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"174.699082ms","start":"2026-07-01T11:49:18.618594Z","end":"2026-07-01T11:49:18.793293Z","steps":["trace[945530636] 'process raft request' (duration: 174.432776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:19.044184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.541153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:19.044401Z","caller":"traceutil/trace.go:171","msg":"trace[630901804] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"162.369602ms","start":"2026-07-01T11:49:18.882012Z","end":"2026-07-01T11:49:19.044382Z","steps":["trace[630901804] 'process raft request' (duration: 162.30902ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:19.044362Z","caller":"traceutil/trace.go:171","msg":"trace[1382910310] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"411.760797ms","start":"2026-07-01T11:49:18.63256Z","end":"2026-07-01T11:49:19.044321Z","steps":["trace[1382910310] 'process raft request' (duration: 287.980459ms)","trace[1382910310] 'compare' (duration: 123.282688ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:19.044419Z","caller":"traceutil/trace.go:171","msg":"trace[1878939519] linearizableReadLoop","detail":"{readStateIndex:25324; appliedIndex:25322; }","duration":"304.019143ms","start":"2026-07-01T11:49:18.740352Z","end":"2026-07-01T11:49:19.044371Z","steps":["trace[1878939519] 'read index received' (duration: 52.791871ms)","trace[1878939519] 'applied index is now lower than readState.Index' (duration: 251.224752ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:19.044547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:18.632534Z","time spent":"411.92421ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:49:19.044688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.324619ms","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-07-01T11:49:19.044735Z","caller":"traceutil/trace.go:171","msg":"trace[24059804] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24534; }","duration":"304.421831ms","start":"2026-07-01T11:49:18.740298Z","end":"2026-07-01T11:49:19.04472Z","steps":["trace[24059804] 'agreement among raft nodes before linearized reading' (duration: 304.203057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:19.044776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:18.740275Z","time spent":"304.492382ms","remote":"127.0.0.1:46116","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-07-01T11:49:19.198783Z","caller":"traceutil/trace.go:171","msg":"trace[860478923] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"144.324756ms","start":"2026-07-01T11:49:19.054435Z","end":"2026-07-01T11:49:19.19876Z","steps":["trace[860478923] 'process raft request' (duration: 140.389386ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:19.749429Z","caller":"traceutil/trace.go:171","msg":"trace[733654264] linearizableReadLoop","detail":"{readStateIndex:25328; appliedIndex:25327; }","duration":"209.095139ms","start":"2026-07-01T11:49:19.540314Z","end":"2026-07-01T11:49:19.749409Z","steps":["trace[733654264] 'read index received' (duration: 208.930075ms)","trace[733654264] 'applied index is now lower than readState.Index' (duration: 163.444µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:19.749534Z","caller":"traceutil/trace.go:171","msg":"trace[1486268365] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"282.971877ms","start":"2026-07-01T11:49:19.466532Z","end":"2026-07-01T11:49:19.749504Z","steps":["trace[1486268365] 'process raft request' (duration: 282.727882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:19.749609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.267862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:49:19.749675Z","caller":"traceutil/trace.go:171","msg":"trace[172646648] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:24537; }","duration":"209.386705ms","start":"2026-07-01T11:49:19.540272Z","end":"2026-07-01T11:49:19.749659Z","steps":["trace[172646648] 'agreement among raft nodes before linearized reading' (duration: 209.242392ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:20.162361Z","caller":"traceutil/trace.go:171","msg":"trace[546006959] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"124.956804ms","start":"2026-07-01T11:49:20.037364Z","end":"2026-07-01T11:49:20.162321Z","steps":["trace[546006959] 'process raft request' (duration: 124.666578ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:20.366096Z","caller":"traceutil/trace.go:171","msg":"trace[764732587] linearizableReadLoop","detail":"{readStateIndex:25334; appliedIndex:25333; }","duration":"186.854328ms","start":"2026-07-01T11:49:20.179215Z","end":"2026-07-01T11:49:20.366069Z","steps":["trace[764732587] 'read index received' (duration: 135.653109ms)","trace[764732587] 'applied index is now lower than readState.Index' (duration: 51.200199ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:20.366225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.995731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:20.366198Z","caller":"traceutil/trace.go:171","msg":"trace[994395834] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"187.247666ms","start":"2026-07-01T11:49:20.178857Z","end":"2026-07-01T11:49:20.366105Z","steps":["trace[994395834] 'process raft request' (duration: 135.803482ms)","trace[994395834] 'compare' (duration: 51.221439ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:20.366298Z","caller":"traceutil/trace.go:171","msg":"trace[431698979] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24543; }","duration":"187.108373ms","start":"2026-07-01T11:49:20.179173Z","end":"2026-07-01T11:49:20.366281Z","steps":["trace[431698979] 'agreement among raft nodes before linearized reading' (duration: 186.988711ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:20.366304Z","caller":"traceutil/trace.go:171","msg":"trace[1621597083] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"185.905409ms","start":"2026-07-01T11:49:20.180367Z","end":"2026-07-01T11:49:20.366273Z","steps":["trace[1621597083] 'process raft request' (duration: 185.670225ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:20.745654Z","caller":"traceutil/trace.go:171","msg":"trace[1132484568] linearizableReadLoop","detail":"{readStateIndex:25336; appliedIndex:25335; }","duration":"140.253423ms","start":"2026-07-01T11:49:20.605377Z","end":"2026-07-01T11:49:20.74563Z","steps":["trace[1132484568] 'read index received' (duration: 140.066509ms)","trace[1132484568] 'applied index is now lower than readState.Index' (duration: 185.624µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:20.745679Z","caller":"traceutil/trace.go:171","msg":"trace[854219002] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"171.19677ms","start":"2026-07-01T11:49:20.574465Z","end":"2026-07-01T11:49:20.745662Z","steps":["trace[854219002] 'process raft request' (duration: 170.973336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:20.745943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.522009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:49:20.746057Z","caller":"traceutil/trace.go:171","msg":"trace[1547668405] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24545; }","duration":"140.711992ms","start":"2026-07-01T11:49:20.60532Z","end":"2026-07-01T11:49:20.746032Z","steps":["trace[1547668405] 'agreement among raft nodes before linearized reading' (duration: 140.408766ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:20.956834Z","caller":"traceutil/trace.go:171","msg":"trace[91757843] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"157.728838ms","start":"2026-07-01T11:49:20.799073Z","end":"2026-07-01T11:49:20.956802Z","steps":["trace[91757843] 'process raft request' (duration: 157.674487ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:20.956982Z","caller":"traceutil/trace.go:171","msg":"trace[1911898426] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"307.472373ms","start":"2026-07-01T11:49:20.649492Z","end":"2026-07-01T11:49:20.956964Z","steps":["trace[1911898426] 'process raft request' (duration: 246.499747ms)","trace[1911898426] 'compare' (duration: 60.467705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:20.957099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:20.64948Z","time spent":"307.545745ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:20.957195Z","caller":"traceutil/trace.go:171","msg":"trace[1669790794] linearizableReadLoop","detail":"{readStateIndex:25337; appliedIndex:25336; }","duration":"211.458427ms","start":"2026-07-01T11:49:20.745717Z","end":"2026-07-01T11:49:20.957175Z","steps":["trace[1669790794] 'read index received' (duration: 150.380909ms)","trace[1669790794] 'applied index is now lower than readState.Index' (duration: 61.074848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:20.957359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.952705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:20.957407Z","caller":"traceutil/trace.go:171","msg":"trace[1395129057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24547; }","duration":"316.033707ms","start":"2026-07-01T11:49:20.641361Z","end":"2026-07-01T11:49:20.957394Z","steps":["trace[1395129057] 'agreement among raft nodes before linearized reading' (duration: 315.938385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:20.957447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:20.641344Z","time spent":"316.093508ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T11:49:20.957615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.400001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:49:20.957658Z","caller":"traceutil/trace.go:171","msg":"trace[1093407868] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24547; }","duration":"341.461042ms","start":"2026-07-01T11:49:20.616187Z","end":"2026-07-01T11:49:20.957648Z","steps":["trace[1093407868] 'agreement among raft nodes before linearized reading' (duration: 341.34321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:20.957686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:20.616177Z","time spent":"341.501073ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":90,"response size":32,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:49:21.190215Z","caller":"traceutil/trace.go:171","msg":"trace[971248425] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"136.13486ms","start":"2026-07-01T11:49:21.054059Z","end":"2026-07-01T11:49:21.190194Z","steps":["trace[971248425] 'process raft request' (duration: 136.083279ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:21.190672Z","caller":"traceutil/trace.go:171","msg":"trace[1512875161] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"162.81701ms","start":"2026-07-01T11:49:21.027843Z","end":"2026-07-01T11:49:21.19066Z","steps":["trace[1512875161] 'process raft request' (duration: 157.664945ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:21.190821Z","caller":"traceutil/trace.go:171","msg":"trace[1347873176] linearizableReadLoop","detail":"{readStateIndex:25339; appliedIndex:25338; }","duration":"143.533479ms","start":"2026-07-01T11:49:21.047277Z","end":"2026-07-01T11:49:21.19081Z","steps":["trace[1347873176] 'read index received' (duration: 138.456556ms)","trace[1347873176] 'applied index is now lower than readState.Index' (duration: 5.075733ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:21.191105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.813514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:21.19115Z","caller":"traceutil/trace.go:171","msg":"trace[1933674382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24549; }","duration":"143.997809ms","start":"2026-07-01T11:49:21.047142Z","end":"2026-07-01T11:49:21.19114Z","steps":["trace[1933674382] 'agreement among raft nodes before linearized reading' (duration: 143.913217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:21.191375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.734681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T11:49:21.191411Z","caller":"traceutil/trace.go:171","msg":"trace[187685127] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24549; }","duration":"136.819664ms","start":"2026-07-01T11:49:21.054583Z","end":"2026-07-01T11:49:21.191403Z","steps":["trace[187685127] 'agreement among raft nodes before linearized reading' (duration: 136.695941ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:21.540918Z","caller":"traceutil/trace.go:171","msg":"trace[853819815] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"127.095646ms","start":"2026-07-01T11:49:21.413793Z","end":"2026-07-01T11:49:21.540889Z","steps":["trace[853819815] 'process raft request' (duration: 126.79457ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:22.005223Z","caller":"traceutil/trace.go:171","msg":"trace[1797371064] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"339.767137ms","start":"2026-07-01T11:49:21.665431Z","end":"2026-07-01T11:49:22.005198Z","steps":["trace[1797371064] 'process raft request' (duration: 339.574723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.005398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:21.665403Z","time spent":"339.89481ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:22.032057Z","caller":"traceutil/trace.go:171","msg":"trace[1670202872] linearizableReadLoop","detail":"{readStateIndex:25345; appliedIndex:25343; }","duration":"264.030862ms","start":"2026-07-01T11:49:21.768008Z","end":"2026-07-01T11:49:22.032039Z","steps":["trace[1670202872] 'read index received' (duration: 236.961794ms)","trace[1670202872] 'applied index is now lower than readState.Index' (duration: 27.068098ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:22.032107Z","caller":"traceutil/trace.go:171","msg":"trace[2005755469] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"278.005895ms","start":"2026-07-01T11:49:21.754065Z","end":"2026-07-01T11:49:22.032071Z","steps":["trace[2005755469] 'process raft request' (duration: 277.868352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.032233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.571176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:49:22.032266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.254336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-01T11:49:22.032291Z","caller":"traceutil/trace.go:171","msg":"trace[534924050] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:24554; }","duration":"130.665758ms","start":"2026-07-01T11:49:21.901607Z","end":"2026-07-01T11:49:22.032273Z","steps":["trace[534924050] 'agreement among raft nodes before linearized reading' (duration: 130.575456ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:22.032314Z","caller":"traceutil/trace.go:171","msg":"trace[750157001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24554; }","duration":"264.339429ms","start":"2026-07-01T11:49:21.767961Z","end":"2026-07-01T11:49:22.032301Z","steps":["trace[750157001] 'agreement among raft nodes before linearized reading' (duration: 264.174604ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:22.158257Z","caller":"traceutil/trace.go:171","msg":"trace[2093054909] linearizableReadLoop","detail":"{readStateIndex:25346; appliedIndex:25345; }","duration":"110.48408ms","start":"2026-07-01T11:49:22.047749Z","end":"2026-07-01T11:49:22.158233Z","steps":["trace[2093054909] 'read index received' (duration: 97.848144ms)","trace[2093054909] 'applied index is now lower than readState.Index' (duration: 12.635006ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:22.158327Z","caller":"traceutil/trace.go:171","msg":"trace[1039789751] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"120.552874ms","start":"2026-07-01T11:49:22.037748Z","end":"2026-07-01T11:49:22.158301Z","steps":["trace[1039789751] 'process raft request' (duration: 107.859117ms)","trace[1039789751] 'compare' (duration: 12.391051ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:22.158368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.599532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:22.158399Z","caller":"traceutil/trace.go:171","msg":"trace[1258247074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24555; }","duration":"110.678454ms","start":"2026-07-01T11:49:22.047709Z","end":"2026-07-01T11:49:22.158388Z","steps":["trace[1258247074] 'agreement among raft nodes before linearized reading' (duration: 110.604161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.481186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.266134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:22.481322Z","caller":"traceutil/trace.go:171","msg":"trace[57763240] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"110.667653ms","start":"2026-07-01T11:49:22.370643Z","end":"2026-07-01T11:49:22.481311Z","steps":["trace[57763240] 'process raft request' (duration: 110.625953ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:22.481435Z","caller":"traceutil/trace.go:171","msg":"trace[7138635] linearizableReadLoop","detail":"{readStateIndex:25347; appliedIndex:25346; }","duration":"304.597465ms","start":"2026-07-01T11:49:22.176797Z","end":"2026-07-01T11:49:22.481394Z","steps":["trace[7138635] 'read index received' (duration: 126.044095ms)","trace[7138635] 'applied index is now lower than readState.Index' (duration: 178.55163ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:22.481542Z","caller":"traceutil/trace.go:171","msg":"trace[378836066] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"310.497784ms","start":"2026-07-01T11:49:22.171031Z","end":"2026-07-01T11:49:22.481529Z","steps":["trace[378836066] 'process raft request' (duration: 131.827492ms)","trace[378836066] 'compare' (duration: 178.155521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:22.481638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.171014Z","time spent":"310.553096ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:49:22.481778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.976413ms","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-07-01T11:49:22.481806Z","caller":"traceutil/trace.go:171","msg":"trace[2048143502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24557; }","duration":"305.025173ms","start":"2026-07-01T11:49:22.176773Z","end":"2026-07-01T11:49:22.481798Z","steps":["trace[2048143502] 'agreement among raft nodes before linearized reading' (duration: 304.930821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.481827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.176764Z","time spent":"305.057404ms","remote":"127.0.0.1:46116","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-07-01T11:49:22.748047Z","caller":"traceutil/trace.go:171","msg":"trace[50351001] linearizableReadLoop","detail":"{readStateIndex:25349; appliedIndex:25348; }","duration":"266.531053ms","start":"2026-07-01T11:49:22.481494Z","end":"2026-07-01T11:49:22.748025Z","steps":["trace[50351001] 'read index received' (duration: 198.502464ms)","trace[50351001] 'applied index is now lower than readState.Index' (duration: 68.027649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:22.748244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.894622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T11:49:22.748275Z","caller":"traceutil/trace.go:171","msg":"trace[302264313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24558; }","duration":"567.947434ms","start":"2026-07-01T11:49:22.180319Z","end":"2026-07-01T11:49:22.748266Z","steps":["trace[302264313] 'agreement among raft nodes before linearized reading' (duration: 567.78908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.748276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.270888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-01T11:49:22.748306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.794281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-07-01T11:49:22.748346Z","caller":"traceutil/trace.go:171","msg":"trace[825792759] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"376.260037ms","start":"2026-07-01T11:49:22.372076Z","end":"2026-07-01T11:49:22.748336Z","steps":["trace[825792759] 'process raft request' (duration: 307.82228ms)","trace[825792759] 'compare' (duration: 67.871206ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:22.748347Z","caller":"traceutil/trace.go:171","msg":"trace[167556019] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24558; }","duration":"418.364321ms","start":"2026-07-01T11:49:22.329965Z","end":"2026-07-01T11:49:22.748329Z","steps":["trace[167556019] 'agreement among raft nodes before linearized reading' (duration: 418.112896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.748299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.180314Z","time spent":"567.979774ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T11:49:22.748364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.897612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-01T11:49:22.748385Z","caller":"traceutil/trace.go:171","msg":"trace[1324921174] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:24558; }","duration":"423.906203ms","start":"2026-07-01T11:49:22.324461Z","end":"2026-07-01T11:49:22.748367Z","steps":["trace[1324921174] 'agreement among raft nodes before linearized reading' (duration: 423.643467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.748395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.329958Z","time spent":"418.426532ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-01T11:49:22.748418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.372044Z","time spent":"376.327928ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:49:22.748434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.324443Z","time spent":"423.982014ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1590,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"info","ts":"2026-07-01T11:49:22.748398Z","caller":"traceutil/trace.go:171","msg":"trace[1724015114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24558; }","duration":"567.932893ms","start":"2026-07-01T11:49:22.180456Z","end":"2026-07-01T11:49:22.748389Z","steps":["trace[1724015114] 'agreement among raft nodes before linearized reading' (duration: 567.824701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:22.748484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.180437Z","time spent":"568.037695ms","remote":"127.0.0.1:46116","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-07-01T11:49:22.75029Z","caller":"traceutil/trace.go:171","msg":"trace[1980941703] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"263.592872ms","start":"2026-07-01T11:49:22.486671Z","end":"2026-07-01T11:49:22.750264Z","steps":["trace[1980941703] 'process raft request' (duration: 263.46112ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:22.997326Z","caller":"traceutil/trace.go:171","msg":"trace[920735107] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"240.273801ms","start":"2026-07-01T11:49:22.75703Z","end":"2026-07-01T11:49:22.997304Z","steps":["trace[920735107] 'process raft request' (duration: 229.090355ms)","trace[920735107] 'compare' (duration: 10.925001ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:22.997502Z","caller":"traceutil/trace.go:171","msg":"trace[1262696570] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"230.042963ms","start":"2026-07-01T11:49:22.767436Z","end":"2026-07-01T11:49:22.997479Z","steps":["trace[1262696570] 'process raft request' (duration: 229.802028ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:23.28332Z","caller":"traceutil/trace.go:171","msg":"trace[377005328] linearizableReadLoop","detail":"{readStateIndex:25355; appliedIndex:25354; }","duration":"234.608977ms","start":"2026-07-01T11:49:23.048688Z","end":"2026-07-01T11:49:23.283297Z","steps":["trace[377005328] 'read index received' (duration: 215.425617ms)","trace[377005328] 'applied index is now lower than readState.Index' (duration: 19.18182ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:23.283383Z","caller":"traceutil/trace.go:171","msg":"trace[227029701] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"314.862342ms","start":"2026-07-01T11:49:22.968477Z","end":"2026-07-01T11:49:23.283339Z","steps":["trace[227029701] 'process raft request' (duration: 295.52662ms)","trace[227029701] 'compare' (duration: 19.181319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:23.283513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.819381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:49:23.283549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.570687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:49:23.283552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:22.968458Z","time spent":"315.003055ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:23.283599Z","caller":"traceutil/trace.go:171","msg":"trace[356352825] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:24566; }","duration":"203.670129ms","start":"2026-07-01T11:49:23.079913Z","end":"2026-07-01T11:49:23.283583Z","steps":["trace[356352825] 'agreement among raft nodes before linearized reading' (duration: 203.546987ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:23.283566Z","caller":"traceutil/trace.go:171","msg":"trace[1508344603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24566; }","duration":"234.880572ms","start":"2026-07-01T11:49:23.048672Z","end":"2026-07-01T11:49:23.283552Z","steps":["trace[1508344603] 'agreement among raft nodes before linearized reading' (duration: 234.729189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:23.552527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.794322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:23.552665Z","caller":"traceutil/trace.go:171","msg":"trace[920454897] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"264.348789ms","start":"2026-07-01T11:49:23.288292Z","end":"2026-07-01T11:49:23.552641Z","steps":["trace[920454897] 'process raft request' (duration: 128.355822ms)","trace[920454897] 'compare' (duration: 135.629249ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:23.736085Z","caller":"traceutil/trace.go:171","msg":"trace[540234331] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"172.331833ms","start":"2026-07-01T11:49:23.563697Z","end":"2026-07-01T11:49:23.736029Z","steps":["trace[540234331] 'process raft request' (duration: 172.062018ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:23.855067Z","caller":"traceutil/trace.go:171","msg":"trace[1424674607] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"116.630124ms","start":"2026-07-01T11:49:23.738414Z","end":"2026-07-01T11:49:23.855044Z","steps":["trace[1424674607] 'process raft request' (duration: 104.59914ms)","trace[1424674607] 'compare' (duration: 11.931022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:24.118381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.303625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:24.118645Z","caller":"traceutil/trace.go:171","msg":"trace[430205881] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"258.675783ms","start":"2026-07-01T11:49:23.859924Z","end":"2026-07-01T11:49:24.118599Z","steps":["trace[430205881] 'process raft request' (duration: 111.041001ms)","trace[430205881] 'compare' (duration: 147.169693ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:24.205498Z","caller":"traceutil/trace.go:171","msg":"trace[1926477056] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"169.137489ms","start":"2026-07-01T11:49:24.036333Z","end":"2026-07-01T11:49:24.205471Z","steps":["trace[1926477056] 'process raft request' (duration: 168.946075ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:24.345923Z","caller":"traceutil/trace.go:171","msg":"trace[990503037] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"135.964916ms","start":"2026-07-01T11:49:24.209888Z","end":"2026-07-01T11:49:24.345853Z","steps":["trace[990503037] 'process raft request' (duration: 135.601468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:24.634459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.927727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T11:49:24.634548Z","caller":"traceutil/trace.go:171","msg":"trace[645646562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24573; }","duration":"148.082901ms","start":"2026-07-01T11:49:24.486444Z","end":"2026-07-01T11:49:24.634527Z","steps":["trace[645646562] 'range keys from in-memory index tree' (duration: 147.714183ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:24.634897Z","caller":"traceutil/trace.go:171","msg":"trace[688855865] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"147.518431ms","start":"2026-07-01T11:49:24.48735Z","end":"2026-07-01T11:49:24.634868Z","steps":["trace[688855865] 'process raft request' (duration: 138.350825ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:24.97338Z","caller":"traceutil/trace.go:171","msg":"trace[86133011] linearizableReadLoop","detail":"{readStateIndex:25368; appliedIndex:25367; }","duration":"218.912067ms","start":"2026-07-01T11:49:24.754435Z","end":"2026-07-01T11:49:24.973347Z","steps":["trace[86133011] 'read index received' (duration: 128.571276ms)","trace[86133011] 'applied index is now lower than readState.Index' (duration: 90.339701ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:24.973518Z","caller":"traceutil/trace.go:171","msg":"trace[915396200] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"220.506079ms","start":"2026-07-01T11:49:24.752981Z","end":"2026-07-01T11:49:24.973487Z","steps":["trace[915396200] 'process raft request' (duration: 130.007515ms)","trace[915396200] 'compare' (duration: 90.246639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:24.973704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.252294ms","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-07-01T11:49:24.973765Z","caller":"traceutil/trace.go:171","msg":"trace[250196343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24576; }","duration":"219.344018ms","start":"2026-07-01T11:49:24.754408Z","end":"2026-07-01T11:49:24.973752Z","steps":["trace[250196343] 'agreement among raft nodes before linearized reading' (duration: 219.162593ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:24.977038Z","caller":"traceutil/trace.go:171","msg":"trace[1266300351] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"207.66428ms","start":"2026-07-01T11:49:24.769351Z","end":"2026-07-01T11:49:24.977016Z","steps":["trace[1266300351] 'process raft request' (duration: 207.470816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:24.977352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.615981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-01T11:49:24.977432Z","caller":"traceutil/trace.go:171","msg":"trace[701915379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24577; }","duration":"179.704643ms","start":"2026-07-01T11:49:24.797709Z","end":"2026-07-01T11:49:24.977414Z","steps":["trace[701915379] 'agreement among raft nodes before linearized reading' (duration: 179.276324ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:25.272508Z","caller":"traceutil/trace.go:171","msg":"trace[1400824625] linearizableReadLoop","detail":"{readStateIndex:25371; appliedIndex:25370; }","duration":"271.555515ms","start":"2026-07-01T11:49:25.000925Z","end":"2026-07-01T11:49:25.27248Z","steps":["trace[1400824625] 'read index received' (duration: 251.216592ms)","trace[1400824625] 'applied index is now lower than readState.Index' (duration: 20.337653ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:25.272803Z","caller":"traceutil/trace.go:171","msg":"trace[1670341928] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"292.109491ms","start":"2026-07-01T11:49:24.98068Z","end":"2026-07-01T11:49:25.27279Z","steps":["trace[1670341928] 'process raft request' (duration: 271.560224ms)","trace[1670341928] 'compare' (duration: 19.761741ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:25.272988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.204865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:49:25.27302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.509107ms","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-07-01T11:49:25.273067Z","caller":"traceutil/trace.go:171","msg":"trace[1583897661] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24579; }","duration":"211.600219ms","start":"2026-07-01T11:49:25.061455Z","end":"2026-07-01T11:49:25.273056Z","steps":["trace[1583897661] 'agreement among raft nodes before linearized reading' (duration: 211.507137ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:25.273079Z","caller":"traceutil/trace.go:171","msg":"trace[2116824310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24579; }","duration":"224.344017ms","start":"2026-07-01T11:49:25.048709Z","end":"2026-07-01T11:49:25.273053Z","steps":["trace[2116824310] 'agreement among raft nodes before linearized reading' (duration: 224.173704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:25.273061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.122036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T11:49:25.273193Z","caller":"traceutil/trace.go:171","msg":"trace[828765202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24579; }","duration":"272.28839ms","start":"2026-07-01T11:49:25.000887Z","end":"2026-07-01T11:49:25.273176Z","steps":["trace[828765202] 'agreement among raft nodes before linearized reading' (duration: 272.021274ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:25.425841Z","caller":"traceutil/trace.go:171","msg":"trace[1458760311] transaction","detail":"{read_only:false; response_revision:24582; number_of_response:1; }","duration":"136.880385ms","start":"2026-07-01T11:49:25.288937Z","end":"2026-07-01T11:49:25.425817Z","steps":["trace[1458760311] 'process raft request' (duration: 136.824644ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:25.425884Z","caller":"traceutil/trace.go:171","msg":"trace[842658371] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"148.706784ms","start":"2026-07-01T11:49:25.277152Z","end":"2026-07-01T11:49:25.425859Z","steps":["trace[842658371] 'process raft request' (duration: 134.098538ms)","trace[842658371] 'compare' (duration: 14.29073ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:25.425917Z","caller":"traceutil/trace.go:171","msg":"trace[239902986] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"137.882765ms","start":"2026-07-01T11:49:25.288014Z","end":"2026-07-01T11:49:25.425897Z","steps":["trace[239902986] 'process raft request' (duration: 137.699611ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:25.426262Z","caller":"traceutil/trace.go:171","msg":"trace[2090799170] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"136.084609ms","start":"2026-07-01T11:49:25.290167Z","end":"2026-07-01T11:49:25.426252Z","steps":["trace[2090799170] 'process raft request' (duration: 135.63103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:29.681272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.674081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-07-01T11:49:29.681381Z","caller":"traceutil/trace.go:171","msg":"trace[388614684] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:24608; }","duration":"357.803943ms","start":"2026-07-01T11:49:29.323548Z","end":"2026-07-01T11:49:29.681352Z","steps":["trace[388614684] 'range keys from in-memory index tree' (duration: 357.468647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:29.681434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:29.32348Z","time spent":"357.938386ms","remote":"127.0.0.1:46024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"info","ts":"2026-07-01T11:49:29.681601Z","caller":"traceutil/trace.go:171","msg":"trace[1641881639] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"243.489546ms","start":"2026-07-01T11:49:29.438073Z","end":"2026-07-01T11:49:29.681563Z","steps":["trace[1641881639] 'process raft request' (duration: 145.649853ms)","trace[1641881639] 'compare' (duration: 97.547558ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:29.886057Z","caller":"traceutil/trace.go:171","msg":"trace[69557458] linearizableReadLoop","detail":"{readStateIndex:25403; appliedIndex:25401; }","duration":"447.294208ms","start":"2026-07-01T11:49:29.438735Z","end":"2026-07-01T11:49:29.88603Z","steps":["trace[69557458] 'read index received' (duration: 145.121322ms)","trace[69557458] 'applied index is now lower than readState.Index' (duration: 302.171926ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:29.88614Z","caller":"traceutil/trace.go:171","msg":"trace[1694360423] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"447.486551ms","start":"2026-07-01T11:49:29.438614Z","end":"2026-07-01T11:49:29.886101Z","steps":["trace[1694360423] 'process raft request' (duration: 447.229696ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:29.886161Z","caller":"traceutil/trace.go:171","msg":"trace[1716130170] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"446.494221ms","start":"2026-07-01T11:49:29.439643Z","end":"2026-07-01T11:49:29.886138Z","steps":["trace[1716130170] 'process raft request' (duration: 446.341168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:29.886289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.369108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-01T11:49:29.886299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:29.438594Z","time spent":"447.619513ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:29.886326Z","caller":"traceutil/trace.go:171","msg":"trace[1886780056] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24611; }","duration":"298.449889ms","start":"2026-07-01T11:49:29.587863Z","end":"2026-07-01T11:49:29.886313Z","steps":["trace[1886780056] 'agreement among raft nodes before linearized reading' (duration: 298.300786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:29.88632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.569056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-cbe37e0a-b470-4133-9950-ecc3a61eb58f\" ","response":"range_response_count:1 size:1316"} {"level":"warn","ts":"2026-07-01T11:49:29.886326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:29.439636Z","time spent":"446.590073ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:29.886372Z","caller":"traceutil/trace.go:171","msg":"trace[496053221] range","detail":"{range_begin:/registry/persistentvolumes/pvc-cbe37e0a-b470-4133-9950-ecc3a61eb58f; range_end:; response_count:1; response_revision:24611; }","duration":"201.671198ms","start":"2026-07-01T11:49:29.684683Z","end":"2026-07-01T11:49:29.886355Z","steps":["trace[496053221] 'agreement among raft nodes before linearized reading' (duration: 201.527885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:29.886372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.618754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-01T11:49:29.886518Z","caller":"traceutil/trace.go:171","msg":"trace[853115609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24611; }","duration":"447.826048ms","start":"2026-07-01T11:49:29.438677Z","end":"2026-07-01T11:49:29.886503Z","steps":["trace[853115609] 'agreement among raft nodes before linearized reading' (duration: 447.484921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:29.886565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:29.438647Z","time spent":"447.904289ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T11:49:30.19866Z","caller":"traceutil/trace.go:171","msg":"trace[1642605696] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"306.28795ms","start":"2026-07-01T11:49:29.892344Z","end":"2026-07-01T11:49:30.198632Z","steps":["trace[1642605696] 'process raft request' (duration: 304.79584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:30.198803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:49:29.892313Z","time spent":"306.417332ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:49:30.500486Z","caller":"traceutil/trace.go:171","msg":"trace[1285035890] linearizableReadLoop","detail":"{readStateIndex:25407; appliedIndex:25406; }","duration":"130.942764ms","start":"2026-07-01T11:49:30.369516Z","end":"2026-07-01T11:49:30.500458Z","steps":["trace[1285035890] 'read index received' (duration: 117.938901ms)","trace[1285035890] 'applied index is now lower than readState.Index' (duration: 13.002553ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:30.500632Z","caller":"traceutil/trace.go:171","msg":"trace[410305585] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"277.593187ms","start":"2026-07-01T11:49:30.223Z","end":"2026-07-01T11:49:30.500593Z","steps":["trace[410305585] 'process raft request' (duration: 264.459912ms)","trace[410305585] 'compare' (duration: 12.647476ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:30.500895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.163832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:30.50101Z","caller":"traceutil/trace.go:171","msg":"trace[675847148] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24614; }","duration":"104.316705ms","start":"2026-07-01T11:49:30.396672Z","end":"2026-07-01T11:49:30.500989Z","steps":["trace[675847148] 'agreement among raft nodes before linearized reading' (duration: 104.132071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:30.501273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.72867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-01T11:49:30.501372Z","caller":"traceutil/trace.go:171","msg":"trace[1058101521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24614; }","duration":"131.879754ms","start":"2026-07-01T11:49:30.369473Z","end":"2026-07-01T11:49:30.501353Z","steps":["trace[1058101521] 'agreement among raft nodes before linearized reading' (duration: 131.595688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:30.750032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.043421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:30.750099Z","caller":"traceutil/trace.go:171","msg":"trace[352804960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24615; }","duration":"110.164553ms","start":"2026-07-01T11:49:30.639917Z","end":"2026-07-01T11:49:30.750082Z","steps":["trace[352804960] 'range keys from in-memory index tree' (duration: 109.947178ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:36.344964Z","caller":"traceutil/trace.go:171","msg":"trace[2027834378] linearizableReadLoop","detail":"{readStateIndex:25509; appliedIndex:25508; }","duration":"124.041605ms","start":"2026-07-01T11:49:36.220895Z","end":"2026-07-01T11:49:36.344936Z","steps":["trace[2027834378] 'read index received' (duration: 123.77394ms)","trace[2027834378] 'applied index is now lower than readState.Index' (duration: 266.445µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:36.345245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.328241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T11:49:36.345283Z","caller":"traceutil/trace.go:171","msg":"trace[1552887073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24714; }","duration":"124.421933ms","start":"2026-07-01T11:49:36.220852Z","end":"2026-07-01T11:49:36.345274Z","steps":["trace[1552887073] 'agreement among raft nodes before linearized reading' (duration: 124.196558ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:36.345443Z","caller":"traceutil/trace.go:171","msg":"trace[430335647] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"135.880065ms","start":"2026-07-01T11:49:36.209537Z","end":"2026-07-01T11:49:36.345418Z","steps":["trace[430335647] 'process raft request' (duration: 135.221052ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:36.625116Z","caller":"traceutil/trace.go:171","msg":"trace[1854014805] linearizableReadLoop","detail":"{readStateIndex:25510; appliedIndex:25509; }","duration":"256.121033ms","start":"2026-07-01T11:49:36.36896Z","end":"2026-07-01T11:49:36.625081Z","steps":["trace[1854014805] 'read index received' (duration: 227.131575ms)","trace[1854014805] 'applied index is now lower than readState.Index' (duration: 28.988428ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:36.625189Z","caller":"traceutil/trace.go:171","msg":"trace[1423241407] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"275.305651ms","start":"2026-07-01T11:49:36.349844Z","end":"2026-07-01T11:49:36.62515Z","steps":["trace[1423241407] 'process raft request' (duration: 246.235152ms)","trace[1423241407] 'compare' (duration: 28.755013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:36.625357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.379678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d5b7829-d191-4ca2-b648-911709ade845\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-01T11:49:36.625393Z","caller":"traceutil/trace.go:171","msg":"trace[848008722] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d5b7829-d191-4ca2-b648-911709ade845; range_end:; response_count:1; response_revision:24715; }","duration":"256.48212ms","start":"2026-07-01T11:49:36.368898Z","end":"2026-07-01T11:49:36.62538Z","steps":["trace[848008722] 'agreement among raft nodes before linearized reading' (duration: 256.322347ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:36.719717Z","caller":"traceutil/trace.go:171","msg":"trace[2128797779] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"202.815971ms","start":"2026-07-01T11:49:36.516867Z","end":"2026-07-01T11:49:36.719683Z","steps":["trace[2128797779] 'process raft request' (duration: 202.664858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:36.719781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.902505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-01T11:49:36.719841Z","caller":"traceutil/trace.go:171","msg":"trace[450510226] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24716; }","duration":"174.004577ms","start":"2026-07-01T11:49:36.545822Z","end":"2026-07-01T11:49:36.719826Z","steps":["trace[450510226] 'agreement among raft nodes before linearized reading' (duration: 173.824803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:36.719909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.983478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T11:49:36.720014Z","caller":"traceutil/trace.go:171","msg":"trace[1646210999] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24716; }","duration":"140.148941ms","start":"2026-07-01T11:49:36.579844Z","end":"2026-07-01T11:49:36.719993Z","steps":["trace[1646210999] 'agreement among raft nodes before linearized reading' (duration: 139.846244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:36.720141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.785066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-01T11:49:36.720195Z","caller":"traceutil/trace.go:171","msg":"trace[107958542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24716; }","duration":"148.879229ms","start":"2026-07-01T11:49:36.571303Z","end":"2026-07-01T11:49:36.720183Z","steps":["trace[107958542] 'agreement among raft nodes before linearized reading' (duration: 148.719966ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:36.942012Z","caller":"traceutil/trace.go:171","msg":"trace[496122049] linearizableReadLoop","detail":"{readStateIndex:25512; appliedIndex:25511; }","duration":"218.126462ms","start":"2026-07-01T11:49:36.723856Z","end":"2026-07-01T11:49:36.941983Z","steps":["trace[496122049] 'read index received' (duration: 218.106101ms)","trace[496122049] 'applied index is now lower than readState.Index' (duration: 19.031µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:36.942027Z","caller":"traceutil/trace.go:171","msg":"trace[235843383] transaction","detail":"{read_only:false; response_revision:24717; number_of_response:1; }","duration":"218.647922ms","start":"2026-07-01T11:49:36.723353Z","end":"2026-07-01T11:49:36.942001Z","steps":["trace[235843383] 'process raft request' (duration: 218.295995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:36.94222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.350156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T11:49:36.94227Z","caller":"traceutil/trace.go:171","msg":"trace[1395840163] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24717; }","duration":"218.441148ms","start":"2026-07-01T11:49:36.723817Z","end":"2026-07-01T11:49:36.942258Z","steps":["trace[1395840163] 'agreement among raft nodes before linearized reading' (duration: 218.272695ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:36.988506Z","caller":"traceutil/trace.go:171","msg":"trace[1175791985] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"264.019752ms","start":"2026-07-01T11:49:36.724458Z","end":"2026-07-01T11:49:36.988478Z","steps":["trace[1175791985] 'process raft request' (duration: 263.826378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:36.988955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.645132ms","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-07-01T11:49:36.989045Z","caller":"traceutil/trace.go:171","msg":"trace[1956608345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24719; }","duration":"215.767644ms","start":"2026-07-01T11:49:36.773256Z","end":"2026-07-01T11:49:36.989024Z","steps":["trace[1956608345] 'agreement among raft nodes before linearized reading' (duration: 215.460397ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:36.989049Z","caller":"traceutil/trace.go:171","msg":"trace[1841134103] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"220.464369ms","start":"2026-07-01T11:49:36.768541Z","end":"2026-07-01T11:49:36.989005Z","steps":["trace[1841134103] 'process raft request' (duration: 219.894277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.288486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.840981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:37.28873Z","caller":"traceutil/trace.go:171","msg":"trace[25537730] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"295.266166ms","start":"2026-07-01T11:49:36.993447Z","end":"2026-07-01T11:49:37.288713Z","steps":["trace[25537730] 'process raft request' (duration: 127.117577ms)","trace[25537730] 'compare' (duration: 167.470584ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:37.288884Z","caller":"traceutil/trace.go:171","msg":"trace[1506159519] linearizableReadLoop","detail":"{readStateIndex:25517; appliedIndex:25514; }","duration":"262.772057ms","start":"2026-07-01T11:49:37.0261Z","end":"2026-07-01T11:49:37.288872Z","steps":["trace[1506159519] 'read index received' (duration: 94.371243ms)","trace[1506159519] 'applied index is now lower than readState.Index' (duration: 168.399904ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:37.289006Z","caller":"traceutil/trace.go:171","msg":"trace[229625405] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"272.750799ms","start":"2026-07-01T11:49:37.016247Z","end":"2026-07-01T11:49:37.288997Z","steps":["trace[229625405] 'process raft request' (duration: 272.559676ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:37.288993Z","caller":"traceutil/trace.go:171","msg":"trace[143678786] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"282.134809ms","start":"2026-07-01T11:49:37.006811Z","end":"2026-07-01T11:49:37.288946Z","steps":["trace[143678786] 'process raft request' (duration: 281.786922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.289424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.213129ms","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-07-01T11:49:37.28946Z","caller":"traceutil/trace.go:171","msg":"trace[2090776367] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24722; }","duration":"241.312441ms","start":"2026-07-01T11:49:37.048137Z","end":"2026-07-01T11:49:37.289449Z","steps":["trace[2090776367] 'agreement among raft nodes before linearized reading' (duration: 241.167208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.289447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.312797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d5b7829-d191-4ca2-b648-911709ade845\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-01T11:49:37.289548Z","caller":"traceutil/trace.go:171","msg":"trace[905809466] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d5b7829-d191-4ca2-b648-911709ade845; range_end:; response_count:1; response_revision:24722; }","duration":"263.465241ms","start":"2026-07-01T11:49:37.026053Z","end":"2026-07-01T11:49:37.289518Z","steps":["trace[905809466] 'agreement among raft nodes before linearized reading' (duration: 263.185515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.289541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.560176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:49:37.289552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.143845ms","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-07-01T11:49:37.289668Z","caller":"traceutil/trace.go:171","msg":"trace[399345371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24722; }","duration":"240.72297ms","start":"2026-07-01T11:49:37.04891Z","end":"2026-07-01T11:49:37.289633Z","steps":["trace[399345371] 'agreement among raft nodes before linearized reading' (duration: 240.422064ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:37.289752Z","caller":"traceutil/trace.go:171","msg":"trace[1361347716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24722; }","duration":"227.327228ms","start":"2026-07-01T11:49:37.062355Z","end":"2026-07-01T11:49:37.289682Z","steps":["trace[1361347716] 'agreement among raft nodes before linearized reading' (duration: 226.963281ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:37.484454Z","caller":"traceutil/trace.go:171","msg":"trace[990967657] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"190.034991ms","start":"2026-07-01T11:49:37.294393Z","end":"2026-07-01T11:49:37.484428Z","steps":["trace[990967657] 'process raft request' (duration: 189.872348ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:37.484679Z","caller":"traceutil/trace.go:171","msg":"trace[1860515174] linearizableReadLoop","detail":"{readStateIndex:25518; appliedIndex:25518; }","duration":"189.39257ms","start":"2026-07-01T11:49:37.295261Z","end":"2026-07-01T11:49:37.484653Z","steps":["trace[1860515174] 'read index received' (duration: 189.38402ms)","trace[1860515174] 'applied index is now lower than readState.Index' (duration: 6.73µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:37.484952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.654935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T11:49:37.485015Z","caller":"traceutil/trace.go:171","msg":"trace[606638370] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24723; }","duration":"189.756537ms","start":"2026-07-01T11:49:37.295245Z","end":"2026-07-01T11:49:37.485002Z","steps":["trace[606638370] 'agreement among raft nodes before linearized reading' (duration: 189.499812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.51831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.110394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-07-01T11:49:37.51835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.927547ms","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-07-01T11:49:37.518422Z","caller":"traceutil/trace.go:171","msg":"trace[1064081874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24724; }","duration":"179.038399ms","start":"2026-07-01T11:49:37.339369Z","end":"2026-07-01T11:49:37.518407Z","steps":["trace[1064081874] 'agreement among raft nodes before linearized reading' (duration: 178.859464ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:37.51843Z","caller":"traceutil/trace.go:171","msg":"trace[62582061] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24724; }","duration":"152.330269ms","start":"2026-07-01T11:49:37.366073Z","end":"2026-07-01T11:49:37.518403Z","steps":["trace[62582061] 'agreement among raft nodes before linearized reading' (duration: 151.962511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.518684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.704153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:37.518717Z","caller":"traceutil/trace.go:171","msg":"trace[1220270317] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24724; }","duration":"104.789675ms","start":"2026-07-01T11:49:37.413918Z","end":"2026-07-01T11:49:37.518708Z","steps":["trace[1220270317] 'agreement among raft nodes before linearized reading' (duration: 104.731824ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:37.518707Z","caller":"traceutil/trace.go:171","msg":"trace[1541259722] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"222.089523ms","start":"2026-07-01T11:49:37.296588Z","end":"2026-07-01T11:49:37.518677Z","steps":["trace[1541259722] 'process raft request' (duration: 221.276806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.518773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.151156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:37.518821Z","caller":"traceutil/trace.go:171","msg":"trace[649894937] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24724; }","duration":"192.247637ms","start":"2026-07-01T11:49:37.326561Z","end":"2026-07-01T11:49:37.518808Z","steps":["trace[649894937] 'agreement among raft nodes before linearized reading' (duration: 192.142285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:37.518847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.094191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-07-01T11:49:37.518912Z","caller":"traceutil/trace.go:171","msg":"trace[359918873] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24724; }","duration":"144.206744ms","start":"2026-07-01T11:49:37.37469Z","end":"2026-07-01T11:49:37.518897Z","steps":["trace[359918873] 'agreement among raft nodes before linearized reading' (duration: 143.989109ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:39.44332Z","caller":"traceutil/trace.go:171","msg":"trace[499673567] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"149.025972ms","start":"2026-07-01T11:49:39.294275Z","end":"2026-07-01T11:49:39.443301Z","steps":["trace[499673567] 'process raft request' (duration: 148.91757ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:39.451968Z","caller":"traceutil/trace.go:171","msg":"trace[373996190] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"155.577125ms","start":"2026-07-01T11:49:39.296363Z","end":"2026-07-01T11:49:39.45194Z","steps":["trace[373996190] 'process raft request' (duration: 155.521484ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:39.452005Z","caller":"traceutil/trace.go:171","msg":"trace[329652662] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"156.31618ms","start":"2026-07-01T11:49:39.295661Z","end":"2026-07-01T11:49:39.451977Z","steps":["trace[329652662] 'process raft request' (duration: 156.060515ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:42.62855Z","caller":"traceutil/trace.go:171","msg":"trace[1680159754] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"111.280776ms","start":"2026-07-01T11:49:42.517243Z","end":"2026-07-01T11:49:42.628524Z","steps":["trace[1680159754] 'process raft request' (duration: 110.52826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:42.916101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.132205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-01T11:49:42.916194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.163115ms","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-07-01T11:49:42.916153Z","caller":"traceutil/trace.go:171","msg":"trace[713210848] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"178.205371ms","start":"2026-07-01T11:49:42.737916Z","end":"2026-07-01T11:49:42.916122Z","steps":["trace[713210848] 'process raft request' (duration: 94.761781ms)","trace[713210848] 'compare' (duration: 83.280127ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:42.916221Z","caller":"traceutil/trace.go:171","msg":"trace[244090713] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24783; }","duration":"157.220987ms","start":"2026-07-01T11:49:42.758992Z","end":"2026-07-01T11:49:42.916213Z","steps":["trace[244090713] 'agreement among raft nodes before linearized reading' (duration: 157.169606ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:42.916199Z","caller":"traceutil/trace.go:171","msg":"trace[601550446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24782; }","duration":"270.256548ms","start":"2026-07-01T11:49:42.645914Z","end":"2026-07-01T11:49:42.91617Z","steps":["trace[601550446] 'range keys from in-memory index tree' (duration: 269.948572ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:42.916133Z","caller":"traceutil/trace.go:171","msg":"trace[1707698452] linearizableReadLoop","detail":"{readStateIndex:25579; appliedIndex:25578; }","duration":"157.083774ms","start":"2026-07-01T11:49:42.759032Z","end":"2026-07-01T11:49:42.916116Z","steps":["trace[1707698452] 'read index received' (duration: 73.520651ms)","trace[1707698452] 'applied index is now lower than readState.Index' (duration: 83.562493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:48.964636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.680743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:48.964714Z","caller":"traceutil/trace.go:171","msg":"trace[443881994] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"264.756928ms","start":"2026-07-01T11:49:48.69994Z","end":"2026-07-01T11:49:48.964697Z","steps":["trace[443881994] 'process raft request' (duration: 78.938311ms)","trace[443881994] 'compare' (duration: 184.716884ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:49.047176Z","caller":"traceutil/trace.go:171","msg":"trace[1063548666] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"111.803016ms","start":"2026-07-01T11:49:48.935358Z","end":"2026-07-01T11:49:49.047161Z","steps":["trace[1063548666] 'process raft request' (duration: 111.706764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:49.241387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.877696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:49:49.241512Z","caller":"traceutil/trace.go:171","msg":"trace[1129440523] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"203.581106ms","start":"2026-07-01T11:49:49.03789Z","end":"2026-07-01T11:49:49.241471Z","steps":["trace[1129440523] 'process raft request' (duration: 58.549017ms)","trace[1129440523] 'compare' (duration: 144.631941ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:49.241562Z","caller":"traceutil/trace.go:171","msg":"trace[510257576] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"158.649845ms","start":"2026-07-01T11:49:49.082898Z","end":"2026-07-01T11:49:49.241548Z","steps":["trace[510257576] 'process raft request' (duration: 158.571814ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:49.241589Z","caller":"traceutil/trace.go:171","msg":"trace[566698931] linearizableReadLoop","detail":"{readStateIndex:25632; appliedIndex:25631; }","duration":"194.181786ms","start":"2026-07-01T11:49:49.047382Z","end":"2026-07-01T11:49:49.241563Z","steps":["trace[566698931] 'read index received' (duration: 49.124186ms)","trace[566698931] 'applied index is now lower than readState.Index' (duration: 145.05636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:49:49.241836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.247573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-01T11:49:49.241845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.392883ms","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-07-01T11:49:49.241877Z","caller":"traceutil/trace.go:171","msg":"trace[669097571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24835; }","duration":"215.337775ms","start":"2026-07-01T11:49:49.026529Z","end":"2026-07-01T11:49:49.241867Z","steps":["trace[669097571] 'agreement among raft nodes before linearized reading' (duration: 215.165431ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:49.241886Z","caller":"traceutil/trace.go:171","msg":"trace[152247850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24835; }","duration":"203.468784ms","start":"2026-07-01T11:49:49.038404Z","end":"2026-07-01T11:49:49.241873Z","steps":["trace[152247850] 'agreement among raft nodes before linearized reading' (duration: 203.27556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:49.241976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.865431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:49:49.242006Z","caller":"traceutil/trace.go:171","msg":"trace[1625831164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24835; }","duration":"193.915451ms","start":"2026-07-01T11:49:49.048084Z","end":"2026-07-01T11:49:49.241999Z","steps":["trace[1625831164] 'agreement among raft nodes before linearized reading' (duration: 193.864869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:49:49.242055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.84983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-01T11:49:49.242094Z","caller":"traceutil/trace.go:171","msg":"trace[605552894] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24835; }","duration":"190.896771ms","start":"2026-07-01T11:49:49.051185Z","end":"2026-07-01T11:49:49.242081Z","steps":["trace[605552894] 'agreement among raft nodes before linearized reading' (duration: 190.803839ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:49:49.369735Z","caller":"traceutil/trace.go:171","msg":"trace[524916346] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"122.665497ms","start":"2026-07-01T11:49:49.247047Z","end":"2026-07-01T11:49:49.369712Z","steps":["trace[524916346] 'process raft request' (duration: 107.167283ms)","trace[524916346] 'compare' (duration: 15.420992ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:53.867739Z","caller":"traceutil/trace.go:171","msg":"trace[1454245082] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"214.871546ms","start":"2026-07-01T11:49:53.652833Z","end":"2026-07-01T11:49:53.867705Z","steps":["trace[1454245082] 'process raft request' (duration: 169.70533ms)","trace[1454245082] 'compare' (duration: 44.41536ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:49:53.926842Z","caller":"traceutil/trace.go:171","msg":"trace[1278136193] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"173.017488ms","start":"2026-07-01T11:49:53.753803Z","end":"2026-07-01T11:49:53.926821Z","steps":["trace[1278136193] 'process raft request' (duration: 172.872435ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:03.842653Z","caller":"traceutil/trace.go:171","msg":"trace[706134702] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"338.992342ms","start":"2026-07-01T11:50:03.503627Z","end":"2026-07-01T11:50:03.84262Z","steps":["trace[706134702] 'process raft request' (duration: 326.748674ms)","trace[706134702] 'compare' (duration: 11.900561ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:03.842632Z","caller":"traceutil/trace.go:171","msg":"trace[1312913568] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25770; }","duration":"336.146194ms","start":"2026-07-01T11:50:03.506451Z","end":"2026-07-01T11:50:03.842597Z","steps":["trace[1312913568] 'read index received' (duration: 324.012279ms)","trace[1312913568] 'applied index is now lower than readState.Index' (duration: 12.132815ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:03.842786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.503613Z","time spent":"339.106255ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:03.842841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.38063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T11:50:03.84289Z","caller":"traceutil/trace.go:171","msg":"trace[412405141] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24971; }","duration":"336.481162ms","start":"2026-07-01T11:50:03.506395Z","end":"2026-07-01T11:50:03.842876Z","steps":["trace[412405141] 'agreement among raft nodes before linearized reading' (duration: 336.308488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:03.842924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.506375Z","time spent":"336.539623ms","remote":"127.0.0.1:46052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-01T11:50:04.040026Z","caller":"traceutil/trace.go:171","msg":"trace[792206253] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"439.009989ms","start":"2026-07-01T11:50:03.600984Z","end":"2026-07-01T11:50:04.039994Z","steps":["trace[792206253] 'process raft request' (duration: 375.844268ms)","trace[792206253] 'compare' (duration: 62.981888ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:04.040145Z","caller":"traceutil/trace.go:171","msg":"trace[162734780] linearizableReadLoop","detail":"{readStateIndex:25773; appliedIndex:25771; }","duration":"197.317489ms","start":"2026-07-01T11:50:03.842809Z","end":"2026-07-01T11:50:04.040127Z","steps":["trace[162734780] 'read index received' (duration: 134.042146ms)","trace[162734780] 'applied index is now lower than readState.Index' (duration: 63.274183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:04.040191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.600957Z","time spent":"439.148933ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:04.040184Z","caller":"traceutil/trace.go:171","msg":"trace[6815982] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"429.968945ms","start":"2026-07-01T11:50:03.610173Z","end":"2026-07-01T11:50:04.040142Z","steps":["trace[6815982] 'process raft request' (duration: 429.775812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.040266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.218644ms","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-07-01T11:50:04.040289Z","caller":"traceutil/trace.go:171","msg":"trace[1836912802] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:24973; }","duration":"440.292615ms","start":"2026-07-01T11:50:03.599989Z","end":"2026-07-01T11:50:04.040281Z","steps":["trace[1836912802] 'agreement among raft nodes before linearized reading' (duration: 440.215244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.040309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.599971Z","time spent":"440.332607ms","remote":"127.0.0.1:47912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":32,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:50:04.040368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.61015Z","time spent":"430.11112ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:04.040438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.672269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:04.040466Z","caller":"traceutil/trace.go:171","msg":"trace[1981751233] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24973; }","duration":"462.72234ms","start":"2026-07-01T11:50:03.577737Z","end":"2026-07-01T11:50:04.04046Z","steps":["trace[1981751233] 'agreement among raft nodes before linearized reading' (duration: 462.681169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.040448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.506317ms","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-07-01T11:50:04.040507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.876753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:50:04.040525Z","caller":"traceutil/trace.go:171","msg":"trace[1337578197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24973; }","duration":"318.594499ms","start":"2026-07-01T11:50:03.72191Z","end":"2026-07-01T11:50:04.040504Z","steps":["trace[1337578197] 'agreement among raft nodes before linearized reading' (duration: 318.354544ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:04.040546Z","caller":"traceutil/trace.go:171","msg":"trace[1455320777] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24973; }","duration":"497.966904ms","start":"2026-07-01T11:50:03.542567Z","end":"2026-07-01T11:50:04.040534Z","steps":["trace[1455320777] 'agreement among raft nodes before linearized reading' (duration: 497.887763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.040569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.721854Z","time spent":"318.706721ms","remote":"127.0.0.1:46116","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":"warn","ts":"2026-07-01T11:50:04.040577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.542548Z","time spent":"498.023206ms","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":32,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:50:04.04048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.577723Z","time spent":"462.753241ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:50:04.04033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.162561ms","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-07-01T11:50:04.040974Z","caller":"traceutil/trace.go:171","msg":"trace[547813080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24973; }","duration":"396.838624ms","start":"2026-07-01T11:50:03.644115Z","end":"2026-07-01T11:50:04.040953Z","steps":["trace[547813080] 'agreement among raft nodes before linearized reading' (duration: 396.069808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.041024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:03.644101Z","time spent":"396.909326ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T11:50:04.125177Z","caller":"traceutil/trace.go:171","msg":"trace[1611751121] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"151.701555ms","start":"2026-07-01T11:50:03.973441Z","end":"2026-07-01T11:50:04.125143Z","steps":["trace[1611751121] 'process raft request' (duration: 151.416509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.125498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.842843ms","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-07-01T11:50:04.125576Z","caller":"traceutil/trace.go:171","msg":"trace[1112826758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24977; }","duration":"209.957065ms","start":"2026-07-01T11:50:03.915605Z","end":"2026-07-01T11:50:04.125562Z","steps":["trace[1112826758] 'agreement among raft nodes before linearized reading' (duration: 209.786212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.125658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.370885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18433"} {"level":"info","ts":"2026-07-01T11:50:04.125758Z","caller":"traceutil/trace.go:171","msg":"trace[1935948512] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24977; }","duration":"281.515477ms","start":"2026-07-01T11:50:03.844224Z","end":"2026-07-01T11:50:04.12574Z","steps":["trace[1935948512] 'agreement among raft nodes before linearized reading' (duration: 281.22224ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:04.196931Z","caller":"traceutil/trace.go:171","msg":"trace[1566472714] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"107.423458ms","start":"2026-07-01T11:50:04.089468Z","end":"2026-07-01T11:50:04.196892Z","steps":["trace[1566472714] 'process raft request' (duration: 90.298101ms)","trace[1566472714] 'compare' (duration: 16.626077ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:04.253667Z","caller":"traceutil/trace.go:171","msg":"trace[471857499] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"123.496723ms","start":"2026-07-01T11:50:04.130143Z","end":"2026-07-01T11:50:04.25364Z","steps":["trace[471857499] 'process raft request' (duration: 123.308819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.25371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.645643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-01T11:50:04.253806Z","caller":"traceutil/trace.go:171","msg":"trace[1041752019] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24979; }","duration":"113.770486ms","start":"2026-07-01T11:50:04.14002Z","end":"2026-07-01T11:50:04.25379Z","steps":["trace[1041752019] 'agreement among raft nodes before linearized reading' (duration: 113.554362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:04.555349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.334598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:04.555611Z","caller":"traceutil/trace.go:171","msg":"trace[151417523] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"296.230815ms","start":"2026-07-01T11:50:04.259357Z","end":"2026-07-01T11:50:04.555587Z","steps":["trace[151417523] 'process raft request' (duration: 296.115213ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:04.555628Z","caller":"traceutil/trace.go:171","msg":"trace[367865301] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"296.48346ms","start":"2026-07-01T11:50:04.25911Z","end":"2026-07-01T11:50:04.555593Z","steps":["trace[367865301] 'process raft request' (duration: 141.797275ms)","trace[367865301] 'compare' (duration: 153.986581ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:04.851857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.363102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:04.851961Z","caller":"traceutil/trace.go:171","msg":"trace[1004791615] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:24981; }","duration":"160.525194ms","start":"2026-07-01T11:50:04.69141Z","end":"2026-07-01T11:50:04.851935Z","steps":["trace[1004791615] 'count revisions from in-memory index tree' (duration: 160.207377ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:05.549733Z","caller":"traceutil/trace.go:171","msg":"trace[508237486] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"185.628623ms","start":"2026-07-01T11:50:05.36408Z","end":"2026-07-01T11:50:05.549708Z","steps":["trace[508237486] 'process raft request' (duration: 185.432189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.213291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.030948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:06.21366Z","caller":"traceutil/trace.go:171","msg":"trace[856973026] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"168.260141ms","start":"2026-07-01T11:50:06.045377Z","end":"2026-07-01T11:50:06.213637Z","steps":["trace[856973026] 'process raft request' (duration: 31.684332ms)","trace[856973026] 'compare' (duration: 135.792073ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:06.213872Z","caller":"traceutil/trace.go:171","msg":"trace[1699214368] linearizableReadLoop","detail":"{readStateIndex:25788; appliedIndex:25786; }","duration":"164.25342ms","start":"2026-07-01T11:50:06.049608Z","end":"2026-07-01T11:50:06.213861Z","steps":["trace[1699214368] 'read index received' (duration: 27.465077ms)","trace[1699214368] 'applied index is now lower than readState.Index' (duration: 136.787233ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:06.213973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.371592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:06.213963Z","caller":"traceutil/trace.go:171","msg":"trace[2071513387] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"165.470384ms","start":"2026-07-01T11:50:06.048466Z","end":"2026-07-01T11:50:06.213936Z","steps":["trace[2071513387] 'process raft request' (duration: 165.121777ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.214006Z","caller":"traceutil/trace.go:171","msg":"trace[1198925271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24987; }","duration":"164.438424ms","start":"2026-07-01T11:50:06.049558Z","end":"2026-07-01T11:50:06.213997Z","steps":["trace[1198925271] 'agreement among raft nodes before linearized reading' (duration: 164.363312ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.569243Z","caller":"traceutil/trace.go:171","msg":"trace[1187765761] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"437.118541ms","start":"2026-07-01T11:50:06.132104Z","end":"2026-07-01T11:50:06.569222Z","steps":["trace[1187765761] 'process raft request' (duration: 437.033499ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.569283Z","caller":"traceutil/trace.go:171","msg":"trace[83546460] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"438.146743ms","start":"2026-07-01T11:50:06.131111Z","end":"2026-07-01T11:50:06.569257Z","steps":["trace[83546460] 'process raft request' (duration: 374.500142ms)","trace[83546460] 'compare' (duration: 63.326684ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:06.569364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.132095Z","time spent":"437.211413ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:06.569413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.131093Z","time spent":"438.251504ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:06.626845Z","caller":"traceutil/trace.go:171","msg":"trace[1705328386] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"424.526075ms","start":"2026-07-01T11:50:06.202302Z","end":"2026-07-01T11:50:06.626828Z","steps":["trace[1705328386] 'process raft request' (duration: 424.24391ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.62685Z","caller":"traceutil/trace.go:171","msg":"trace[206344906] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"242.359042ms","start":"2026-07-01T11:50:06.384456Z","end":"2026-07-01T11:50:06.626815Z","steps":["trace[206344906] 'process raft request' (duration: 242.25481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.627009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.20228Z","time spent":"424.640837ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:06.627149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.869292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-01T11:50:06.627193Z","caller":"traceutil/trace.go:171","msg":"trace[84173092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24991; }","duration":"495.953163ms","start":"2026-07-01T11:50:06.131225Z","end":"2026-07-01T11:50:06.627178Z","steps":["trace[84173092] 'agreement among raft nodes before linearized reading' (duration: 495.79662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.62722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.131206Z","time spent":"496.006865ms","remote":"127.0.0.1:46116","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":"warn","ts":"2026-07-01T11:50:06.62728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.476605ms","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-07-01T11:50:06.627352Z","caller":"traceutil/trace.go:171","msg":"trace[942076175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24991; }","duration":"497.570177ms","start":"2026-07-01T11:50:06.129762Z","end":"2026-07-01T11:50:06.627332Z","steps":["trace[942076175] 'agreement among raft nodes before linearized reading' (duration: 497.407753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.627408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.129746Z","time spent":"497.644168ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T11:50:06.627544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.702731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-07-01T11:50:06.627603Z","caller":"traceutil/trace.go:171","msg":"trace[1408640612] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24991; }","duration":"540.781232ms","start":"2026-07-01T11:50:06.086808Z","end":"2026-07-01T11:50:06.627589Z","steps":["trace[1408640612] 'agreement among raft nodes before linearized reading' (duration: 540.622149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.627641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.086773Z","time spent":"540.857694ms","remote":"127.0.0.1:45980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1179,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-07-01T11:50:06.626786Z","caller":"traceutil/trace.go:171","msg":"trace[1515226504] linearizableReadLoop","detail":"{readStateIndex:25791; appliedIndex:25788; }","duration":"412.860589ms","start":"2026-07-01T11:50:06.213903Z","end":"2026-07-01T11:50:06.626763Z","steps":["trace[1515226504] 'read index received' (duration: 291.691283ms)","trace[1515226504] 'applied index is now lower than readState.Index' (duration: 121.168406ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:06.628423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.09931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-01T11:50:06.628451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.238589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:50:06.628484Z","caller":"traceutil/trace.go:171","msg":"trace[494659471] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24991; }","duration":"215.215583ms","start":"2026-07-01T11:50:06.413253Z","end":"2026-07-01T11:50:06.628468Z","steps":["trace[494659471] 'agreement among raft nodes before linearized reading' (duration: 215.103061ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.628535Z","caller":"traceutil/trace.go:171","msg":"trace[1458329119] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:24991; }","duration":"382.349291ms","start":"2026-07-01T11:50:06.246169Z","end":"2026-07-01T11:50:06.628518Z","steps":["trace[1458329119] 'agreement among raft nodes before linearized reading' (duration: 382.213078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.628566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.674096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:50:06.628475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.067803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-01T11:50:06.628594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.246158Z","time spent":"382.420332ms","remote":"127.0.0.1:44098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":32,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:50:06.628646Z","caller":"traceutil/trace.go:171","msg":"trace[2065808590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24991; }","duration":"413.784578ms","start":"2026-07-01T11:50:06.214818Z","end":"2026-07-01T11:50:06.628602Z","steps":["trace[2065808590] 'agreement among raft nodes before linearized reading' (duration: 413.508463ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:06.628652Z","caller":"traceutil/trace.go:171","msg":"trace[43829372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24991; }","duration":"371.265006ms","start":"2026-07-01T11:50:06.257374Z","end":"2026-07-01T11:50:06.628639Z","steps":["trace[43829372] 'agreement among raft nodes before linearized reading' (duration: 370.992701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:06.628702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.257363Z","time spent":"371.327547ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T11:50:06.628701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:06.214806Z","time spent":"413.88178ms","remote":"127.0.0.1:45882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T11:50:07.717401Z","caller":"traceutil/trace.go:171","msg":"trace[112273067] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"162.280849ms","start":"2026-07-01T11:50:07.555096Z","end":"2026-07-01T11:50:07.717376Z","steps":["trace[112273067] 'process raft request' (duration: 162.107136ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:07.718048Z","caller":"traceutil/trace.go:171","msg":"trace[1141851661] linearizableReadLoop","detail":"{readStateIndex:25799; appliedIndex:25799; }","duration":"134.077109ms","start":"2026-07-01T11:50:07.583955Z","end":"2026-07-01T11:50:07.718032Z","steps":["trace[1141851661] 'read index received' (duration: 134.069458ms)","trace[1141851661] 'applied index is now lower than readState.Index' (duration: 5.801µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:07.718208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.247842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-01T11:50:07.718245Z","caller":"traceutil/trace.go:171","msg":"trace[2046831399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24998; }","duration":"134.330234ms","start":"2026-07-01T11:50:07.583906Z","end":"2026-07-01T11:50:07.718236Z","steps":["trace[2046831399] 'agreement among raft nodes before linearized reading' (duration: 134.200131ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:07.882183Z","caller":"traceutil/trace.go:171","msg":"trace[321261137] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"286.795613ms","start":"2026-07-01T11:50:07.595355Z","end":"2026-07-01T11:50:07.882151Z","steps":["trace[321261137] 'process raft request' (duration: 247.463616ms)","trace[321261137] 'compare' (duration: 39.145543ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:07.882246Z","caller":"traceutil/trace.go:171","msg":"trace[1764598174] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"160.222558ms","start":"2026-07-01T11:50:07.722006Z","end":"2026-07-01T11:50:07.882229Z","steps":["trace[1764598174] 'process raft request' (duration: 160.152246ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:07.882274Z","caller":"traceutil/trace.go:171","msg":"trace[1621554314] linearizableReadLoop","detail":"{readStateIndex:25800; appliedIndex:25799; }","duration":"164.162998ms","start":"2026-07-01T11:50:07.718088Z","end":"2026-07-01T11:50:07.882251Z","steps":["trace[1621554314] 'read index received' (duration: 124.795479ms)","trace[1621554314] 'applied index is now lower than readState.Index' (duration: 39.366089ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:07.882522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.761634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29715110-lxjj7\" ","response":"range_response_count:1 size:7222"} {"level":"info","ts":"2026-07-01T11:50:07.882566Z","caller":"traceutil/trace.go:171","msg":"trace[2058493886] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29715110-lxjj7; range_end:; response_count:1; response_revision:25000; }","duration":"290.848836ms","start":"2026-07-01T11:50:07.591705Z","end":"2026-07-01T11:50:07.882554Z","steps":["trace[2058493886] 'agreement among raft nodes before linearized reading' (duration: 290.634172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:07.882821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.850381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-07-01T11:50:07.882883Z","caller":"traceutil/trace.go:171","msg":"trace[493694999] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25000; }","duration":"270.972073ms","start":"2026-07-01T11:50:07.611895Z","end":"2026-07-01T11:50:07.882867Z","steps":["trace[493694999] 'agreement among raft nodes before linearized reading' (duration: 270.762579ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:10.103836Z","caller":"traceutil/trace.go:171","msg":"trace[1751546412] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"143.163473ms","start":"2026-07-01T11:50:09.960646Z","end":"2026-07-01T11:50:10.103809Z","steps":["trace[1751546412] 'process raft request' (duration: 65.701822ms)","trace[1751546412] 'compare' (duration: 77.028571ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:11.840852Z","caller":"traceutil/trace.go:171","msg":"trace[665766673] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"111.934448ms","start":"2026-07-01T11:50:11.728867Z","end":"2026-07-01T11:50:11.840802Z","steps":["trace[665766673] 'process raft request' (duration: 64.030366ms)","trace[665766673] 'compare' (duration: 47.507224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:14.239751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.447266ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-01T11:50:14.239855Z","caller":"traceutil/trace.go:171","msg":"trace[844676491] linearizableReadLoop","detail":"{readStateIndex:25854; appliedIndex:25853; }","duration":"209.856415ms","start":"2026-07-01T11:50:14.029981Z","end":"2026-07-01T11:50:14.239837Z","steps":["trace[844676491] 'read index received' (duration: 40.371µs)","trace[844676491] 'applied index is now lower than readState.Index' (duration: 209.804353ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:14.240055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.066019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-01T11:50:14.240031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.046103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:14.240091Z","caller":"traceutil/trace.go:171","msg":"trace[334318273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25051; }","duration":"210.144629ms","start":"2026-07-01T11:50:14.029937Z","end":"2026-07-01T11:50:14.240082Z","steps":["trace[334318273] 'agreement among raft nodes before linearized reading' (duration: 209.993116ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:14.240112Z","caller":"traceutil/trace.go:171","msg":"trace[2006748811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25051; }","duration":"194.157506ms","start":"2026-07-01T11:50:14.045935Z","end":"2026-07-01T11:50:14.240092Z","steps":["trace[2006748811] 'agreement among raft nodes before linearized reading' (duration: 194.018783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:14.240159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.748986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T11:50:14.240241Z","caller":"traceutil/trace.go:171","msg":"trace[1651100703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25051; }","duration":"118.869669ms","start":"2026-07-01T11:50:14.121352Z","end":"2026-07-01T11:50:14.240222Z","steps":["trace[1651100703] 'agreement among raft nodes before linearized reading' (duration: 118.634645ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:14.485686Z","caller":"traceutil/trace.go:171","msg":"trace[867444038] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"238.175368ms","start":"2026-07-01T11:50:14.247487Z","end":"2026-07-01T11:50:14.485663Z","steps":["trace[867444038] 'process raft request' (duration: 237.976344ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:14.485908Z","caller":"traceutil/trace.go:171","msg":"trace[1194072896] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"160.989514ms","start":"2026-07-01T11:50:14.324909Z","end":"2026-07-01T11:50:14.485898Z","steps":["trace[1194072896] 'process raft request' (duration: 160.711858ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:14.486158Z","caller":"traceutil/trace.go:171","msg":"trace[1408450842] linearizableReadLoop","detail":"{readStateIndex:25856; appliedIndex:25854; }","duration":"207.71638ms","start":"2026-07-01T11:50:14.278413Z","end":"2026-07-01T11:50:14.48613Z","steps":["trace[1408450842] 'read index received' (duration: 58.452685ms)","trace[1408450842] 'applied index is now lower than readState.Index' (duration: 149.262605ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:14.48626Z","caller":"traceutil/trace.go:171","msg":"trace[2051721261] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"154.615544ms","start":"2026-07-01T11:50:14.331615Z","end":"2026-07-01T11:50:14.48623Z","steps":["trace[2051721261] 'process raft request' (duration: 154.242787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:14.486332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.904105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:14.486375Z","caller":"traceutil/trace.go:171","msg":"trace[1879495050] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25055; }","duration":"208.001926ms","start":"2026-07-01T11:50:14.278361Z","end":"2026-07-01T11:50:14.486362Z","steps":["trace[1879495050] 'agreement among raft nodes before linearized reading' (duration: 207.907634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:14.486396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.043411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T11:50:14.486436Z","caller":"traceutil/trace.go:171","msg":"trace[1398847342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25055; }","duration":"113.135083ms","start":"2026-07-01T11:50:14.37329Z","end":"2026-07-01T11:50:14.486425Z","steps":["trace[1398847342] 'agreement among raft nodes before linearized reading' (duration: 112.99179ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:30.153242Z","caller":"traceutil/trace.go:171","msg":"trace[1205810065] linearizableReadLoop","detail":"{readStateIndex:25992; appliedIndex:25991; }","duration":"110.736305ms","start":"2026-07-01T11:50:30.042481Z","end":"2026-07-01T11:50:30.153217Z","steps":["trace[1205810065] 'read index received' (duration: 110.50043ms)","trace[1205810065] 'applied index is now lower than readState.Index' (duration: 234.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:30.153359Z","caller":"traceutil/trace.go:171","msg":"trace[1523902029] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"150.280227ms","start":"2026-07-01T11:50:30.003047Z","end":"2026-07-01T11:50:30.153327Z","steps":["trace[1523902029] 'process raft request' (duration: 150.024181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:30.153462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.95735ms","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-07-01T11:50:30.153551Z","caller":"traceutil/trace.go:171","msg":"trace[98917163] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25186; }","duration":"111.061952ms","start":"2026-07-01T11:50:30.042469Z","end":"2026-07-01T11:50:30.153531Z","steps":["trace[98917163] 'agreement among raft nodes before linearized reading' (duration: 110.881368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:30.153638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.338635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:30.153679Z","caller":"traceutil/trace.go:171","msg":"trace[54671788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25186; }","duration":"105.421127ms","start":"2026-07-01T11:50:30.048247Z","end":"2026-07-01T11:50:30.153668Z","steps":["trace[54671788] 'agreement among raft nodes before linearized reading' (duration: 105.107181ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:35.465171Z","caller":"traceutil/trace.go:171","msg":"trace[113652082] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"118.588265ms","start":"2026-07-01T11:50:35.346558Z","end":"2026-07-01T11:50:35.465146Z","steps":["trace[113652082] 'process raft request' (duration: 118.429182ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:36.651951Z","caller":"traceutil/trace.go:171","msg":"trace[953473237] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"194.354079ms","start":"2026-07-01T11:50:36.45757Z","end":"2026-07-01T11:50:36.651924Z","steps":["trace[953473237] 'process raft request' (duration: 194.181346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:36.960946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.456403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:36.961064Z","caller":"traceutil/trace.go:171","msg":"trace[1468877166] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"284.893255ms","start":"2026-07-01T11:50:36.676148Z","end":"2026-07-01T11:50:36.961041Z","steps":["trace[1468877166] 'process raft request' (duration: 161.258709ms)","trace[1468877166] 'compare' (duration: 123.282219ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:37.001572Z","caller":"traceutil/trace.go:171","msg":"trace[1021689361] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"184.459689ms","start":"2026-07-01T11:50:36.817093Z","end":"2026-07-01T11:50:37.001552Z","steps":["trace[1021689361] 'process raft request' (duration: 184.413038ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:37.001606Z","caller":"traceutil/trace.go:171","msg":"trace[64969293] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"276.111436ms","start":"2026-07-01T11:50:36.725461Z","end":"2026-07-01T11:50:37.001573Z","steps":["trace[64969293] 'process raft request' (duration: 275.866821ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:37.001676Z","caller":"traceutil/trace.go:171","msg":"trace[2039317193] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"266.124335ms","start":"2026-07-01T11:50:36.73552Z","end":"2026-07-01T11:50:37.001644Z","steps":["trace[2039317193] 'process raft request' (duration: 265.942251ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:37.047093Z","caller":"traceutil/trace.go:171","msg":"trace[2108660247] linearizableReadLoop","detail":"{readStateIndex:26044; appliedIndex:26040; }","duration":"194.39805ms","start":"2026-07-01T11:50:36.85267Z","end":"2026-07-01T11:50:37.047068Z","steps":["trace[2108660247] 'read index received' (duration: 148.713954ms)","trace[2108660247] 'applied index is now lower than readState.Index' (duration: 45.683116ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:37.047132Z","caller":"traceutil/trace.go:171","msg":"trace[1206772053] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"199.573685ms","start":"2026-07-01T11:50:36.84753Z","end":"2026-07-01T11:50:37.047103Z","steps":["trace[1206772053] 'process raft request' (duration: 199.397072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.047283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.591124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:37.047406Z","caller":"traceutil/trace.go:171","msg":"trace[991774631] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25237; }","duration":"194.757649ms","start":"2026-07-01T11:50:36.852631Z","end":"2026-07-01T11:50:37.047388Z","steps":["trace[991774631] 'agreement among raft nodes before linearized reading' (duration: 194.582884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.047603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.074156ms","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-07-01T11:50:37.047681Z","caller":"traceutil/trace.go:171","msg":"trace[678253296] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25237; }","duration":"125.179058ms","start":"2026-07-01T11:50:36.922485Z","end":"2026-07-01T11:50:37.047664Z","steps":["trace[678253296] 'agreement among raft nodes before linearized reading' (duration: 124.974244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.047797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.823493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-01T11:50:37.047855Z","caller":"traceutil/trace.go:171","msg":"trace[1923786178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25237; }","duration":"132.922195ms","start":"2026-07-01T11:50:36.914914Z","end":"2026-07-01T11:50:37.047836Z","steps":["trace[1923786178] 'agreement among raft nodes before linearized reading' (duration: 132.800112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.047891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.819324ms","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-07-01T11:50:37.047877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.84576ms","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-07-01T11:50:37.047953Z","caller":"traceutil/trace.go:171","msg":"trace[1120694301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25237; }","duration":"171.923516ms","start":"2026-07-01T11:50:36.876013Z","end":"2026-07-01T11:50:37.047937Z","steps":["trace[1120694301] 'agreement among raft nodes before linearized reading' (duration: 171.761652ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:37.047956Z","caller":"traceutil/trace.go:171","msg":"trace[1982800439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25237; }","duration":"119.954652ms","start":"2026-07-01T11:50:36.92799Z","end":"2026-07-01T11:50:37.047944Z","steps":["trace[1982800439] 'agreement among raft nodes before linearized reading' (duration: 119.722127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.047437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.692217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:50:37.048151Z","caller":"traceutil/trace.go:171","msg":"trace[1772461389] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25237; }","duration":"193.446982ms","start":"2026-07-01T11:50:36.854684Z","end":"2026-07-01T11:50:37.048131Z","steps":["trace[1772461389] 'agreement among raft nodes before linearized reading' (duration: 192.669506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.328879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.01607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:50:37.328977Z","caller":"traceutil/trace.go:171","msg":"trace[1335232728] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"277.130218ms","start":"2026-07-01T11:50:37.051828Z","end":"2026-07-01T11:50:37.328959Z","steps":["trace[1335232728] 'process raft request' (duration: 94.863823ms)","trace[1335232728] 'compare' (duration: 181.878827ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:50:37.375847Z","caller":"traceutil/trace.go:171","msg":"trace[397843579] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"323.516618ms","start":"2026-07-01T11:50:37.052303Z","end":"2026-07-01T11:50:37.37582Z","steps":["trace[397843579] 'process raft request' (duration: 323.333084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.376032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:37.052284Z","time spent":"323.666792ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:37.376261Z","caller":"traceutil/trace.go:171","msg":"trace[1265600831] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"322.752232ms","start":"2026-07-01T11:50:37.053477Z","end":"2026-07-01T11:50:37.376229Z","steps":["trace[1265600831] 'process raft request' (duration: 322.6391ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:37.376224Z","caller":"traceutil/trace.go:171","msg":"trace[298931042] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"323.742062ms","start":"2026-07-01T11:50:37.052456Z","end":"2026-07-01T11:50:37.376198Z","steps":["trace[298931042] 'process raft request' (duration: 323.321914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.376425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:37.053452Z","time spent":"322.881535ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:50:37.376579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:37.052434Z","time spent":"323.960597ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:50:37.376756Z","caller":"traceutil/trace.go:171","msg":"trace[727493942] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"323.786322ms","start":"2026-07-01T11:50:37.052916Z","end":"2026-07-01T11:50:37.376702Z","steps":["trace[727493942] 'process raft request' (duration: 323.079808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:50:37.376889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:50:37.052901Z","time spent":"323.946216ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:51:39.261338Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000685b.wal"} {"level":"info","ts":"2026-07-01T11:52:25.571669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23440} {"level":"info","ts":"2026-07-01T11:52:25.713164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23440,"took":"138.061358ms","hash":386159226,"current-db-size-bytes":58585088,"current-db-size":"59 MB","current-db-size-in-use-bytes":36900864,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-01T11:52:25.713231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":386159226,"revision":23440,"compact-revision":20211} {"level":"info","ts":"2026-07-01T11:54:06.111782Z","caller":"traceutil/trace.go:171","msg":"trace[734535738] transaction","detail":"{read_only:false; response_revision:27380; number_of_response:1; }","duration":"112.765306ms","start":"2026-07-01T11:54:05.99899Z","end":"2026-07-01T11:54:06.111755Z","steps":["trace[734535738] 'process raft request' (duration: 112.48339ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:57:25.578371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26314} {"level":"info","ts":"2026-07-01T11:57:25.73029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26314,"took":"147.656692ms","hash":1773024788,"current-db-size-bytes":58585088,"current-db-size":"59 MB","current-db-size-in-use-bytes":33644544,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T11:57:25.730352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1773024788,"revision":26314,"compact-revision":23440} {"level":"info","ts":"2026-07-01T11:57:51.538685Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:57:51.543422Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-01T11:57:51.543515Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-01T12:02:25.58483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28904} {"level":"info","ts":"2026-07-01T12:02:25.722779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28904,"took":"131.193889ms","hash":1818880461,"current-db-size-bytes":58585088,"current-db-size":"59 MB","current-db-size-in-use-bytes":30928896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T12:02:25.722845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1818880461,"revision":28904,"compact-revision":26314} {"level":"info","ts":"2026-07-01T12:04:49.977314Z","caller":"traceutil/trace.go:171","msg":"trace[1191634511] linearizableReadLoop","detail":"{readStateIndex:33311; appliedIndex:33310; }","duration":"318.893599ms","start":"2026-07-01T12:04:49.658381Z","end":"2026-07-01T12:04:49.977275Z","steps":["trace[1191634511] 'read index received' (duration: 318.072893ms)","trace[1191634511] 'applied index is now lower than readState.Index' (duration: 819.336µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T12:04:49.977379Z","caller":"traceutil/trace.go:171","msg":"trace[744339275] transaction","detail":"{read_only:false; response_revision:32310; number_of_response:1; }","duration":"393.510607ms","start":"2026-07-01T12:04:49.583831Z","end":"2026-07-01T12:04:49.977342Z","steps":["trace[744339275] 'process raft request' (duration: 392.598518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T12:04:49.977525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.583816Z","time spent":"393.631239ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T12:04:49.977624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.234287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18278"} {"level":"info","ts":"2026-07-01T12:04:49.977705Z","caller":"traceutil/trace.go:171","msg":"trace[396999352] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:32310; }","duration":"319.367869ms","start":"2026-07-01T12:04:49.658314Z","end":"2026-07-01T12:04:49.977682Z","steps":["trace[396999352] 'agreement among raft nodes before linearized reading' (duration: 319.140075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T12:04:49.977747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.65829Z","time spent":"319.44874ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18302,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-07-01T12:04:49.979979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.839827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T12:04:49.980055Z","caller":"traceutil/trace.go:171","msg":"trace[343489226] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:32314; }","duration":"258.96327ms","start":"2026-07-01T12:04:49.721073Z","end":"2026-07-01T12:04:49.980036Z","steps":["trace[343489226] 'agreement among raft nodes before linearized reading' (duration: 258.826677ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T12:04:49.980081Z","caller":"traceutil/trace.go:171","msg":"trace[1967183736] transaction","detail":"{read_only:false; response_revision:32312; number_of_response:1; }","duration":"301.626301ms","start":"2026-07-01T12:04:49.678417Z","end":"2026-07-01T12:04:49.980043Z","steps":["trace[1967183736] 'process raft request' (duration: 301.397726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T12:04:49.980172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.678405Z","time spent":"301.715883ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T12:04:49.980163Z","caller":"traceutil/trace.go:171","msg":"trace[308304512] transaction","detail":"{read_only:false; response_revision:32313; number_of_response:1; }","duration":"214.770007ms","start":"2026-07-01T12:04:49.76531Z","end":"2026-07-01T12:04:49.98008Z","steps":["trace[308304512] 'process raft request' (duration: 214.535942ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T12:04:49.980341Z","caller":"traceutil/trace.go:171","msg":"trace[1003363961] transaction","detail":"{read_only:false; response_revision:32311; number_of_response:1; }","duration":"302.149642ms","start":"2026-07-01T12:04:49.678182Z","end":"2026-07-01T12:04:49.980332Z","steps":["trace[1003363961] 'process raft request' (duration: 301.530779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T12:04:49.98039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T12:04:49.678154Z","time spent":"302.213742ms","remote":"127.0.0.1:46116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T12:07:25.590424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31222} {"level":"info","ts":"2026-07-01T12:07:25.734124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31222,"took":"139.468245ms","hash":2514403307,"current-db-size-bytes":58585088,"current-db-size":"59 MB","current-db-size-in-use-bytes":30863360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T12:07:25.734202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2514403307,"revision":31222,"compact-revision":28904} {"level":"info","ts":"2026-07-01T12:12:25.596535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33517} {"level":"info","ts":"2026-07-01T12:12:25.745864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33517,"took":"144.590699ms","hash":3982256093,"current-db-size-bytes":59629568,"current-db-size":"60 MB","current-db-size-in-use-bytes":32903168,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-01T12:12:25.745919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3982256093,"revision":33517,"compact-revision":31222} {"level":"info","ts":"2026-07-01T12:17:25.604439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35809} {"level":"info","ts":"2026-07-01T12:17:25.739873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35809,"took":"132.167036ms","hash":2432680735,"current-db-size-bytes":61321216,"current-db-size":"61 MB","current-db-size-in-use-bytes":31985664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T12:17:25.739919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2432680735,"revision":35809,"compact-revision":33517} {"level":"info","ts":"2026-07-01T12:19:05.002317Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T12:19:05.007587Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-01T12:19:05.007772Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}