{"level":"warn","ts":"2026-04-27T21:00:43.637398Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T21:00:43.63748Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.132: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.19.213.132:2380","--initial-cluster=instance=https://199.19.213.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.132: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-04-27T21:00:43.637547Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T21:00:43.637561Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-27T21:00:43.637579Z","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-04-27T21:00:43.638193Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"]} {"level":"info","ts":"2026-04-27T21:00:43.638267Z","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.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.132: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-04-27T21:00:43.641967Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.491357ms"} {"level":"info","ts":"2026-04-27T21:00:43.649588Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"19a81b20c4b111c3","cluster-id":"42a0cab9df6b0eb"} {"level":"info","ts":"2026-04-27T21:00:43.64973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T21:00:43.649764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 0"} {"level":"info","ts":"2026-04-27T21:00:43.649789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 19a81b20c4b111c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T21:00:43.649804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 1"} {"level":"info","ts":"2026-04-27T21:00:43.649868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"warn","ts":"2026-04-27T21:00:43.661747Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T21:00:43.665566Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T21:00:43.667352Z","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-04-27T21:00:43.66941Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"19a81b20c4b111c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T21:00:43.66972Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"19a81b20c4b111c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T21:00:43.669835Z","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-04-27T21:00:43.670081Z","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-04-27T21:00:43.670199Z","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-04-27T21:00:43.670545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"info","ts":"2026-04-27T21:00:43.670746Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","added-peer-id":"19a81b20c4b111c3","added-peer-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-27T21:00:43.672815Z","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-04-27T21:00:43.673017Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"19a81b20c4b111c3","initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T21:00:43.673086Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T21:00:43.67324Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-27T21:00:43.673303Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-27T21:00:43.950547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T21:00:43.950645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T21:00:43.950688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgPreVoteResp from 19a81b20c4b111c3 at term 1"} {"level":"info","ts":"2026-04-27T21:00:43.950709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became candidate at term 2"} {"level":"info","ts":"2026-04-27T21:00:43.950721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgVoteResp from 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-27T21:00:43.950736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became leader at term 2"} {"level":"info","ts":"2026-04-27T21:00:43.95075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 19a81b20c4b111c3 elected leader 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-27T21:00:43.95198Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"19a81b20c4b111c3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.132:2379]}","request-path":"/0/members/19a81b20c4b111c3/attributes","cluster-id":"42a0cab9df6b0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T21:00:43.951995Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:00:43.952096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:00:43.951969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:43.952279Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T21:00:43.952326Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T21:00:43.953446Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:43.953624Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:43.953702Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:00:43.956053Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.132:2379"} {"level":"info","ts":"2026-04-27T21:00:43.956605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T21:00:51.492429Z","caller":"traceutil/trace.go:171","msg":"trace[291638397] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"107.232088ms","start":"2026-04-27T21:00:51.385174Z","end":"2026-04-27T21:00:51.492407Z","steps":["trace[291638397] 'process raft request' (duration: 107.091535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:51.932913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.669534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:00:51.933086Z","caller":"traceutil/trace.go:171","msg":"trace[87146833] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"129.926938ms","start":"2026-04-27T21:00:51.803141Z","end":"2026-04-27T21:00:51.933068Z","steps":["trace[87146833] 'read index received' (duration: 71.362µs)","trace[87146833] 'applied index is now lower than readState.Index' (duration: 129.854296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:00:51.933196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.06095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T21:00:51.933159Z","caller":"traceutil/trace.go:171","msg":"trace[1524625102] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"507.873829ms","start":"2026-04-27T21:00:51.425237Z","end":"2026-04-27T21:00:51.933111Z","steps":["trace[1524625102] 'process raft request' (duration: 197.446952ms)","trace[1524625102] 'compare' (duration: 309.47558ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:00:51.933242Z","caller":"traceutil/trace.go:171","msg":"trace[1263588941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:280; }","duration":"130.235695ms","start":"2026-04-27T21:00:51.802997Z","end":"2026-04-27T21:00:51.933232Z","steps":["trace[1263588941] 'agreement among raft nodes before linearized reading' (duration: 130.120072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:00:51.933342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:00:51.424662Z","time spent":"508.603795ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T21:01:22.243522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.052145ms","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-04-27T21:01:22.24364Z","caller":"traceutil/trace.go:171","msg":"trace[1739562232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"156.231089ms","start":"2026-04-27T21:01:22.087381Z","end":"2026-04-27T21:01:22.243613Z","steps":["trace[1739562232] 'range keys from in-memory index tree' (duration: 155.833321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.409282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.30443ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-27T21:01:22.409607Z","caller":"traceutil/trace.go:171","msg":"trace[1051403694] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"160.896597ms","start":"2026-04-27T21:01:22.248684Z","end":"2026-04-27T21:01:22.409581Z","steps":["trace[1051403694] 'process raft request' (duration: 160.716614ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:24.27208Z","caller":"traceutil/trace.go:171","msg":"trace[672736155] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"116.006574ms","start":"2026-04-27T21:01:24.156044Z","end":"2026-04-27T21:01:24.272051Z","steps":["trace[672736155] 'process raft request' (duration: 115.80825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:39.165309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.886376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-clp62\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-27T21:01:39.165415Z","caller":"traceutil/trace.go:171","msg":"trace[290641672] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-clp62; range_end:; response_count:1; response_revision:732; }","duration":"121.06869ms","start":"2026-04-27T21:01:39.044324Z","end":"2026-04-27T21:01:39.165393Z","steps":["trace[290641672] 'range keys from in-memory index tree' (duration: 120.639302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:49.817713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.250659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T21:02:49.817776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.662195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T21:02:49.817837Z","caller":"traceutil/trace.go:171","msg":"trace[1823272715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1766; }","duration":"122.423032ms","start":"2026-04-27T21:02:49.695388Z","end":"2026-04-27T21:02:49.817811Z","steps":["trace[1823272715] 'range keys from in-memory index tree' (duration: 122.079395ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:49.81785Z","caller":"traceutil/trace.go:171","msg":"trace[37076346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1766; }","duration":"123.748377ms","start":"2026-04-27T21:02:49.694083Z","end":"2026-04-27T21:02:49.817832Z","steps":["trace[37076346] 'range keys from in-memory index tree' (duration: 123.490902ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:50.244394Z","caller":"traceutil/trace.go:171","msg":"trace[453164340] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"161.520182ms","start":"2026-04-27T21:02:50.08284Z","end":"2026-04-27T21:02:50.24436Z","steps":["trace[453164340] 'process raft request' (duration: 161.289048ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:33.619075Z","caller":"traceutil/trace.go:171","msg":"trace[1017242957] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"111.690669ms","start":"2026-04-27T21:03:33.507348Z","end":"2026-04-27T21:03:33.619039Z","steps":["trace[1017242957] 'process raft request' (duration: 82.018149ms)","trace[1017242957] 'compare' (duration: 29.400075ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:33.619101Z","caller":"traceutil/trace.go:171","msg":"trace[310459281] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2144; }","duration":"102.562788ms","start":"2026-04-27T21:03:33.516507Z","end":"2026-04-27T21:03:33.61907Z","steps":["trace[310459281] 'read index received' (duration: 72.858237ms)","trace[310459281] 'applied index is now lower than readState.Index' (duration: 29.702221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:03:33.619381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.857494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12381"} {"level":"info","ts":"2026-04-27T21:03:33.619438Z","caller":"traceutil/trace.go:171","msg":"trace[1646976923] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2063; }","duration":"102.979946ms","start":"2026-04-27T21:03:33.516441Z","end":"2026-04-27T21:03:33.619421Z","steps":["trace[1646976923] 'agreement among raft nodes before linearized reading' (duration: 102.776802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:39.573008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.018084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:03:39.573345Z","caller":"traceutil/trace.go:171","msg":"trace[1117205411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"151.203288ms","start":"2026-04-27T21:03:39.421887Z","end":"2026-04-27T21:03:39.573091Z","steps":["trace[1117205411] 'range keys from in-memory index tree' (duration: 150.924382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:04:29.590315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.184279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:04:29.590436Z","caller":"traceutil/trace.go:171","msg":"trace[1650278968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2533; }","duration":"167.337631ms","start":"2026-04-27T21:04:29.423068Z","end":"2026-04-27T21:04:29.590406Z","steps":["trace[1650278968] 'range keys from in-memory index tree' (duration: 167.135708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:05:04.049102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.999435ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:05:04.049218Z","caller":"traceutil/trace.go:171","msg":"trace[1361398485] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"186.386295ms","start":"2026-04-27T21:05:03.86278Z","end":"2026-04-27T21:05:04.049167Z","steps":["trace[1361398485] 'process raft request' (duration: 156.031129ms)","trace[1361398485] 'compare' (duration: 30.215304ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:05:04.04943Z","caller":"traceutil/trace.go:171","msg":"trace[46519429] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2740; }","duration":"111.345022ms","start":"2026-04-27T21:05:03.938066Z","end":"2026-04-27T21:05:04.049411Z","steps":["trace[46519429] 'range keys from in-memory index tree' (duration: 110.981225ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:44.471902Z","caller":"traceutil/trace.go:171","msg":"trace[1680570314] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"111.091891ms","start":"2026-04-27T21:08:44.360788Z","end":"2026-04-27T21:08:44.47188Z","steps":["trace[1680570314] 'process raft request' (duration: 110.955089ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:44.499212Z","caller":"traceutil/trace.go:171","msg":"trace[1112000685] linearizableReadLoop","detail":"{readStateIndex:4910; appliedIndex:4908; }","duration":"131.416736ms","start":"2026-04-27T21:08:44.36777Z","end":"2026-04-27T21:08:44.499187Z","steps":["trace[1112000685] 'read index received' (duration: 103.986787ms)","trace[1112000685] 'applied index is now lower than readState.Index' (duration: 27.428279ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:08:44.499235Z","caller":"traceutil/trace.go:171","msg":"trace[1455518848] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"134.790241ms","start":"2026-04-27T21:08:44.364415Z","end":"2026-04-27T21:08:44.499205Z","steps":["trace[1455518848] 'process raft request' (duration: 134.571636ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:44.49933Z","caller":"traceutil/trace.go:171","msg":"trace[1684264696] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"129.873218ms","start":"2026-04-27T21:08:44.369447Z","end":"2026-04-27T21:08:44.49932Z","steps":["trace[1684264696] 'process raft request' (duration: 129.684614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:08:44.49957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.410975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-27T21:08:44.499639Z","caller":"traceutil/trace.go:171","msg":"trace[480095077] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4728; }","duration":"114.527487ms","start":"2026-04-27T21:08:44.385093Z","end":"2026-04-27T21:08:44.499621Z","steps":["trace[480095077] 'agreement among raft nodes before linearized reading' (duration: 114.272973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:08:44.499854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.067438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-04-27T21:08:44.499886Z","caller":"traceutil/trace.go:171","msg":"trace[1886945528] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4728; }","duration":"132.12867ms","start":"2026-04-27T21:08:44.367747Z","end":"2026-04-27T21:08:44.499875Z","steps":["trace[1886945528] 'agreement among raft nodes before linearized reading' (duration: 132.048489ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:49.097945Z","caller":"traceutil/trace.go:171","msg":"trace[1602407695] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"147.575442ms","start":"2026-04-27T21:08:48.950347Z","end":"2026-04-27T21:08:49.097923Z","steps":["trace[1602407695] 'process raft request' (duration: 147.526641ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:49.097916Z","caller":"traceutil/trace.go:171","msg":"trace[374476019] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"149.340716ms","start":"2026-04-27T21:08:48.94854Z","end":"2026-04-27T21:08:49.097881Z","steps":["trace[374476019] 'process raft request' (duration: 125.522136ms)","trace[374476019] 'compare' (duration: 23.539085ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:08:49.098064Z","caller":"traceutil/trace.go:171","msg":"trace[1174630520] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"148.590522ms","start":"2026-04-27T21:08:48.949433Z","end":"2026-04-27T21:08:49.098024Z","steps":["trace[1174630520] 'process raft request' (duration: 148.350838ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:49.273886Z","caller":"traceutil/trace.go:171","msg":"trace[1494935785] linearizableReadLoop","detail":"{readStateIndex:5068; appliedIndex:5067; }","duration":"164.094755ms","start":"2026-04-27T21:08:49.109768Z","end":"2026-04-27T21:08:49.273863Z","steps":["trace[1494935785] 'read index received' (duration: 118.972681ms)","trace[1494935785] 'applied index is now lower than readState.Index' (duration: 45.120834ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:08:49.273928Z","caller":"traceutil/trace.go:171","msg":"trace[702749918] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"168.521279ms","start":"2026-04-27T21:08:49.105369Z","end":"2026-04-27T21:08:49.27389Z","steps":["trace[702749918] 'process raft request' (duration: 123.358744ms)","trace[702749918] 'compare' (duration: 45.012152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:08:49.274175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.389441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84c89290-c0ba-44f0-954e-01f5341b0be2\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-27T21:08:49.274381Z","caller":"traceutil/trace.go:171","msg":"trace[1048011198] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84c89290-c0ba-44f0-954e-01f5341b0be2; range_end:; response_count:1; response_revision:4881; }","duration":"164.595204ms","start":"2026-04-27T21:08:49.109756Z","end":"2026-04-27T21:08:49.274352Z","steps":["trace[1048011198] 'agreement among raft nodes before linearized reading' (duration: 164.243858ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:49.278191Z","caller":"traceutil/trace.go:171","msg":"trace[125334028] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"160.957215ms","start":"2026-04-27T21:08:49.117179Z","end":"2026-04-27T21:08:49.278136Z","steps":["trace[125334028] 'process raft request' (duration: 160.751022ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:49.645911Z","caller":"traceutil/trace.go:171","msg":"trace[141195468] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"102.020041ms","start":"2026-04-27T21:08:49.543868Z","end":"2026-04-27T21:08:49.645888Z","steps":["trace[141195468] 'process raft request' (duration: 101.885218ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:07.903969Z","caller":"traceutil/trace.go:171","msg":"trace[600211003] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"121.100197ms","start":"2026-04-27T21:09:07.78283Z","end":"2026-04-27T21:09:07.90393Z","steps":["trace[600211003] 'process raft request' (duration: 120.79651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:09:07.90418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.268503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T21:09:07.904517Z","caller":"traceutil/trace.go:171","msg":"trace[1164389750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5260; }","duration":"100.544228ms","start":"2026-04-27T21:09:07.803769Z","end":"2026-04-27T21:09:07.904313Z","steps":["trace[1164389750] 'agreement among raft nodes before linearized reading' (duration: 100.09361ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:09:08.088027Z","caller":"traceutil/trace.go:171","msg":"trace[1289531181] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"178.139472ms","start":"2026-04-27T21:09:07.909657Z","end":"2026-04-27T21:09:08.087796Z","steps":["trace[1289531181] 'process raft request' (duration: 130.596335ms)","trace[1289531181] 'compare' (duration: 47.1694ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:10:44.002174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-04-27T21:10:44.07211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"67.827711ms","hash":1780641683,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":34287616,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-27T21:10:44.072166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1780641683,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-04-27T21:15:02.337434Z","caller":"traceutil/trace.go:171","msg":"trace[1079337845] transaction","detail":"{read_only:false; response_revision:8040; number_of_response:1; }","duration":"179.764956ms","start":"2026-04-27T21:15:02.157647Z","end":"2026-04-27T21:15:02.337412Z","steps":["trace[1079337845] 'process raft request' (duration: 179.637643ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:02.339404Z","caller":"traceutil/trace.go:171","msg":"trace[115279355] transaction","detail":"{read_only:false; response_revision:8041; number_of_response:1; }","duration":"178.825759ms","start":"2026-04-27T21:15:02.160534Z","end":"2026-04-27T21:15:02.33936Z","steps":["trace[115279355] 'process raft request' (duration: 178.717487ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:13.904144Z","caller":"traceutil/trace.go:171","msg":"trace[1595671099] transaction","detail":"{read_only:false; response_revision:8097; number_of_response:1; }","duration":"121.565383ms","start":"2026-04-27T21:15:13.78255Z","end":"2026-04-27T21:15:13.904116Z","steps":["trace[1595671099] 'process raft request' (duration: 121.345559ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:14.525719Z","caller":"traceutil/trace.go:171","msg":"trace[707927211] linearizableReadLoop","detail":"{readStateIndex:8387; appliedIndex:8385; }","duration":"103.715708ms","start":"2026-04-27T21:15:14.421977Z","end":"2026-04-27T21:15:14.525692Z","steps":["trace[707927211] 'read index received' (duration: 36.675629ms)","trace[707927211] 'applied index is now lower than readState.Index' (duration: 67.039079ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:14.525773Z","caller":"traceutil/trace.go:171","msg":"trace[1285944153] transaction","detail":"{read_only:false; response_revision:8101; number_of_response:1; }","duration":"143.934803ms","start":"2026-04-27T21:15:14.38181Z","end":"2026-04-27T21:15:14.525745Z","steps":["trace[1285944153] 'process raft request' (duration: 140.379697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:14.526042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.049165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:15:14.526089Z","caller":"traceutil/trace.go:171","msg":"trace[29447115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8101; }","duration":"104.157807ms","start":"2026-04-27T21:15:14.421921Z","end":"2026-04-27T21:15:14.526078Z","steps":["trace[29447115] 'agreement among raft nodes before linearized reading' (duration: 103.875521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:15.158704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.735518ms","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-04-27T21:15:15.158794Z","caller":"traceutil/trace.go:171","msg":"trace[1322146585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8103; }","duration":"160.847611ms","start":"2026-04-27T21:15:14.997927Z","end":"2026-04-27T21:15:15.158775Z","steps":["trace[1322146585] 'range keys from in-memory index tree' (duration: 160.495754ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:43.437135Z","caller":"traceutil/trace.go:171","msg":"trace[514167519] linearizableReadLoop","detail":"{readStateIndex:8605; appliedIndex:8604; }","duration":"130.722335ms","start":"2026-04-27T21:15:43.306316Z","end":"2026-04-27T21:15:43.437039Z","steps":["trace[514167519] 'read index received' (duration: 130.577792ms)","trace[514167519] 'applied index is now lower than readState.Index' (duration: 143.893µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:43.437356Z","caller":"traceutil/trace.go:171","msg":"trace[942992123] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"136.450254ms","start":"2026-04-27T21:15:43.300897Z","end":"2026-04-27T21:15:43.437347Z","steps":["trace[942992123] 'process raft request' (duration: 136.040365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:43.437567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.236965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:15:43.437597Z","caller":"traceutil/trace.go:171","msg":"trace[1792490145] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8310; }","duration":"131.315907ms","start":"2026-04-27T21:15:43.306275Z","end":"2026-04-27T21:15:43.437591Z","steps":["trace[1792490145] 'agreement among raft nodes before linearized reading' (duration: 131.199265ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:44.015149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6108} {"level":"info","ts":"2026-04-27T21:15:44.157596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6108,"took":"140.380036ms","hash":2204785836,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":23252992,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-27T21:15:44.157676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2204785836,"revision":6108,"compact-revision":2958} {"level":"info","ts":"2026-04-27T21:15:45.621359Z","caller":"traceutil/trace.go:171","msg":"trace[363559664] linearizableReadLoop","detail":"{readStateIndex:8618; appliedIndex:8617; }","duration":"162.35644ms","start":"2026-04-27T21:15:45.458978Z","end":"2026-04-27T21:15:45.621334Z","steps":["trace[363559664] 'read index received' (duration: 162.256118ms)","trace[363559664] 'applied index is now lower than readState.Index' (duration: 99.492µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:45.621526Z","caller":"traceutil/trace.go:171","msg":"trace[1792134137] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"179.071684ms","start":"2026-04-27T21:15:45.442446Z","end":"2026-04-27T21:15:45.621518Z","steps":["trace[1792134137] 'process raft request' (duration: 178.738197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:45.621762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.419182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-758b79c7f-nwcw5.18aa533644eef922\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-04-27T21:15:45.621831Z","caller":"traceutil/trace.go:171","msg":"trace[110055064] range","detail":"{range_begin:/registry/events/openstack/barbican-api-758b79c7f-nwcw5.18aa533644eef922; range_end:; response_count:1; response_revision:8322; }","duration":"112.530224ms","start":"2026-04-27T21:15:45.509283Z","end":"2026-04-27T21:15:45.621813Z","steps":["trace[110055064] 'agreement among raft nodes before linearized reading' (duration: 112.360311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:45.622111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.086164ms","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-04-27T21:15:45.622241Z","caller":"traceutil/trace.go:171","msg":"trace[425052833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8322; }","duration":"163.299278ms","start":"2026-04-27T21:15:45.458913Z","end":"2026-04-27T21:15:45.622213Z","steps":["trace[425052833] 'agreement among raft nodes before linearized reading' (duration: 162.918251ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:45.781017Z","caller":"traceutil/trace.go:171","msg":"trace[488203261] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"155.036591ms","start":"2026-04-27T21:15:45.62592Z","end":"2026-04-27T21:15:45.780957Z","steps":["trace[488203261] 'process raft request' (duration: 154.943639ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:45.781029Z","caller":"traceutil/trace.go:171","msg":"trace[258950659] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"156.332486ms","start":"2026-04-27T21:15:45.624668Z","end":"2026-04-27T21:15:45.781001Z","steps":["trace[258950659] 'process raft request' (duration: 153.993383ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:45.781203Z","caller":"traceutil/trace.go:171","msg":"trace[336372354] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"154.074304ms","start":"2026-04-27T21:15:45.627111Z","end":"2026-04-27T21:15:45.781186Z","steps":["trace[336372354] 'process raft request' (duration: 153.981682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:55.64268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.460125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:15:55.642784Z","caller":"traceutil/trace.go:171","msg":"trace[1503054230] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8713; }","duration":"138.913649ms","start":"2026-04-27T21:15:55.503854Z","end":"2026-04-27T21:15:55.642767Z","steps":["trace[1503054230] 'read index received' (duration: 36.027776ms)","trace[1503054230] 'applied index is now lower than readState.Index' (duration: 102.884253ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:55.642819Z","caller":"traceutil/trace.go:171","msg":"trace[768297291] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"158.758921ms","start":"2026-04-27T21:15:55.484018Z","end":"2026-04-27T21:15:55.642777Z","steps":["trace[768297291] 'process raft request' (duration: 55.981691ms)","trace[768297291] 'compare' (duration: 102.139968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:15:55.643511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.642252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-758b79c7f-nwcw5.18aa533644eef922\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-04-27T21:15:55.64366Z","caller":"traceutil/trace.go:171","msg":"trace[1971554874] range","detail":"{range_begin:/registry/events/openstack/barbican-api-758b79c7f-nwcw5.18aa533644eef922; range_end:; response_count:1; response_revision:8414; }","duration":"139.829776ms","start":"2026-04-27T21:15:55.503767Z","end":"2026-04-27T21:15:55.643597Z","steps":["trace[1971554874] 'agreement among raft nodes before linearized reading' (duration: 139.044801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:56.183754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.916743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:15:56.184173Z","caller":"traceutil/trace.go:171","msg":"trace[252224116] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8422; }","duration":"103.376072ms","start":"2026-04-27T21:15:56.080767Z","end":"2026-04-27T21:15:56.184143Z","steps":["trace[252224116] 'count revisions from in-memory index tree' (duration: 102.805271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:56.184263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.944534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:15:56.184945Z","caller":"traceutil/trace.go:171","msg":"trace[596441721] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8422; }","duration":"111.691338ms","start":"2026-04-27T21:15:56.07323Z","end":"2026-04-27T21:15:56.184922Z","steps":["trace[596441721] 'count revisions from in-memory index tree' (duration: 110.870433ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:56.339683Z","caller":"traceutil/trace.go:171","msg":"trace[744230330] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"150.535148ms","start":"2026-04-27T21:15:56.189116Z","end":"2026-04-27T21:15:56.339652Z","steps":["trace[744230330] 'process raft request' (duration: 103.957033ms)","trace[744230330] 'compare' (duration: 46.380232ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:56.33989Z","caller":"traceutil/trace.go:171","msg":"trace[837578318] transaction","detail":"{read_only:false; response_revision:8425; number_of_response:1; }","duration":"150.400856ms","start":"2026-04-27T21:15:56.189437Z","end":"2026-04-27T21:15:56.339837Z","steps":["trace[837578318] 'process raft request' (duration: 150.165261ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:56.844659Z","caller":"traceutil/trace.go:171","msg":"trace[1725836776] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"101.70808ms","start":"2026-04-27T21:15:56.74292Z","end":"2026-04-27T21:15:56.844628Z","steps":["trace[1725836776] 'process raft request' (duration: 101.465975ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:16:45.261536Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002403.wal"} {"level":"info","ts":"2026-04-27T21:18:22.318752Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"19a81b20c4b111c3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:18:22.326157Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-27T21:18:22.326277Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-27T21:20:28.762456Z","caller":"traceutil/trace.go:171","msg":"trace[809379291] transaction","detail":"{read_only:false; response_revision:10873; number_of_response:1; }","duration":"122.509874ms","start":"2026-04-27T21:20:28.639908Z","end":"2026-04-27T21:20:28.762418Z","steps":["trace[809379291] 'process raft request' (duration: 122.154868ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:20:44.021287Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8314} {"level":"info","ts":"2026-04-27T21:20:44.099732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8314,"took":"76.190712ms","hash":1158672608,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":26726400,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-27T21:20:44.099809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1158672608,"revision":8314,"compact-revision":6108} {"level":"info","ts":"2026-04-27T21:25:44.027936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10962} {"level":"info","ts":"2026-04-27T21:25:44.108932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10962,"took":"78.278319ms","hash":2882424375,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":27844608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-27T21:25:44.109021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2882424375,"revision":10962,"compact-revision":8314} {"level":"info","ts":"2026-04-27T21:26:54.258197Z","caller":"traceutil/trace.go:171","msg":"trace[379797725] transaction","detail":"{read_only:false; response_revision:13464; number_of_response:1; }","duration":"127.059755ms","start":"2026-04-27T21:26:54.131097Z","end":"2026-04-27T21:26:54.258157Z","steps":["trace[379797725] 'process raft request' (duration: 126.77062ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:10.627118Z","caller":"traceutil/trace.go:171","msg":"trace[1193640469] transaction","detail":"{read_only:false; response_revision:13561; number_of_response:1; }","duration":"351.76539ms","start":"2026-04-27T21:27:10.275316Z","end":"2026-04-27T21:27:10.627081Z","steps":["trace[1193640469] 'process raft request' (duration: 351.553746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:27:10.627322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:27:10.275303Z","time spent":"351.921423ms","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:27:10.63539Z","caller":"traceutil/trace.go:171","msg":"trace[905308817] linearizableReadLoop","detail":"{readStateIndex:14025; appliedIndex:14023; }","duration":"291.351497ms","start":"2026-04-27T21:27:10.344002Z","end":"2026-04-27T21:27:10.635353Z","steps":["trace[905308817] 'read index received' (duration: 282.969449ms)","trace[905308817] 'applied index is now lower than readState.Index' (duration: 8.380928ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:27:10.635789Z","caller":"traceutil/trace.go:171","msg":"trace[1773553348] transaction","detail":"{read_only:false; response_revision:13562; number_of_response:1; }","duration":"325.277093ms","start":"2026-04-27T21:27:10.310495Z","end":"2026-04-27T21:27:10.635772Z","steps":["trace[1773553348] 'process raft request' (duration: 324.704433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:27:10.635855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.987586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T21:27:10.635894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:27:10.310465Z","time spent":"325.363114ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:27:10.635913Z","caller":"traceutil/trace.go:171","msg":"trace[432329450] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:13562; }","duration":"189.065077ms","start":"2026-04-27T21:27:10.446831Z","end":"2026-04-27T21:27:10.635896Z","steps":["trace[432329450] 'agreement among raft nodes before linearized reading' (duration: 188.958025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:27:10.635915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.875566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-27T21:27:10.635909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.423063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:27:10.636022Z","caller":"traceutil/trace.go:171","msg":"trace[730201495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13562; }","duration":"292.049039ms","start":"2026-04-27T21:27:10.343948Z","end":"2026-04-27T21:27:10.635997Z","steps":["trace[730201495] 'agreement among raft nodes before linearized reading' (duration: 291.717473ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:10.636032Z","caller":"traceutil/trace.go:171","msg":"trace[433079392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13562; }","duration":"214.626086ms","start":"2026-04-27T21:27:10.421383Z","end":"2026-04-27T21:27:10.636009Z","steps":["trace[433079392] 'agreement among raft nodes before linearized reading' (duration: 214.398642ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:31.440798Z","caller":"traceutil/trace.go:171","msg":"trace[1094405343] transaction","detail":"{read_only:false; response_revision:13862; number_of_response:1; }","duration":"219.597082ms","start":"2026-04-27T21:27:31.221167Z","end":"2026-04-27T21:27:31.440764Z","steps":["trace[1094405343] 'process raft request' (duration: 219.391198ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:31.445759Z","caller":"traceutil/trace.go:171","msg":"trace[543040978] transaction","detail":"{read_only:false; response_revision:13863; number_of_response:1; }","duration":"223.089387ms","start":"2026-04-27T21:27:31.222642Z","end":"2026-04-27T21:27:31.445732Z","steps":["trace[543040978] 'process raft request' (duration: 222.963005ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:28:46.14692Z","caller":"traceutil/trace.go:171","msg":"trace[446328596] linearizableReadLoop","detail":"{readStateIndex:15111; appliedIndex:15110; }","duration":"109.641024ms","start":"2026-04-27T21:28:46.037252Z","end":"2026-04-27T21:28:46.146893Z","steps":["trace[446328596] 'read index received' (duration: 108.588084ms)","trace[446328596] 'applied index is now lower than readState.Index' (duration: 1.0518ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:28:46.146998Z","caller":"traceutil/trace.go:171","msg":"trace[1828803982] transaction","detail":"{read_only:false; response_revision:14615; number_of_response:1; }","duration":"116.781448ms","start":"2026-04-27T21:28:46.030186Z","end":"2026-04-27T21:28:46.146967Z","steps":["trace[1828803982] 'process raft request' (duration: 115.766619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:28:46.147093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.814357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:28:46.14713Z","caller":"traceutil/trace.go:171","msg":"trace[1377384283] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:14615; }","duration":"109.880659ms","start":"2026-04-27T21:28:46.037237Z","end":"2026-04-27T21:28:46.147118Z","steps":["trace[1377384283] 'agreement among raft nodes before linearized reading' (duration: 109.799987ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:28:52.718639Z","caller":"traceutil/trace.go:171","msg":"trace[567355914] transaction","detail":"{read_only:false; response_revision:14647; number_of_response:1; }","duration":"142.289625ms","start":"2026-04-27T21:28:52.576324Z","end":"2026-04-27T21:28:52.718614Z","steps":["trace[567355914] 'process raft request' (duration: 141.682744ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:44.033927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12995} {"level":"info","ts":"2026-04-27T21:30:44.115009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12995,"took":"71.299195ms","hash":2313986853,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-27T21:30:44.115071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2313986853,"revision":12995,"compact-revision":10962} {"level":"info","ts":"2026-04-27T21:34:13.441345Z","caller":"traceutil/trace.go:171","msg":"trace[823151022] transaction","detail":"{read_only:false; response_revision:17080; number_of_response:1; }","duration":"163.623596ms","start":"2026-04-27T21:34:13.277696Z","end":"2026-04-27T21:34:13.44132Z","steps":["trace[823151022] 'process raft request' (duration: 161.764402ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:34:19.691515Z","caller":"traceutil/trace.go:171","msg":"trace[1918325235] transaction","detail":"{read_only:false; response_revision:17126; number_of_response:1; }","duration":"214.500849ms","start":"2026-04-27T21:34:19.476973Z","end":"2026-04-27T21:34:19.691474Z","steps":["trace[1918325235] 'process raft request' (duration: 200.349164ms)","trace[1918325235] 'compare' (duration: 13.988542ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:34:19.930316Z","caller":"traceutil/trace.go:171","msg":"trace[1149102076] linearizableReadLoop","detail":"{readStateIndex:17706; appliedIndex:17705; }","duration":"198.048262ms","start":"2026-04-27T21:34:19.732239Z","end":"2026-04-27T21:34:19.930287Z","steps":["trace[1149102076] 'read index received' (duration: 197.820778ms)","trace[1149102076] 'applied index is now lower than readState.Index' (duration: 226.404µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:34:19.930462Z","caller":"traceutil/trace.go:171","msg":"trace[41453478] transaction","detail":"{read_only:false; response_revision:17127; number_of_response:1; }","duration":"234.446944ms","start":"2026-04-27T21:34:19.695962Z","end":"2026-04-27T21:34:19.930409Z","steps":["trace[41453478] 'process raft request' (duration: 234.168219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:34:19.930574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.313197ms","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-04-27T21:34:19.930653Z","caller":"traceutil/trace.go:171","msg":"trace[46516240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17127; }","duration":"198.435949ms","start":"2026-04-27T21:34:19.732196Z","end":"2026-04-27T21:34:19.930632Z","steps":["trace[46516240] 'agreement among raft nodes before linearized reading' (duration: 198.231196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:34:19.930566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.214617ms","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-04-27T21:34:19.930751Z","caller":"traceutil/trace.go:171","msg":"trace[203848921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17127; }","duration":"149.425341ms","start":"2026-04-27T21:34:19.781295Z","end":"2026-04-27T21:34:19.93072Z","steps":["trace[203848921] 'agreement among raft nodes before linearized reading' (duration: 149.135476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:34:19.930761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.487926ms","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-04-27T21:34:19.93087Z","caller":"traceutil/trace.go:171","msg":"trace[260370689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17127; }","duration":"138.640968ms","start":"2026-04-27T21:34:19.792181Z","end":"2026-04-27T21:34:19.930822Z","steps":["trace[260370689] 'agreement among raft nodes before linearized reading' (duration: 138.328702ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:35:44.040961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15425} {"level":"info","ts":"2026-04-27T21:35:44.138931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15425,"took":"92.809829ms","hash":2852879435,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":34045952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-27T21:35:44.138993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2852879435,"revision":15425,"compact-revision":12995} {"level":"info","ts":"2026-04-27T21:38:41.223702Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"19a81b20c4b111c3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:38:41.228011Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-27T21:38:41.228122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-04-27T21:39:44.492633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.226914ms","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-04-27T21:39:44.492762Z","caller":"traceutil/trace.go:171","msg":"trace[732348917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19696; }","duration":"135.426948ms","start":"2026-04-27T21:39:44.357309Z","end":"2026-04-27T21:39:44.492736Z","steps":["trace[732348917] 'range keys from in-memory index tree' (duration: 135.076631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:39:44.492784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.740762ms","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-04-27T21:39:44.492937Z","caller":"traceutil/trace.go:171","msg":"trace[1203827813] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19696; }","duration":"121.912415ms","start":"2026-04-27T21:39:44.370999Z","end":"2026-04-27T21:39:44.492912Z","steps":["trace[1203827813] 'count revisions from in-memory index tree' (duration: 121.64701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:39:44.815907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.005921ms","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-04-27T21:39:44.816009Z","caller":"traceutil/trace.go:171","msg":"trace[1832681878] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19697; }","duration":"198.146584ms","start":"2026-04-27T21:39:44.617842Z","end":"2026-04-27T21:39:44.815989Z","steps":["trace[1832681878] 'count revisions from in-memory index tree' (duration: 197.900749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:39:44.816046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.688027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-04-27T21:39:44.816109Z","caller":"traceutil/trace.go:171","msg":"trace[2070539991] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19697; }","duration":"171.79703ms","start":"2026-04-27T21:39:44.644295Z","end":"2026-04-27T21:39:44.816092Z","steps":["trace[2070539991] 'range keys from in-memory index tree' (duration: 171.536635ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:39:44.943709Z","caller":"traceutil/trace.go:171","msg":"trace[1872949539] transaction","detail":"{read_only:false; response_revision:19698; number_of_response:1; }","duration":"124.042144ms","start":"2026-04-27T21:39:44.819642Z","end":"2026-04-27T21:39:44.943685Z","steps":["trace[1872949539] 'process raft request' (duration: 123.886991ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:39:45.514146Z","caller":"traceutil/trace.go:171","msg":"trace[705358398] linearizableReadLoop","detail":"{readStateIndex:20363; appliedIndex:20362; }","duration":"153.703971ms","start":"2026-04-27T21:39:45.360403Z","end":"2026-04-27T21:39:45.514107Z","steps":["trace[705358398] 'read index received' (duration: 153.428065ms)","trace[705358398] 'applied index is now lower than readState.Index' (duration: 273.996µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:39:45.514335Z","caller":"traceutil/trace.go:171","msg":"trace[976983266] transaction","detail":"{read_only:false; response_revision:19699; number_of_response:1; }","duration":"174.117414ms","start":"2026-04-27T21:39:45.340203Z","end":"2026-04-27T21:39:45.51432Z","steps":["trace[976983266] 'process raft request' (duration: 173.659324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:39:45.514743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.300031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:39:45.514867Z","caller":"traceutil/trace.go:171","msg":"trace[86335075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19699; }","duration":"154.457425ms","start":"2026-04-27T21:39:45.360362Z","end":"2026-04-27T21:39:45.51482Z","steps":["trace[86335075] 'agreement among raft nodes before linearized reading' (duration: 154.130709ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:39:45.731247Z","caller":"traceutil/trace.go:171","msg":"trace[1728424709] linearizableReadLoop","detail":"{readStateIndex:20365; appliedIndex:20364; }","duration":"181.190286ms","start":"2026-04-27T21:39:45.550018Z","end":"2026-04-27T21:39:45.731208Z","steps":["trace[1728424709] 'read index received' (duration: 121.08732ms)","trace[1728424709] 'applied index is now lower than readState.Index' (duration: 60.101716ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:39:45.731414Z","caller":"traceutil/trace.go:171","msg":"trace[819174353] transaction","detail":"{read_only:false; response_revision:19701; number_of_response:1; }","duration":"212.455822ms","start":"2026-04-27T21:39:45.518944Z","end":"2026-04-27T21:39:45.7314Z","steps":["trace[819174353] 'process raft request' (duration: 152.152302ms)","trace[819174353] 'compare' (duration: 59.919563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:39:45.731862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.824848ms","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-04-27T21:39:45.731911Z","caller":"traceutil/trace.go:171","msg":"trace[578326247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19701; }","duration":"181.92085ms","start":"2026-04-27T21:39:45.549978Z","end":"2026-04-27T21:39:45.731899Z","steps":["trace[578326247] 'agreement among raft nodes before linearized reading' (duration: 181.673985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:39:45.732023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.586659ms","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-04-27T21:39:45.732079Z","caller":"traceutil/trace.go:171","msg":"trace[120973529] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19701; }","duration":"177.687171ms","start":"2026-04-27T21:39:45.554374Z","end":"2026-04-27T21:39:45.732062Z","steps":["trace[120973529] 'agreement among raft nodes before linearized reading' (duration: 177.547218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:39:45.732255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.708764ms","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-04-27T21:39:45.733541Z","caller":"traceutil/trace.go:171","msg":"trace[1554133653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19701; }","duration":"183.03817ms","start":"2026-04-27T21:39:45.550478Z","end":"2026-04-27T21:39:45.733516Z","steps":["trace[1554133653] 'agreement among raft nodes before linearized reading' (duration: 181.375259ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:40:44.04686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18032} {"level":"info","ts":"2026-04-27T21:40:44.176287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18032,"took":"123.742071ms","hash":972682840,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-27T21:40:44.176371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":972682840,"revision":18032,"compact-revision":15425} {"level":"info","ts":"2026-04-27T21:45:44.055635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20304} {"level":"info","ts":"2026-04-27T21:45:44.163374Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20304,"took":"103.196256ms","hash":1337804391,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":42618880,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-27T21:45:44.163444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1337804391,"revision":20304,"compact-revision":18032} {"level":"info","ts":"2026-04-27T21:46:37.470034Z","caller":"traceutil/trace.go:171","msg":"trace[1426396051] transaction","detail":"{read_only:false; response_revision:24054; number_of_response:1; }","duration":"107.226592ms","start":"2026-04-27T21:46:37.362777Z","end":"2026-04-27T21:46:37.470004Z","steps":["trace[1426396051] 'process raft request' (duration: 101.752989ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:37.905221Z","caller":"traceutil/trace.go:171","msg":"trace[1050569269] transaction","detail":"{read_only:false; response_revision:24055; number_of_response:1; }","duration":"220.874353ms","start":"2026-04-27T21:46:37.684319Z","end":"2026-04-27T21:46:37.905194Z","steps":["trace[1050569269] 'process raft request' (duration: 220.70166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:38.041728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.582853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:46:38.04206Z","caller":"traceutil/trace.go:171","msg":"trace[227298716] transaction","detail":"{read_only:false; response_revision:24056; number_of_response:1; }","duration":"357.556317ms","start":"2026-04-27T21:46:37.684449Z","end":"2026-04-27T21:46:38.042005Z","steps":["trace[227298716] 'process raft request' (duration: 245.577257ms)","trace[227298716] 'compare' (duration: 111.39867ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:38.042359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:37.684428Z","time spent":"357.740091ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:38.042463Z","caller":"traceutil/trace.go:171","msg":"trace[629349585] transaction","detail":"{read_only:false; response_revision:24057; number_of_response:1; }","duration":"355.290415ms","start":"2026-04-27T21:46:37.687107Z","end":"2026-04-27T21:46:38.042397Z","steps":["trace[629349585] 'process raft request' (duration: 354.757425ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:38.042556Z","caller":"traceutil/trace.go:171","msg":"trace[1787225467] transaction","detail":"{read_only:false; response_revision:24058; number_of_response:1; }","duration":"178.700503ms","start":"2026-04-27T21:46:37.863763Z","end":"2026-04-27T21:46:38.042464Z","steps":["trace[1787225467] 'process raft request' (duration: 178.188572ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:38.042429Z","caller":"traceutil/trace.go:171","msg":"trace[901338602] linearizableReadLoop","detail":"{readStateIndex:24844; appliedIndex:24841; }","duration":"336.132945ms","start":"2026-04-27T21:46:37.706268Z","end":"2026-04-27T21:46:38.042401Z","steps":["trace[901338602] 'read index received' (duration: 198.780229ms)","trace[901338602] 'applied index is now lower than readState.Index' (duration: 137.350986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:38.042657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:37.687081Z","time spent":"355.482458ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:46:38.042638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.333379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:38.042746Z","caller":"traceutil/trace.go:171","msg":"trace[10907961] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24058; }","duration":"336.506092ms","start":"2026-04-27T21:46:37.706214Z","end":"2026-04-27T21:46:38.04272Z","steps":["trace[10907961] 'agreement among raft nodes before linearized reading' (duration: 336.304159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:38.04285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:37.70619Z","time spent":"336.647174ms","remote":"127.0.0.1:51768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:46:38.043135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.218297ms","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-04-27T21:46:38.043162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.224638ms","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-04-27T21:46:38.043206Z","caller":"traceutil/trace.go:171","msg":"trace[1196166160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24058; }","duration":"158.29914ms","start":"2026-04-27T21:46:37.884894Z","end":"2026-04-27T21:46:38.043193Z","steps":["trace[1196166160] 'agreement among raft nodes before linearized reading' (duration: 158.114486ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:38.043193Z","caller":"traceutil/trace.go:171","msg":"trace[352672923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24058; }","duration":"142.302358ms","start":"2026-04-27T21:46:37.900874Z","end":"2026-04-27T21:46:38.043176Z","steps":["trace[352672923] 'agreement among raft nodes before linearized reading' (duration: 142.102765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:38.043366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.131468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:46:38.043446Z","caller":"traceutil/trace.go:171","msg":"trace[2069709977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24058; }","duration":"317.2381ms","start":"2026-04-27T21:46:37.726184Z","end":"2026-04-27T21:46:38.043422Z","steps":["trace[2069709977] 'agreement among raft nodes before linearized reading' (duration: 316.71472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:38.043496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:37.726133Z","time spent":"317.354322ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T21:46:42.788908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.589115ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T21:46:42.789057Z","caller":"traceutil/trace.go:171","msg":"trace[1156876002] linearizableReadLoop","detail":"{readStateIndex:24887; appliedIndex:24886; }","duration":"197.77913ms","start":"2026-04-27T21:46:42.591253Z","end":"2026-04-27T21:46:42.789032Z","steps":["trace[1156876002] 'read index received' (duration: 24.82µs)","trace[1156876002] 'applied index is now lower than readState.Index' (duration: 197.75194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:42.789324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.062895ms","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-04-27T21:46:42.789401Z","caller":"traceutil/trace.go:171","msg":"trace[2042174213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24099; }","duration":"198.180527ms","start":"2026-04-27T21:46:42.591203Z","end":"2026-04-27T21:46:42.789383Z","steps":["trace[2042174213] 'agreement among raft nodes before linearized reading' (duration: 197.949643ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:42.914413Z","caller":"traceutil/trace.go:171","msg":"trace[2049207419] transaction","detail":"{read_only:false; response_revision:24100; number_of_response:1; }","duration":"120.695534ms","start":"2026-04-27T21:46:42.793695Z","end":"2026-04-27T21:46:42.914391Z","steps":["trace[2049207419] 'process raft request' (duration: 120.542961ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:43.20018Z","caller":"traceutil/trace.go:171","msg":"trace[1351936233] transaction","detail":"{read_only:false; response_revision:24101; number_of_response:1; }","duration":"174.701966ms","start":"2026-04-27T21:46:43.025445Z","end":"2026-04-27T21:46:43.200147Z","steps":["trace[1351936233] 'process raft request' (duration: 174.499912ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:43.619295Z","caller":"traceutil/trace.go:171","msg":"trace[1983580253] transaction","detail":"{read_only:false; response_revision:24102; number_of_response:1; }","duration":"129.468659ms","start":"2026-04-27T21:46:43.489806Z","end":"2026-04-27T21:46:43.619274Z","steps":["trace[1983580253] 'process raft request' (duration: 129.280646ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:44.024255Z","caller":"traceutil/trace.go:171","msg":"trace[1441900609] transaction","detail":"{read_only:false; response_revision:24103; number_of_response:1; }","duration":"100.75771ms","start":"2026-04-27T21:46:43.923452Z","end":"2026-04-27T21:46:44.02421Z","steps":["trace[1441900609] 'process raft request' (duration: 100.422474ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:44.170387Z","caller":"traceutil/trace.go:171","msg":"trace[1804201935] transaction","detail":"{read_only:false; response_revision:24105; number_of_response:1; }","duration":"106.891334ms","start":"2026-04-27T21:46:44.063463Z","end":"2026-04-27T21:46:44.170354Z","steps":["trace[1804201935] 'process raft request' (duration: 93.746668ms)","trace[1804201935] 'compare' (duration: 12.78882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:44.518679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.258861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:46:44.518841Z","caller":"traceutil/trace.go:171","msg":"trace[796901661] linearizableReadLoop","detail":"{readStateIndex:24895; appliedIndex:24892; }","duration":"417.069804ms","start":"2026-04-27T21:46:44.101755Z","end":"2026-04-27T21:46:44.518825Z","steps":["trace[796901661] 'read index received' (duration: 55.450661ms)","trace[796901661] 'applied index is now lower than readState.Index' (duration: 361.618013ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:44.518922Z","caller":"traceutil/trace.go:171","msg":"trace[459753447] transaction","detail":"{read_only:false; response_revision:24106; number_of_response:1; }","duration":"454.977545ms","start":"2026-04-27T21:46:44.063933Z","end":"2026-04-27T21:46:44.51891Z","steps":["trace[459753447] 'process raft request' (duration: 242.407218ms)","trace[459753447] 'compare' (duration: 212.141239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:44.518981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.06391Z","time spent":"455.039466ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:44.519012Z","caller":"traceutil/trace.go:171","msg":"trace[1343022852] transaction","detail":"{read_only:false; response_revision:24107; number_of_response:1; }","duration":"454.118338ms","start":"2026-04-27T21:46:44.06485Z","end":"2026-04-27T21:46:44.518968Z","steps":["trace[1343022852] 'process raft request' (duration: 453.917434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.519173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.408501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-27T21:46:44.5192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.064836Z","time spent":"454.267401ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:44.519246Z","caller":"traceutil/trace.go:171","msg":"trace[2117148981] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24107; }","duration":"417.529084ms","start":"2026-04-27T21:46:44.101697Z","end":"2026-04-27T21:46:44.519226Z","steps":["trace[2117148981] 'agreement among raft nodes before linearized reading' (duration: 417.287368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.519288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.101677Z","time spent":"417.600204ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-27T21:46:44.535564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.552534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:46:44.535597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.36052ms","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-04-27T21:46:44.535631Z","caller":"traceutil/trace.go:171","msg":"trace[510436551] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24108; }","duration":"287.708976ms","start":"2026-04-27T21:46:44.247904Z","end":"2026-04-27T21:46:44.535613Z","steps":["trace[510436551] 'agreement among raft nodes before linearized reading' (duration: 287.566144ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:44.535643Z","caller":"traceutil/trace.go:171","msg":"trace[366816973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24108; }","duration":"425.431861ms","start":"2026-04-27T21:46:44.110199Z","end":"2026-04-27T21:46:44.535631Z","steps":["trace[366816973] 'agreement among raft nodes before linearized reading' (duration: 425.302248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.53574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.110169Z","time spent":"425.500602ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T21:46:44.535867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.309267ms","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-04-27T21:46:44.535944Z","caller":"traceutil/trace.go:171","msg":"trace[201709993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24108; }","duration":"426.381658ms","start":"2026-04-27T21:46:44.109545Z","end":"2026-04-27T21:46:44.535927Z","steps":["trace[201709993] 'agreement among raft nodes before linearized reading' (duration: 426.052252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.536005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.109502Z","time spent":"426.491641ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T21:46:44.53615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.914001ms","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-04-27T21:46:44.536181Z","caller":"traceutil/trace.go:171","msg":"trace[1605160998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24108; }","duration":"425.974261ms","start":"2026-04-27T21:46:44.110198Z","end":"2026-04-27T21:46:44.536172Z","steps":["trace[1605160998] 'agreement among raft nodes before linearized reading' (duration: 425.865669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.53622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.110184Z","time spent":"426.012592ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T21:46:44.536506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.218903ms","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-04-27T21:46:44.536624Z","caller":"traceutil/trace.go:171","msg":"trace[867295467] 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:24108; }","duration":"178.405646ms","start":"2026-04-27T21:46:44.35819Z","end":"2026-04-27T21:46:44.536596Z","steps":["trace[867295467] 'agreement among raft nodes before linearized reading' (duration: 178.155792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.535576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.033547ms","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-04-27T21:46:44.536927Z","caller":"traceutil/trace.go:171","msg":"trace[324065487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24108; }","duration":"430.430334ms","start":"2026-04-27T21:46:44.106485Z","end":"2026-04-27T21:46:44.536915Z","steps":["trace[324065487] 'agreement among raft nodes before linearized reading' (duration: 428.942226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.536976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.10646Z","time spent":"430.500516ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T21:46:44.846649Z","caller":"traceutil/trace.go:171","msg":"trace[1307760685] transaction","detail":"{read_only:false; response_revision:24110; number_of_response:1; }","duration":"304.135275ms","start":"2026-04-27T21:46:44.542486Z","end":"2026-04-27T21:46:44.846621Z","steps":["trace[1307760685] 'process raft request' (duration: 276.164351ms)","trace[1307760685] 'compare' (duration: 27.619958ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:44.846852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.54247Z","time spent":"304.305289ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:44.847071Z","caller":"traceutil/trace.go:171","msg":"trace[1276150925] linearizableReadLoop","detail":"{readStateIndex:24900; appliedIndex:24897; }","duration":"303.338281ms","start":"2026-04-27T21:46:44.543714Z","end":"2026-04-27T21:46:44.847052Z","steps":["trace[1276150925] 'read index received' (duration: 4.943324ms)","trace[1276150925] 'applied index is now lower than readState.Index' (duration: 298.393457ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:44.847186Z","caller":"traceutil/trace.go:171","msg":"trace[965691335] transaction","detail":"{read_only:false; response_revision:24112; number_of_response:1; }","duration":"303.513473ms","start":"2026-04-27T21:46:44.543614Z","end":"2026-04-27T21:46:44.847127Z","steps":["trace[965691335] 'process raft request' (duration: 303.039574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.847237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.477974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:44.847281Z","caller":"traceutil/trace.go:171","msg":"trace[1412555394] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24113; }","duration":"303.581835ms","start":"2026-04-27T21:46:44.543686Z","end":"2026-04-27T21:46:44.847267Z","steps":["trace[1412555394] 'agreement among raft nodes before linearized reading' (duration: 303.471993ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:44.847182Z","caller":"traceutil/trace.go:171","msg":"trace[1743408768] transaction","detail":"{read_only:false; response_revision:24113; number_of_response:1; }","duration":"303.079216ms","start":"2026-04-27T21:46:44.544081Z","end":"2026-04-27T21:46:44.84716Z","steps":["trace[1743408768] 'process raft request' (duration: 302.7593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.847352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.543674Z","time spent":"303.666576ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-04-27T21:46:44.847351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.543586Z","time spent":"303.664587ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:46:44.847413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.544072Z","time spent":"303.28566ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:44.846966Z","caller":"traceutil/trace.go:171","msg":"trace[1367906840] transaction","detail":"{read_only:false; response_revision:24111; number_of_response:1; }","duration":"303.989803ms","start":"2026-04-27T21:46:44.542713Z","end":"2026-04-27T21:46:44.846703Z","steps":["trace[1367906840] 'process raft request' (duration: 303.83168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:44.847929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:44.542706Z","time spent":"305.146485ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:45.134403Z","caller":"traceutil/trace.go:171","msg":"trace[2080161308] transaction","detail":"{read_only:false; response_revision:24114; number_of_response:1; }","duration":"213.297961ms","start":"2026-04-27T21:46:44.921073Z","end":"2026-04-27T21:46:45.134371Z","steps":["trace[2080161308] 'process raft request' (duration: 181.529424ms)","trace[2080161308] 'compare' (duration: 31.629864ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:45.345969Z","caller":"traceutil/trace.go:171","msg":"trace[936682601] linearizableReadLoop","detail":"{readStateIndex:24903; appliedIndex:24902; }","duration":"135.248898ms","start":"2026-04-27T21:46:45.210659Z","end":"2026-04-27T21:46:45.345908Z","steps":["trace[936682601] 'read index received' (duration: 122.101442ms)","trace[936682601] 'applied index is now lower than readState.Index' (duration: 13.146066ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:45.346243Z","caller":"traceutil/trace.go:171","msg":"trace[582032860] transaction","detail":"{read_only:false; response_revision:24115; number_of_response:1; }","duration":"141.382942ms","start":"2026-04-27T21:46:45.204798Z","end":"2026-04-27T21:46:45.346181Z","steps":["trace[582032860] 'process raft request' (duration: 127.997661ms)","trace[582032860] 'compare' (duration: 12.893472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:45.34659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.90678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-04-27T21:46:45.346692Z","caller":"traceutil/trace.go:171","msg":"trace[766818341] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24115; }","duration":"136.035443ms","start":"2026-04-27T21:46:45.210612Z","end":"2026-04-27T21:46:45.346647Z","steps":["trace[766818341] 'agreement among raft nodes before linearized reading' (duration: 135.881159ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:45.63155Z","caller":"traceutil/trace.go:171","msg":"trace[1180747966] transaction","detail":"{read_only:false; response_revision:24117; number_of_response:1; }","duration":"164.782232ms","start":"2026-04-27T21:46:45.466734Z","end":"2026-04-27T21:46:45.631516Z","steps":["trace[1180747966] 'process raft request' (duration: 99.093559ms)","trace[1180747966] 'compare' (duration: 65.431618ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:45.803347Z","caller":"traceutil/trace.go:171","msg":"trace[1488949918] transaction","detail":"{read_only:false; response_revision:24118; number_of_response:1; }","duration":"177.945328ms","start":"2026-04-27T21:46:45.625372Z","end":"2026-04-27T21:46:45.803317Z","steps":["trace[1488949918] 'process raft request' (duration: 177.739874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:46.107532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.022518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:46.107603Z","caller":"traceutil/trace.go:171","msg":"trace[1083522866] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:24118; }","duration":"155.153591ms","start":"2026-04-27T21:46:45.952433Z","end":"2026-04-27T21:46:46.107587Z","steps":["trace[1083522866] 'count revisions from in-memory index tree' (duration: 154.955787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:46.107752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.274106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:46:46.107801Z","caller":"traceutil/trace.go:171","msg":"trace[1818295339] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24118; }","duration":"177.352818ms","start":"2026-04-27T21:46:45.930433Z","end":"2026-04-27T21:46:46.107785Z","steps":["trace[1818295339] 'count revisions from in-memory index tree' (duration: 177.137262ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:46.169697Z","caller":"traceutil/trace.go:171","msg":"trace[828506295] transaction","detail":"{read_only:false; response_revision:24119; number_of_response:1; }","duration":"138.973767ms","start":"2026-04-27T21:46:46.0307Z","end":"2026-04-27T21:46:46.169674Z","steps":["trace[828506295] 'process raft request' (duration: 138.538839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:46.393505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.295083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:46:46.393628Z","caller":"traceutil/trace.go:171","msg":"trace[375664237] linearizableReadLoop","detail":"{readStateIndex:24908; appliedIndex:24907; }","duration":"152.806626ms","start":"2026-04-27T21:46:46.240801Z","end":"2026-04-27T21:46:46.393608Z","steps":["trace[375664237] 'read index received' (duration: 29.31926ms)","trace[375664237] 'applied index is now lower than readState.Index' (duration: 123.485756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:46.39386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.02407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-27T21:46:46.393896Z","caller":"traceutil/trace.go:171","msg":"trace[1668122892] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24120; }","duration":"153.129242ms","start":"2026-04-27T21:46:46.240757Z","end":"2026-04-27T21:46:46.393887Z","steps":["trace[1668122892] 'agreement among raft nodes before linearized reading' (duration: 152.900508ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:46.39424Z","caller":"traceutil/trace.go:171","msg":"trace[2118570496] transaction","detail":"{read_only:false; response_revision:24120; number_of_response:1; }","duration":"217.132093ms","start":"2026-04-27T21:46:46.17649Z","end":"2026-04-27T21:46:46.393622Z","steps":["trace[2118570496] 'process raft request' (duration: 93.609427ms)","trace[2118570496] 'compare' (duration: 123.104359ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:46.687227Z","caller":"traceutil/trace.go:171","msg":"trace[1336618456] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"162.406746ms","start":"2026-04-27T21:46:46.524772Z","end":"2026-04-27T21:46:46.687178Z","steps":["trace[1336618456] 'process raft request' (duration: 140.950974ms)","trace[1336618456] 'compare' (duration: 21.279619ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:46.975367Z","caller":"traceutil/trace.go:171","msg":"trace[672456691] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"450.45612ms","start":"2026-04-27T21:46:46.52488Z","end":"2026-04-27T21:46:46.975336Z","steps":["trace[672456691] 'process raft request' (duration: 431.507464ms)","trace[672456691] 'compare' (duration: 18.61028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:46.975535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:46.524852Z","time spent":"450.604232ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:46.975704Z","caller":"traceutil/trace.go:171","msg":"trace[1595416500] linearizableReadLoop","detail":"{readStateIndex:24912; appliedIndex:24909; }","duration":"423.196018ms","start":"2026-04-27T21:46:46.552475Z","end":"2026-04-27T21:46:46.975671Z","steps":["trace[1595416500] 'read index received' (duration: 113.340926ms)","trace[1595416500] 'applied index is now lower than readState.Index' (duration: 309.853172ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:46.975747Z","caller":"traceutil/trace.go:171","msg":"trace[150567548] transaction","detail":"{read_only:false; response_revision:24124; number_of_response:1; }","duration":"432.576535ms","start":"2026-04-27T21:46:46.543141Z","end":"2026-04-27T21:46:46.975718Z","steps":["trace[150567548] 'process raft request' (duration: 432.108077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:46.97589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:46.543127Z","time spent":"432.681537ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:46:46.975956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.331542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-27T21:46:46.975966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.102286ms","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-04-27T21:46:46.975989Z","caller":"traceutil/trace.go:171","msg":"trace[1158164491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24124; }","duration":"124.388704ms","start":"2026-04-27T21:46:46.851594Z","end":"2026-04-27T21:46:46.975982Z","steps":["trace[1158164491] 'agreement among raft nodes before linearized reading' (duration: 124.256842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:46.975998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.646749ms","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-04-27T21:46:46.976007Z","caller":"traceutil/trace.go:171","msg":"trace[1833908411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24124; }","duration":"125.175638ms","start":"2026-04-27T21:46:46.85082Z","end":"2026-04-27T21:46:46.975995Z","steps":["trace[1833908411] 'agreement among raft nodes before linearized reading' (duration: 125.036656ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:46.97605Z","caller":"traceutil/trace.go:171","msg":"trace[1983714827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24124; }","duration":"124.72417ms","start":"2026-04-27T21:46:46.851308Z","end":"2026-04-27T21:46:46.976032Z","steps":["trace[1983714827] 'agreement among raft nodes before linearized reading' (duration: 124.539796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:46.976115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.606646ms","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-04-27T21:46:46.976256Z","caller":"traceutil/trace.go:171","msg":"trace[649007072] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24124; }","duration":"423.776689ms","start":"2026-04-27T21:46:46.552428Z","end":"2026-04-27T21:46:46.976205Z","steps":["trace[649007072] 'agreement among raft nodes before linearized reading' (duration: 423.416932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:46.976186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.890822ms","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-04-27T21:46:46.976308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:46.552415Z","time spent":"423.881681ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-27T21:46:46.976324Z","caller":"traceutil/trace.go:171","msg":"trace[2083306275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24124; }","duration":"125.048166ms","start":"2026-04-27T21:46:46.851254Z","end":"2026-04-27T21:46:46.976302Z","steps":["trace[2083306275] 'agreement among raft nodes before linearized reading' (duration: 124.600808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:47.122173Z","caller":"traceutil/trace.go:171","msg":"trace[939684172] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"142.194537ms","start":"2026-04-27T21:46:46.979953Z","end":"2026-04-27T21:46:47.122148Z","steps":["trace[939684172] 'process raft request' (duration: 112.187224ms)","trace[939684172] 'compare' (duration: 29.872211ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:47.123847Z","caller":"traceutil/trace.go:171","msg":"trace[1653612871] transaction","detail":"{read_only:false; response_revision:24127; number_of_response:1; }","duration":"143.721466ms","start":"2026-04-27T21:46:46.980099Z","end":"2026-04-27T21:46:47.12382Z","steps":["trace[1653612871] 'process raft request' (duration: 143.627885ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:47.123904Z","caller":"traceutil/trace.go:171","msg":"trace[144000753] transaction","detail":"{read_only:false; response_revision:24126; number_of_response:1; }","duration":"143.847648ms","start":"2026-04-27T21:46:46.980024Z","end":"2026-04-27T21:46:47.123872Z","steps":["trace[144000753] 'process raft request' (duration: 143.542893ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:47.124017Z","caller":"traceutil/trace.go:171","msg":"trace[1606691122] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"143.454422ms","start":"2026-04-27T21:46:46.980545Z","end":"2026-04-27T21:46:47.123999Z","steps":["trace[1606691122] 'process raft request' (duration: 143.229858ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:47.124167Z","caller":"traceutil/trace.go:171","msg":"trace[842136210] transaction","detail":"{read_only:false; response_revision:24129; number_of_response:1; }","duration":"142.410672ms","start":"2026-04-27T21:46:46.981698Z","end":"2026-04-27T21:46:47.124108Z","steps":["trace[842136210] 'process raft request' (duration: 142.180607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:56.369527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.438881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:56.369608Z","caller":"traceutil/trace.go:171","msg":"trace[1687875285] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24250; }","duration":"175.592413ms","start":"2026-04-27T21:46:56.193998Z","end":"2026-04-27T21:46:56.369591Z","steps":["trace[1687875285] 'count revisions from in-memory index tree' (duration: 175.328279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:56.369927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.331779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:46:56.370042Z","caller":"traceutil/trace.go:171","msg":"trace[297463676] linearizableReadLoop","detail":"{readStateIndex:25041; appliedIndex:25040; }","duration":"111.633524ms","start":"2026-04-27T21:46:56.258389Z","end":"2026-04-27T21:46:56.370023Z","steps":["trace[297463676] 'read index received' (duration: 42.03µs)","trace[297463676] 'applied index is now lower than readState.Index' (duration: 111.589954ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:56.370102Z","caller":"traceutil/trace.go:171","msg":"trace[626193776] transaction","detail":"{read_only:false; response_revision:24251; number_of_response:1; }","duration":"166.356961ms","start":"2026-04-27T21:46:56.203695Z","end":"2026-04-27T21:46:56.370052Z","steps":["trace[626193776] 'process raft request' (duration: 23.790416ms)","trace[626193776] 'compare' (duration: 141.929112ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:56.370208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.816577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-04-27T21:46:56.370246Z","caller":"traceutil/trace.go:171","msg":"trace[1417146286] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24251; }","duration":"111.879379ms","start":"2026-04-27T21:46:56.258351Z","end":"2026-04-27T21:46:56.37023Z","steps":["trace[1417146286] 'agreement among raft nodes before linearized reading' (duration: 111.729176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:56.659945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.182993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:46:56.66002Z","caller":"traceutil/trace.go:171","msg":"trace[117910436] linearizableReadLoop","detail":"{readStateIndex:25042; appliedIndex:25041; }","duration":"111.825538ms","start":"2026-04-27T21:46:56.548184Z","end":"2026-04-27T21:46:56.66001Z","steps":["trace[117910436] 'read index received' (duration: 48.301µs)","trace[117910436] 'applied index is now lower than readState.Index' (duration: 111.776497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:56.660078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.903059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:56.660094Z","caller":"traceutil/trace.go:171","msg":"trace[1036792304] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24252; }","duration":"111.9557ms","start":"2026-04-27T21:46:56.548131Z","end":"2026-04-27T21:46:56.660087Z","steps":["trace[1036792304] 'agreement among raft nodes before linearized reading' (duration: 111.904339ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:56.660313Z","caller":"traceutil/trace.go:171","msg":"trace[1977523759] transaction","detail":"{read_only:false; response_revision:24252; number_of_response:1; }","duration":"227.882065ms","start":"2026-04-27T21:46:56.432386Z","end":"2026-04-27T21:46:56.660268Z","steps":["trace[1977523759] 'process raft request' (duration: 81.309575ms)","trace[1977523759] 'compare' (duration: 146.079201ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:56.864735Z","caller":"traceutil/trace.go:171","msg":"trace[429059005] transaction","detail":"{read_only:false; response_revision:24253; number_of_response:1; }","duration":"141.81844ms","start":"2026-04-27T21:46:56.722889Z","end":"2026-04-27T21:46:56.864708Z","steps":["trace[429059005] 'process raft request' (duration: 128.412388ms)","trace[429059005] 'compare' (duration: 13.277079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:57.156431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.287277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7d77b46-ee64-43a5-8388-99e17ec6e009\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-27T21:46:57.156514Z","caller":"traceutil/trace.go:171","msg":"trace[1793185053] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7d77b46-ee64-43a5-8388-99e17ec6e009; range_end:; response_count:1; response_revision:24253; }","duration":"152.42008ms","start":"2026-04-27T21:46:57.004073Z","end":"2026-04-27T21:46:57.156493Z","steps":["trace[1793185053] 'range keys from in-memory index tree' (duration: 152.064341ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.156739Z","caller":"traceutil/trace.go:171","msg":"trace[1345223934] transaction","detail":"{read_only:false; response_revision:24254; number_of_response:1; }","duration":"147.620779ms","start":"2026-04-27T21:46:57.009092Z","end":"2026-04-27T21:46:57.156713Z","steps":["trace[1345223934] 'process raft request' (duration: 68.805061ms)","trace[1345223934] 'compare' (duration: 78.618454ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:57.183865Z","caller":"traceutil/trace.go:171","msg":"trace[884974323] linearizableReadLoop","detail":"{readStateIndex:25045; appliedIndex:25043; }","duration":"137.906557ms","start":"2026-04-27T21:46:57.045934Z","end":"2026-04-27T21:46:57.18384Z","steps":["trace[884974323] 'read index received' (duration: 31.98364ms)","trace[884974323] 'applied index is now lower than readState.Index' (duration: 105.921967ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:57.183987Z","caller":"traceutil/trace.go:171","msg":"trace[1123291952] transaction","detail":"{read_only:false; response_revision:24255; number_of_response:1; }","duration":"171.868875ms","start":"2026-04-27T21:46:57.012104Z","end":"2026-04-27T21:46:57.183973Z","steps":["trace[1123291952] 'process raft request' (duration: 171.61292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.184132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.192662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-04-27T21:46:57.184185Z","caller":"traceutil/trace.go:171","msg":"trace[1438418576] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24255; }","duration":"138.298384ms","start":"2026-04-27T21:46:57.045875Z","end":"2026-04-27T21:46:57.184174Z","steps":["trace[1438418576] 'agreement among raft nodes before linearized reading' (duration: 138.143572ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.429456Z","caller":"traceutil/trace.go:171","msg":"trace[791541793] linearizableReadLoop","detail":"{readStateIndex:25046; appliedIndex:25045; }","duration":"245.490485ms","start":"2026-04-27T21:46:57.183942Z","end":"2026-04-27T21:46:57.429433Z","steps":["trace[791541793] 'read index received' (duration: 245.386983ms)","trace[791541793] 'applied index is now lower than readState.Index' (duration: 101.962µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:57.429702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.069641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-27T21:46:57.429744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.155666ms","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-04-27T21:46:57.429737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.240544ms","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-04-27T21:46:57.429834Z","caller":"traceutil/trace.go:171","msg":"trace[343879008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24256; }","duration":"263.29018ms","start":"2026-04-27T21:46:57.166524Z","end":"2026-04-27T21:46:57.429814Z","steps":["trace[343879008] 'agreement among raft nodes before linearized reading' (duration: 262.978333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.429862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.50248ms","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-04-27T21:46:57.429901Z","caller":"traceutil/trace.go:171","msg":"trace[466594927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24256; }","duration":"265.566412ms","start":"2026-04-27T21:46:57.164325Z","end":"2026-04-27T21:46:57.429892Z","steps":["trace[466594927] 'agreement among raft nodes before linearized reading' (duration: 265.47126ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.429922Z","caller":"traceutil/trace.go:171","msg":"trace[255522026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24256; }","duration":"266.428878ms","start":"2026-04-27T21:46:57.163428Z","end":"2026-04-27T21:46:57.429857Z","steps":["trace[255522026] 'agreement among raft nodes before linearized reading' (duration: 266.191883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.430055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.719256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-27T21:46:57.430086Z","caller":"traceutil/trace.go:171","msg":"trace[1385913335] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:24256; }","duration":"329.771436ms","start":"2026-04-27T21:46:57.100307Z","end":"2026-04-27T21:46:57.430078Z","steps":["trace[1385913335] 'agreement among raft nodes before linearized reading' (duration: 329.691315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.430111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.10029Z","time spent":"329.814707ms","remote":"127.0.0.1:53384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.19.213.132\" "} {"level":"warn","ts":"2026-04-27T21:46:57.430202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.825437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7d77b46-ee64-43a5-8388-99e17ec6e009\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-04-27T21:46:57.43038Z","caller":"traceutil/trace.go:171","msg":"trace[1153375200] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7d77b46-ee64-43a5-8388-99e17ec6e009; range_end:; response_count:1; response_revision:24256; }","duration":"378.042042ms","start":"2026-04-27T21:46:57.052325Z","end":"2026-04-27T21:46:57.430367Z","steps":["trace[1153375200] 'agreement among raft nodes before linearized reading' (duration: 377.801007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.432016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.052298Z","time spent":"379.698193ms","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7d77b46-ee64-43a5-8388-99e17ec6e009\" "} {"level":"info","ts":"2026-04-27T21:46:57.430437Z","caller":"traceutil/trace.go:171","msg":"trace[1488604462] transaction","detail":"{read_only:false; response_revision:24256; number_of_response:1; }","duration":"265.866048ms","start":"2026-04-27T21:46:57.164557Z","end":"2026-04-27T21:46:57.430423Z","steps":["trace[1488604462] 'process raft request' (duration: 264.722386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.430619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.998029ms","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-04-27T21:46:57.432291Z","caller":"traceutil/trace.go:171","msg":"trace[2128526563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24256; }","duration":"251.68062ms","start":"2026-04-27T21:46:57.180596Z","end":"2026-04-27T21:46:57.432277Z","steps":["trace[2128526563] 'agreement among raft nodes before linearized reading' (duration: 249.954248ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.429766Z","caller":"traceutil/trace.go:171","msg":"trace[406167738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24256; }","duration":"266.157692ms","start":"2026-04-27T21:46:57.163594Z","end":"2026-04-27T21:46:57.429751Z","steps":["trace[406167738] 'agreement among raft nodes before linearized reading' (duration: 265.938678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.474329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.076058ms","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-04-27T21:46:57.474416Z","caller":"traceutil/trace.go:171","msg":"trace[1677419041] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24257; }","duration":"285.189159ms","start":"2026-04-27T21:46:57.189207Z","end":"2026-04-27T21:46:57.474396Z","steps":["trace[1677419041] 'agreement among raft nodes before linearized reading' (duration: 284.950305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.474413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.871298ms","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-04-27T21:46:57.474484Z","caller":"traceutil/trace.go:171","msg":"trace[1301621310] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24257; }","duration":"233.000471ms","start":"2026-04-27T21:46:57.241465Z","end":"2026-04-27T21:46:57.474465Z","steps":["trace[1301621310] 'agreement among raft nodes before linearized reading' (duration: 232.769756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.474627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.853133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:46:57.474717Z","caller":"traceutil/trace.go:171","msg":"trace[857652767] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24257; }","duration":"269.989515ms","start":"2026-04-27T21:46:57.204709Z","end":"2026-04-27T21:46:57.474699Z","steps":["trace[857652767] 'agreement among raft nodes before linearized reading' (duration: 269.810471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.735748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.430942Z","time spent":"304.799778ms","remote":"127.0.0.1:53384","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T21:46:57.735886Z","caller":"traceutil/trace.go:171","msg":"trace[1509102908] transaction","detail":"{read_only:false; response_revision:24260; number_of_response:1; }","duration":"301.121138ms","start":"2026-04-27T21:46:57.43474Z","end":"2026-04-27T21:46:57.735861Z","steps":["trace[1509102908] 'process raft request' (duration: 301.050317ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.736038Z","caller":"traceutil/trace.go:171","msg":"trace[1752999714] transaction","detail":"{read_only:false; response_revision:24258; number_of_response:1; }","duration":"302.858641ms","start":"2026-04-27T21:46:57.433151Z","end":"2026-04-27T21:46:57.73601Z","steps":["trace[1752999714] 'process raft request' (duration: 302.479614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.736062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.434729Z","time spent":"301.237921ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:46:57.736172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.433132Z","time spent":"302.970173ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:57.736275Z","caller":"traceutil/trace.go:171","msg":"trace[2045048598] transaction","detail":"{read_only:false; response_revision:24261; number_of_response:1; }","duration":"300.72284ms","start":"2026-04-27T21:46:57.435529Z","end":"2026-04-27T21:46:57.736252Z","steps":["trace[2045048598] 'process raft request' (duration: 300.293993ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.736372Z","caller":"traceutil/trace.go:171","msg":"trace[1891160837] transaction","detail":"{read_only:false; response_revision:24259; number_of_response:1; }","duration":"302.735019ms","start":"2026-04-27T21:46:57.433609Z","end":"2026-04-27T21:46:57.736344Z","steps":["trace[1891160837] 'process raft request' (duration: 302.143428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.736421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.435521Z","time spent":"300.830662ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:46:57.736497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.43359Z","time spent":"302.847252ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:46:57.736563Z","caller":"traceutil/trace.go:171","msg":"trace[1982207128] transaction","detail":"{read_only:false; response_revision:24262; number_of_response:1; }","duration":"299.915067ms","start":"2026-04-27T21:46:57.43662Z","end":"2026-04-27T21:46:57.736535Z","steps":["trace[1982207128] 'process raft request' (duration: 299.54652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.736697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.43661Z","time spent":"300.017459ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:46:57.736848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.399734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:57.736913Z","caller":"traceutil/trace.go:171","msg":"trace[593657038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24262; }","duration":"261.494596ms","start":"2026-04-27T21:46:57.475401Z","end":"2026-04-27T21:46:57.736896Z","steps":["trace[593657038] 'agreement among raft nodes before linearized reading' (duration: 261.391294ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.737686Z","caller":"traceutil/trace.go:171","msg":"trace[480270916] linearizableReadLoop","detail":"{readStateIndex:25053; appliedIndex:25047; }","duration":"262.230189ms","start":"2026-04-27T21:46:57.475442Z","end":"2026-04-27T21:46:57.737672Z","steps":["trace[480270916] 'read index received' (duration: 260.075519ms)","trace[480270916] 'applied index is now lower than readState.Index' (duration: 2.15284ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:57.755852Z","caller":"traceutil/trace.go:171","msg":"trace[1104701696] transaction","detail":"{read_only:false; response_revision:24263; number_of_response:1; }","duration":"276.879964ms","start":"2026-04-27T21:46:57.478952Z","end":"2026-04-27T21:46:57.755832Z","steps":["trace[1104701696] 'process raft request' (duration: 276.724821ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:57.756024Z","caller":"traceutil/trace.go:171","msg":"trace[346988263] transaction","detail":"{read_only:false; response_revision:24264; number_of_response:1; }","duration":"111.137015ms","start":"2026-04-27T21:46:57.644863Z","end":"2026-04-27T21:46:57.756Z","steps":["trace[346988263] 'process raft request' (duration: 110.928841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.803688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.398675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-04-27T21:46:57.803777Z","caller":"traceutil/trace.go:171","msg":"trace[1934749761] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:24265; }","duration":"323.602581ms","start":"2026-04-27T21:46:57.480154Z","end":"2026-04-27T21:46:57.803756Z","steps":["trace[1934749761] 'agreement among raft nodes before linearized reading' (duration: 323.293745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:57.803821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.480136Z","time spent":"323.674572ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1280,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-04-27T21:46:57.936684Z","caller":"traceutil/trace.go:171","msg":"trace[54265658] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"126.889261ms","start":"2026-04-27T21:46:57.809778Z","end":"2026-04-27T21:46:57.936667Z","steps":["trace[54265658] 'process raft request' (duration: 110.380551ms)","trace[54265658] 'compare' (duration: 15.810646ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:57.936377Z","caller":"traceutil/trace.go:171","msg":"trace[36222025] linearizableReadLoop","detail":"{readStateIndex:25057; appliedIndex:25056; }","duration":"124.7335ms","start":"2026-04-27T21:46:57.811623Z","end":"2026-04-27T21:46:57.936356Z","steps":["trace[36222025] 'read index received' (duration: 108.609878ms)","trace[36222025] 'applied index is now lower than readState.Index' (duration: 16.122282ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:57.937091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.441584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-04-27T21:46:57.937178Z","caller":"traceutil/trace.go:171","msg":"trace[1814957020] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24267; }","duration":"125.569206ms","start":"2026-04-27T21:46:57.811589Z","end":"2026-04-27T21:46:57.937158Z","steps":["trace[1814957020] 'agreement among raft nodes before linearized reading' (duration: 125.27559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:58.275915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.486606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:46:58.275984Z","caller":"traceutil/trace.go:171","msg":"trace[1430928034] linearizableReadLoop","detail":"{readStateIndex:25059; appliedIndex:25058; }","duration":"325.334562ms","start":"2026-04-27T21:46:57.950639Z","end":"2026-04-27T21:46:58.275974Z","steps":["trace[1430928034] 'read index received' (duration: 104.647722ms)","trace[1430928034] 'applied index is now lower than readState.Index' (duration: 220.68604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:58.276057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.475788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:46:58.276072Z","caller":"traceutil/trace.go:171","msg":"trace[1296404395] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24268; }","duration":"315.514118ms","start":"2026-04-27T21:46:57.960551Z","end":"2026-04-27T21:46:58.276066Z","steps":["trace[1296404395] 'agreement among raft nodes before linearized reading' (duration: 315.474117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:58.276089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.960534Z","time spent":"315.550609ms","remote":"127.0.0.1:53806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:46:58.276217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.924452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:46:58.276231Z","caller":"traceutil/trace.go:171","msg":"trace[1530583113] 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:24268; }","duration":"165.968853ms","start":"2026-04-27T21:46:58.110257Z","end":"2026-04-27T21:46:58.276226Z","steps":["trace[1530583113] 'agreement among raft nodes before linearized reading' (duration: 165.936252ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:58.276496Z","caller":"traceutil/trace.go:171","msg":"trace[2031389850] transaction","detail":"{read_only:false; response_revision:24268; number_of_response:1; }","duration":"333.864592ms","start":"2026-04-27T21:46:57.942613Z","end":"2026-04-27T21:46:58.276477Z","steps":["trace[2031389850] 'process raft request' (duration: 112.756105ms)","trace[2031389850] 'compare' (duration: 220.272262ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:58.276576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.942589Z","time spent":"333.945074ms","remote":"127.0.0.1:53422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:46:58.276744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.075556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-04-27T21:46:58.276858Z","caller":"traceutil/trace.go:171","msg":"trace[1340768916] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24268; }","duration":"326.197838ms","start":"2026-04-27T21:46:57.950625Z","end":"2026-04-27T21:46:58.276822Z","steps":["trace[1340768916] 'agreement among raft nodes before linearized reading' (duration: 325.396193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:58.276905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:46:57.950617Z","time spent":"326.276752ms","remote":"127.0.0.1:53844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9624,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"info","ts":"2026-04-27T21:46:58.643309Z","caller":"traceutil/trace.go:171","msg":"trace[895795724] linearizableReadLoop","detail":"{readStateIndex:25061; appliedIndex:25060; }","duration":"222.570625ms","start":"2026-04-27T21:46:58.420697Z","end":"2026-04-27T21:46:58.643268Z","steps":["trace[895795724] 'read index received' (duration: 211.612779ms)","trace[895795724] 'applied index is now lower than readState.Index' (duration: 10.956246ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:58.643509Z","caller":"traceutil/trace.go:171","msg":"trace[1926437118] transaction","detail":"{read_only:false; response_revision:24270; number_of_response:1; }","duration":"262.177688ms","start":"2026-04-27T21:46:58.381307Z","end":"2026-04-27T21:46:58.643484Z","steps":["trace[1926437118] 'process raft request' (duration: 251.021358ms)","trace[1926437118] 'compare' (duration: 10.565309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:46:58.643644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.122244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7d77b46-ee64-43a5-8388-99e17ec6e009\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-04-27T21:46:58.643692Z","caller":"traceutil/trace.go:171","msg":"trace[1756326935] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7d77b46-ee64-43a5-8388-99e17ec6e009; range_end:; response_count:1; response_revision:24270; }","duration":"207.241998ms","start":"2026-04-27T21:46:58.436435Z","end":"2026-04-27T21:46:58.643677Z","steps":["trace[1756326935] 'agreement among raft nodes before linearized reading' (duration: 207.074525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:58.643804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.099255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:58.643877Z","caller":"traceutil/trace.go:171","msg":"trace[906957996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24270; }","duration":"223.234218ms","start":"2026-04-27T21:46:58.420624Z","end":"2026-04-27T21:46:58.643859Z","steps":["trace[906957996] 'agreement among raft nodes before linearized reading' (duration: 223.107246ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:58.906339Z","caller":"traceutil/trace.go:171","msg":"trace[1477306198] transaction","detail":"{read_only:false; response_revision:24271; number_of_response:1; }","duration":"240.699196ms","start":"2026-04-27T21:46:58.665618Z","end":"2026-04-27T21:46:58.906317Z","steps":["trace[1477306198] 'process raft request' (duration: 230.829491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:33.894957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.785115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:33.895125Z","caller":"traceutil/trace.go:171","msg":"trace[1587091803] linearizableReadLoop","detail":"{readStateIndex:25364; appliedIndex:25362; }","duration":"473.552405ms","start":"2026-04-27T21:47:33.421556Z","end":"2026-04-27T21:47:33.895109Z","steps":["trace[1587091803] 'read index received' (duration: 190.516625ms)","trace[1587091803] 'applied index is now lower than readState.Index' (duration: 283.03465ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:33.895198Z","caller":"traceutil/trace.go:171","msg":"trace[177422568] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"507.718025ms","start":"2026-04-27T21:47:33.387471Z","end":"2026-04-27T21:47:33.895189Z","steps":["trace[177422568] 'process raft request' (duration: 507.586363ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:33.895152Z","caller":"traceutil/trace.go:171","msg":"trace[1768420181] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"508.919677ms","start":"2026-04-27T21:47:33.386206Z","end":"2026-04-27T21:47:33.895126Z","steps":["trace[1768420181] 'process raft request' (duration: 225.883838ms)","trace[1768420181] 'compare' (duration: 282.633411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:33.895268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:33.387453Z","time spent":"507.775866ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:33.895294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:33.386169Z","time spent":"509.07833ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:33.895812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.240027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:33.895901Z","caller":"traceutil/trace.go:171","msg":"trace[237116398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24565; }","duration":"474.39666ms","start":"2026-04-27T21:47:33.421484Z","end":"2026-04-27T21:47:33.89588Z","steps":["trace[237116398] 'agreement among raft nodes before linearized reading' (duration: 474.024883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:33.896029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:33.421461Z","time spent":"474.521921ms","remote":"127.0.0.1:53350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:47:33.897433Z","caller":"traceutil/trace.go:171","msg":"trace[1928974957] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"266.212964ms","start":"2026-04-27T21:47:33.631174Z","end":"2026-04-27T21:47:33.897387Z","steps":["trace[1928974957] 'process raft request' (duration: 266.077461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:33.897492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.853667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:33.897544Z","caller":"traceutil/trace.go:171","msg":"trace[416447221] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:24566; }","duration":"426.98636ms","start":"2026-04-27T21:47:33.470544Z","end":"2026-04-27T21:47:33.897531Z","steps":["trace[416447221] 'agreement among raft nodes before linearized reading' (duration: 426.874008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:33.897543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.961437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:33.897601Z","caller":"traceutil/trace.go:171","msg":"trace[1543634224] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24566; }","duration":"413.040198ms","start":"2026-04-27T21:47:33.484547Z","end":"2026-04-27T21:47:33.897587Z","steps":["trace[1543634224] 'agreement among raft nodes before linearized reading' (duration: 412.914526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:33.897635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:33.470504Z","time spent":"427.122392ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:33.897638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:33.484528Z","time spent":"413.104109ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:47:40.815991Z","caller":"traceutil/trace.go:171","msg":"trace[1524254598] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"107.498557ms","start":"2026-04-27T21:47:40.708464Z","end":"2026-04-27T21:47:40.815962Z","steps":["trace[1524254598] 'process raft request' (duration: 107.318694ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.114802Z","caller":"traceutil/trace.go:171","msg":"trace[511394916] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"353.39821ms","start":"2026-04-27T21:47:40.761362Z","end":"2026-04-27T21:47:41.11476Z","steps":["trace[511394916] 'process raft request' (duration: 325.69255ms)","trace[511394916] 'compare' (duration: 27.349873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:41.114932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:40.761342Z","time spent":"353.527652ms","remote":"127.0.0.1:49562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:41.114995Z","caller":"traceutil/trace.go:171","msg":"trace[903142074] linearizableReadLoop","detail":"{readStateIndex:25419; appliedIndex:25417; }","duration":"149.78727ms","start":"2026-04-27T21:47:40.965185Z","end":"2026-04-27T21:47:41.114972Z","steps":["trace[903142074] 'read index received' (duration: 121.832895ms)","trace[903142074] 'applied index is now lower than readState.Index' (duration: 27.953225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:41.115126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.925493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:41.115156Z","caller":"traceutil/trace.go:171","msg":"trace[1127860034] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24619; }","duration":"149.998094ms","start":"2026-04-27T21:47:40.965149Z","end":"2026-04-27T21:47:41.115147Z","steps":["trace[1127860034] 'agreement among raft nodes before linearized reading' (duration: 149.913312ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.115263Z","caller":"traceutil/trace.go:171","msg":"trace[1194076621] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"323.756704ms","start":"2026-04-27T21:47:40.791496Z","end":"2026-04-27T21:47:41.115253Z","steps":["trace[1194076621] 'process raft request' (duration: 323.215604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.115318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:40.791472Z","time spent":"323.818934ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:41.191231Z","caller":"traceutil/trace.go:171","msg":"trace[2063367218] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"128.50065ms","start":"2026-04-27T21:47:41.062712Z","end":"2026-04-27T21:47:41.191213Z","steps":["trace[2063367218] 'process raft request' (duration: 128.391608ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.618583Z","caller":"traceutil/trace.go:171","msg":"trace[464425854] linearizableReadLoop","detail":"{readStateIndex:25421; appliedIndex:25419; }","duration":"497.226728ms","start":"2026-04-27T21:47:41.121332Z","end":"2026-04-27T21:47:41.618559Z","steps":["trace[464425854] 'read index received' (duration: 69.772179ms)","trace[464425854] 'applied index is now lower than readState.Index' (duration: 427.453149ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:41.618623Z","caller":"traceutil/trace.go:171","msg":"trace[1971891767] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"510.18406ms","start":"2026-04-27T21:47:41.108405Z","end":"2026-04-27T21:47:41.618589Z","steps":["trace[1971891767] 'process raft request' (duration: 427.016891ms)","trace[1971891767] 'compare' (duration: 83.031087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:41.618756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:41.108385Z","time spent":"510.298332ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:41.618801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.448312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-04-27T21:47:41.618854Z","caller":"traceutil/trace.go:171","msg":"trace[1948386039] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24621; }","duration":"497.532052ms","start":"2026-04-27T21:47:41.121294Z","end":"2026-04-27T21:47:41.618826Z","steps":["trace[1948386039] 'agreement among raft nodes before linearized reading' (duration: 497.38352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.618887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:41.121276Z","time spent":"497.602964ms","remote":"127.0.0.1:53540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18389,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-04-27T21:47:41.634965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.692309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:41.635071Z","caller":"traceutil/trace.go:171","msg":"trace[548610061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24623; }","duration":"213.786121ms","start":"2026-04-27T21:47:41.421247Z","end":"2026-04-27T21:47:41.635033Z","steps":["trace[548610061] 'agreement among raft nodes before linearized reading' (duration: 213.632998ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.63539Z","caller":"traceutil/trace.go:171","msg":"trace[395274027] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"231.556253ms","start":"2026-04-27T21:47:41.403812Z","end":"2026-04-27T21:47:41.635368Z","steps":["trace[395274027] 'process raft request' (duration: 231.015373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.635478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.668997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:41.635563Z","caller":"traceutil/trace.go:171","msg":"trace[1475386224] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24623; }","duration":"150.818369ms","start":"2026-04-27T21:47:41.484722Z","end":"2026-04-27T21:47:41.63554Z","steps":["trace[1475386224] 'agreement among raft nodes before linearized reading' (duration: 150.646706ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:41.635686Z","caller":"traceutil/trace.go:171","msg":"trace[1995644506] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"234.740734ms","start":"2026-04-27T21:47:41.400935Z","end":"2026-04-27T21:47:41.635676Z","steps":["trace[1995644506] 'process raft request' (duration: 233.759175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:41.635965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.515892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-27T21:47:41.636Z","caller":"traceutil/trace.go:171","msg":"trace[933256049] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24623; }","duration":"152.620553ms","start":"2026-04-27T21:47:41.483369Z","end":"2026-04-27T21:47:41.63599Z","steps":["trace[933256049] 'agreement among raft nodes before linearized reading' (duration: 152.474261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:44.486204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.755977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-27T21:47:44.486248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.407285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-27T21:47:44.486331Z","caller":"traceutil/trace.go:171","msg":"trace[101385735] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24645; }","duration":"212.545247ms","start":"2026-04-27T21:47:44.273768Z","end":"2026-04-27T21:47:44.486313Z","steps":["trace[101385735] 'count revisions from in-memory index tree' (duration: 212.331744ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:44.486313Z","caller":"traceutil/trace.go:171","msg":"trace[1535445126] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24645; }","duration":"119.884859ms","start":"2026-04-27T21:47:44.366404Z","end":"2026-04-27T21:47:44.486289Z","steps":["trace[1535445126] 'count revisions from in-memory index tree' (duration: 119.598063ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:50.096618Z","caller":"traceutil/trace.go:171","msg":"trace[762360686] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"128.871818ms","start":"2026-04-27T21:47:49.96772Z","end":"2026-04-27T21:47:50.096592Z","steps":["trace[762360686] 'process raft request' (duration: 128.683584ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:50.109201Z","caller":"traceutil/trace.go:171","msg":"trace[1289218017] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"136.059812ms","start":"2026-04-27T21:47:49.973111Z","end":"2026-04-27T21:47:50.109171Z","steps":["trace[1289218017] 'process raft request' (duration: 135.730356ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:50.282508Z","caller":"traceutil/trace.go:171","msg":"trace[738294148] linearizableReadLoop","detail":"{readStateIndex:25487; appliedIndex:25486; }","duration":"148.76697ms","start":"2026-04-27T21:47:50.133699Z","end":"2026-04-27T21:47:50.282466Z","steps":["trace[738294148] 'read index received' (duration: 108.79723ms)","trace[738294148] 'applied index is now lower than readState.Index' (duration: 39.96866ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:50.282603Z","caller":"traceutil/trace.go:171","msg":"trace[1862416871] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"150.554825ms","start":"2026-04-27T21:47:50.132009Z","end":"2026-04-27T21:47:50.282564Z","steps":["trace[1862416871] 'process raft request' (duration: 110.381021ms)","trace[1862416871] 'compare' (duration: 39.95445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:50.282745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.019285ms","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-04-27T21:47:50.282795Z","caller":"traceutil/trace.go:171","msg":"trace[265841153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24685; }","duration":"149.131306ms","start":"2026-04-27T21:47:50.133652Z","end":"2026-04-27T21:47:50.282783Z","steps":["trace[265841153] 'agreement among raft nodes before linearized reading' (duration: 148.955003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:50.572134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.970322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:50.572326Z","caller":"traceutil/trace.go:171","msg":"trace[1722015632] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"284.723652ms","start":"2026-04-27T21:47:50.287584Z","end":"2026-04-27T21:47:50.572307Z","steps":["trace[1722015632] 'process raft request' (duration: 284.663621ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:50.572477Z","caller":"traceutil/trace.go:171","msg":"trace[994007001] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"435.977298ms","start":"2026-04-27T21:47:50.136484Z","end":"2026-04-27T21:47:50.572461Z","steps":["trace[994007001] 'process raft request' (duration: 275.571298ms)","trace[994007001] 'compare' (duration: 159.85363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:50.572533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.136472Z","time spent":"436.0307ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:50.572551Z","caller":"traceutil/trace.go:171","msg":"trace[1292055317] linearizableReadLoop","detail":"{readStateIndex:25488; appliedIndex:25487; }","duration":"289.960589ms","start":"2026-04-27T21:47:50.282577Z","end":"2026-04-27T21:47:50.572538Z","steps":["trace[1292055317] 'read index received' (duration: 129.476048ms)","trace[1292055317] 'applied index is now lower than readState.Index' (duration: 160.483051ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:50.572725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.476232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-27T21:47:50.572735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.306905ms","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-04-27T21:47:50.572749Z","caller":"traceutil/trace.go:171","msg":"trace[212754137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24687; }","duration":"425.536743ms","start":"2026-04-27T21:47:50.147204Z","end":"2026-04-27T21:47:50.572741Z","steps":["trace[212754137] 'agreement among raft nodes before linearized reading' (duration: 425.471232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:50.572728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.984638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:50.57277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.147166Z","time spent":"425.598754ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T21:47:50.572772Z","caller":"traceutil/trace.go:171","msg":"trace[1020673140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24687; }","duration":"436.364865ms","start":"2026-04-27T21:47:50.136396Z","end":"2026-04-27T21:47:50.572761Z","steps":["trace[1020673140] 'agreement among raft nodes before linearized reading' (duration: 436.190662ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:50.57279Z","caller":"traceutil/trace.go:171","msg":"trace[863495522] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24687; }","duration":"396.09251ms","start":"2026-04-27T21:47:50.176678Z","end":"2026-04-27T21:47:50.572771Z","steps":["trace[863495522] 'agreement among raft nodes before linearized reading' (duration: 395.937738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:50.572801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.136385Z","time spent":"436.409096ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T21:47:50.572841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.176661Z","time spent":"396.162801ms","remote":"127.0.0.1:54044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:50.57284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.160328ms","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-04-27T21:47:50.572918Z","caller":"traceutil/trace.go:171","msg":"trace[713134008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24687; }","duration":"423.27271ms","start":"2026-04-27T21:47:50.149624Z","end":"2026-04-27T21:47:50.572897Z","steps":["trace[713134008] 'agreement among raft nodes before linearized reading' (duration: 423.000015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:50.572914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.951737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-27T21:47:50.572971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.149614Z","time spent":"423.348801ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T21:47:50.573022Z","caller":"traceutil/trace.go:171","msg":"trace[1977743189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24687; }","duration":"356.077409ms","start":"2026-04-27T21:47:50.216889Z","end":"2026-04-27T21:47:50.572966Z","steps":["trace[1977743189] 'agreement among raft nodes before linearized reading' (duration: 355.847655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:50.573076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.216857Z","time spent":"356.209542ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-27T21:47:50.959073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.258455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:50.959309Z","caller":"traceutil/trace.go:171","msg":"trace[579270255] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"382.433994ms","start":"2026-04-27T21:47:50.576843Z","end":"2026-04-27T21:47:50.959277Z","steps":["trace[579270255] 'process raft request' (duration: 236.857323ms)","trace[579270255] 'compare' (duration: 145.043791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:50.959439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.653322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:50.959474Z","caller":"traceutil/trace.go:171","msg":"trace[1777097526] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:24691; }","duration":"209.731724ms","start":"2026-04-27T21:47:50.749734Z","end":"2026-04-27T21:47:50.959465Z","steps":["trace[1777097526] 'agreement among raft nodes before linearized reading' (duration: 209.637842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:50.959467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.576821Z","time spent":"382.565037ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:50.95934Z","caller":"traceutil/trace.go:171","msg":"trace[190125220] linearizableReadLoop","detail":"{readStateIndex:25493; appliedIndex:25490; }","duration":"209.505009ms","start":"2026-04-27T21:47:50.749817Z","end":"2026-04-27T21:47:50.959322Z","steps":["trace[190125220] 'read index received' (duration: 63.905978ms)","trace[190125220] 'applied index is now lower than readState.Index' (duration: 145.597991ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:50.959661Z","caller":"traceutil/trace.go:171","msg":"trace[1262731505] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"381.664629ms","start":"2026-04-27T21:47:50.57796Z","end":"2026-04-27T21:47:50.959625Z","steps":["trace[1262731505] 'process raft request' (duration: 381.315653ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:50.959665Z","caller":"traceutil/trace.go:171","msg":"trace[69685332] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"381.74024ms","start":"2026-04-27T21:47:50.577891Z","end":"2026-04-27T21:47:50.959631Z","steps":["trace[69685332] 'process raft request' (duration: 381.274252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:50.95982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.577879Z","time spent":"381.859234ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:50.959841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.577954Z","time spent":"381.791332ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:51.55634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.502491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:51.556634Z","caller":"traceutil/trace.go:171","msg":"trace[1648481603] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"304.794797ms","start":"2026-04-27T21:47:51.251817Z","end":"2026-04-27T21:47:51.556612Z","steps":["trace[1648481603] 'process raft request' (duration: 304.735296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.556732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.251792Z","time spent":"304.88999ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:51.556861Z","caller":"traceutil/trace.go:171","msg":"trace[852842186] linearizableReadLoop","detail":"{readStateIndex:25494; appliedIndex:25493; }","duration":"597.251184ms","start":"2026-04-27T21:47:50.959586Z","end":"2026-04-27T21:47:51.556837Z","steps":["trace[852842186] 'read index received' (duration: 388.207303ms)","trace[852842186] 'applied index is now lower than readState.Index' (duration: 209.040551ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:51.556968Z","caller":"traceutil/trace.go:171","msg":"trace[987530735] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"729.23865ms","start":"2026-04-27T21:47:50.827684Z","end":"2026-04-27T21:47:51.556923Z","steps":["trace[987530735] 'process raft request' (duration: 520.026706ms)","trace[987530735] 'compare' (duration: 208.356268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:51.557045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.654716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:51.557072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.56836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:51.557037Z","caller":"traceutil/trace.go:171","msg":"trace[1889709482] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"392.716446ms","start":"2026-04-27T21:47:51.164238Z","end":"2026-04-27T21:47:51.556954Z","steps":["trace[1889709482] 'process raft request' (duration: 392.233108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.557074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.0167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:51.557104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.267196ms","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-04-27T21:47:51.557117Z","caller":"traceutil/trace.go:171","msg":"trace[1217755595] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24694; }","duration":"634.782447ms","start":"2026-04-27T21:47:50.922317Z","end":"2026-04-27T21:47:51.5571Z","steps":["trace[1217755595] 'agreement among raft nodes before linearized reading' (duration: 634.638084ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:51.557127Z","caller":"traceutil/trace.go:171","msg":"trace[1894354310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24694; }","duration":"137.666452ms","start":"2026-04-27T21:47:51.419445Z","end":"2026-04-27T21:47:51.557111Z","steps":["trace[1894354310] 'agreement among raft nodes before linearized reading' (duration: 137.576571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.557125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.550802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-04-27T21:47:51.557149Z","caller":"traceutil/trace.go:171","msg":"trace[794103844] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24694; }","duration":"395.128312ms","start":"2026-04-27T21:47:51.162006Z","end":"2026-04-27T21:47:51.557134Z","steps":["trace[794103844] 'agreement among raft nodes before linearized reading' (duration: 395.01369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.557143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.827659Z","time spent":"729.399712ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:51.557154Z","caller":"traceutil/trace.go:171","msg":"trace[1122311927] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24694; }","duration":"281.376548ms","start":"2026-04-27T21:47:51.275762Z","end":"2026-04-27T21:47:51.557138Z","steps":["trace[1122311927] 'agreement among raft nodes before linearized reading' (duration: 281.282556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.557163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.922292Z","time spent":"634.860929ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:47:51.557183Z","caller":"traceutil/trace.go:171","msg":"trace[826236281] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24694; }","duration":"707.651534ms","start":"2026-04-27T21:47:50.849515Z","end":"2026-04-27T21:47:51.557166Z","steps":["trace[826236281] 'agreement among raft nodes before linearized reading' (duration: 707.442211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:51.557219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:50.849493Z","time spent":"707.718375ms","remote":"127.0.0.1:53516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"warn","ts":"2026-04-27T21:47:51.557194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.161987Z","time spent":"395.192904ms","remote":"127.0.0.1:40212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:51.55722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.164198Z","time spent":"392.933051ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:52.043286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.05146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:52.043367Z","caller":"traceutil/trace.go:171","msg":"trace[730008274] linearizableReadLoop","detail":"{readStateIndex:25498; appliedIndex:25497; }","duration":"264.339598ms","start":"2026-04-27T21:47:51.779015Z","end":"2026-04-27T21:47:52.043354Z","steps":["trace[730008274] 'read index received' (duration: 110.072274ms)","trace[730008274] 'applied index is now lower than readState.Index' (duration: 154.266034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:52.043451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.438781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:52.043469Z","caller":"traceutil/trace.go:171","msg":"trace[282138388] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24696; }","duration":"264.487732ms","start":"2026-04-27T21:47:51.778974Z","end":"2026-04-27T21:47:52.043462Z","steps":["trace[282138388] 'agreement among raft nodes before linearized reading' (duration: 264.442391ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:52.04344Z","caller":"traceutil/trace.go:171","msg":"trace[1492140558] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"354.353568ms","start":"2026-04-27T21:47:51.68906Z","end":"2026-04-27T21:47:52.043413Z","steps":["trace[1492140558] 'process raft request' (duration: 200.084683ms)","trace[1492140558] 'compare' (duration: 153.826156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:52.043575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:51.689039Z","time spent":"354.45825ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:52.043595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.651692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-27T21:47:52.043633Z","caller":"traceutil/trace.go:171","msg":"trace[1523012929] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24696; }","duration":"178.712572ms","start":"2026-04-27T21:47:51.86491Z","end":"2026-04-27T21:47:52.043622Z","steps":["trace[1523012929] 'agreement among raft nodes before linearized reading' (duration: 178.58247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.09909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040238979485855,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T21:47:57.322498Z","caller":"traceutil/trace.go:171","msg":"trace[1467346604] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"758.730463ms","start":"2026-04-27T21:47:56.563737Z","end":"2026-04-27T21:47:57.322468Z","steps":["trace[1467346604] 'process raft request' (duration: 758.480689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.322669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.56367Z","time spent":"758.912706ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:57.70643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.076885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:57.706735Z","caller":"traceutil/trace.go:171","msg":"trace[1030187941] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"1.055526021s","start":"2026-04-27T21:47:56.651169Z","end":"2026-04-27T21:47:57.706695Z","steps":["trace[1030187941] 'process raft request' (duration: 696.034928ms)","trace[1030187941] 'compare' (duration: 358.857341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:57.706859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.651145Z","time spent":"1.055669763s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:57.707136Z","caller":"traceutil/trace.go:171","msg":"trace[200528998] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25530; }","duration":"1.108712838s","start":"2026-04-27T21:47:56.598393Z","end":"2026-04-27T21:47:57.707106Z","steps":["trace[200528998] 'read index received' (duration: 723.889929ms)","trace[200528998] 'applied index is now lower than readState.Index' (duration: 384.820399ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:57.707155Z","caller":"traceutil/trace.go:171","msg":"trace[964880152] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"101.851241ms","start":"2026-04-27T21:47:57.605273Z","end":"2026-04-27T21:47:57.707125Z","steps":["trace[964880152] 'process raft request' (duration: 101.29264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.707489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109074295s","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-04-27T21:47:57.707575Z","caller":"traceutil/trace.go:171","msg":"trace[1575248494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24731; }","duration":"1.109227928s","start":"2026-04-27T21:47:56.598329Z","end":"2026-04-27T21:47:57.707557Z","steps":["trace[1575248494] 'agreement among raft nodes before linearized reading' (duration: 1.108889992s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.707627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.598303Z","time spent":"1.109314589s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:47:57.707693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.043026ms","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-04-27T21:47:57.707748Z","caller":"traceutil/trace.go:171","msg":"trace[2132743509] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24731; }","duration":"697.156008ms","start":"2026-04-27T21:47:57.010579Z","end":"2026-04-27T21:47:57.707735Z","steps":["trace[2132743509] 'agreement among raft nodes before linearized reading' (duration: 696.984975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.707789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.010553Z","time spent":"697.227599ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-27T21:47:57.707802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.759001ms","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-04-27T21:47:57.707892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.916143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:57.707875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.902018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:47:57.707912Z","caller":"traceutil/trace.go:171","msg":"trace[2003795533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24731; }","duration":"711.924644ms","start":"2026-04-27T21:47:56.995964Z","end":"2026-04-27T21:47:57.707888Z","steps":["trace[2003795533] 'agreement among raft nodes before linearized reading' (duration: 711.619199ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.707939Z","caller":"traceutil/trace.go:171","msg":"trace[365840069] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24731; }","duration":"825.961364ms","start":"2026-04-27T21:47:56.881963Z","end":"2026-04-27T21:47:57.707924Z","steps":["trace[365840069] 'agreement among raft nodes before linearized reading' (duration: 825.883722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.707964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.995944Z","time spent":"712.010156ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T21:47:57.707964Z","caller":"traceutil/trace.go:171","msg":"trace[1825690779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24731; }","duration":"717.029451ms","start":"2026-04-27T21:47:56.990911Z","end":"2026-04-27T21:47:57.70794Z","steps":["trace[1825690779] 'agreement among raft nodes before linearized reading' (duration: 716.659454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.707977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.881915Z","time spent":"826.053116ms","remote":"127.0.0.1:51702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:57.708009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.990897Z","time spent":"717.102742ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T21:47:57.708011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10771129s","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-04-27T21:47:57.708109Z","caller":"traceutil/trace.go:171","msg":"trace[839000066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24731; }","duration":"1.107961144s","start":"2026-04-27T21:47:56.600134Z","end":"2026-04-27T21:47:57.708095Z","steps":["trace[839000066] 'agreement among raft nodes before linearized reading' (duration: 1.107383233s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.708143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.916099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-27T21:47:57.708181Z","caller":"traceutil/trace.go:171","msg":"trace[1808827026] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24731; }","duration":"570.98368ms","start":"2026-04-27T21:47:57.137189Z","end":"2026-04-27T21:47:57.708173Z","steps":["trace[1808827026] 'agreement among raft nodes before linearized reading' (duration: 570.882248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.7082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086116654s","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-04-27T21:47:57.708251Z","caller":"traceutil/trace.go:171","msg":"trace[738330565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24731; }","duration":"1.086203235s","start":"2026-04-27T21:47:56.622033Z","end":"2026-04-27T21:47:57.708237Z","steps":["trace[738330565] 'agreement among raft nodes before linearized reading' (duration: 1.086090423s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.708293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.622015Z","time spent":"1.086265526s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:47:57.708308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.746497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-04-27T21:47:57.708049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.074172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:47:57.708338Z","caller":"traceutil/trace.go:171","msg":"trace[372371759] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:24731; }","duration":"603.801167ms","start":"2026-04-27T21:47:57.104528Z","end":"2026-04-27T21:47:57.708329Z","steps":["trace[372371759] 'agreement among raft nodes before linearized reading' (duration: 603.725195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.708382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.10451Z","time spent":"603.867498ms","remote":"127.0.0.1:53384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.19.213.132\" "} {"level":"warn","ts":"2026-04-27T21:47:57.708219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:56.600125Z","time spent":"1.108035065s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T21:47:57.708464Z","caller":"traceutil/trace.go:171","msg":"trace[1195958195] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24731; }","duration":"531.405759ms","start":"2026-04-27T21:47:57.176962Z","end":"2026-04-27T21:47:57.708368Z","steps":["trace[1195958195] 'agreement among raft nodes before linearized reading' (duration: 531.054071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:57.708209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.137172Z","time spent":"571.030491ms","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-27T21:47:57.7092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.176906Z","time spent":"531.621503ms","remote":"127.0.0.1:57152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:47:57.931843Z","caller":"traceutil/trace.go:171","msg":"trace[1011882165] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"218.654482ms","start":"2026-04-27T21:47:57.713157Z","end":"2026-04-27T21:47:57.931811Z","steps":["trace[1011882165] 'process raft request' (duration: 218.311356ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.931955Z","caller":"traceutil/trace.go:171","msg":"trace[1346375388] linearizableReadLoop","detail":"{readStateIndex:25542; appliedIndex:25535; }","duration":"193.182034ms","start":"2026-04-27T21:47:57.738746Z","end":"2026-04-27T21:47:57.931928Z","steps":["trace[1346375388] 'read index received' (duration: 192.817797ms)","trace[1346375388] 'applied index is now lower than readState.Index' (duration: 362.927µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:57.932176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.409208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-04-27T21:47:57.932239Z","caller":"traceutil/trace.go:171","msg":"trace[129842612] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24738; }","duration":"193.493359ms","start":"2026-04-27T21:47:57.738727Z","end":"2026-04-27T21:47:57.932221Z","steps":["trace[129842612] 'agreement among raft nodes before linearized reading' (duration: 193.296446ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.932425Z","caller":"traceutil/trace.go:171","msg":"trace[237732057] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"218.463868ms","start":"2026-04-27T21:47:57.713905Z","end":"2026-04-27T21:47:57.932368Z","steps":["trace[237732057] 'process raft request' (duration: 217.858866ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.93249Z","caller":"traceutil/trace.go:171","msg":"trace[1849629699] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"218.213283ms","start":"2026-04-27T21:47:57.714242Z","end":"2026-04-27T21:47:57.932455Z","steps":["trace[1849629699] 'process raft request' (duration: 217.600951ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.932524Z","caller":"traceutil/trace.go:171","msg":"trace[254862284] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"202.702112ms","start":"2026-04-27T21:47:57.729809Z","end":"2026-04-27T21:47:57.932512Z","steps":["trace[254862284] 'process raft request' (duration: 202.08089ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.932635Z","caller":"traceutil/trace.go:171","msg":"trace[1002740901] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"218.6134ms","start":"2026-04-27T21:47:57.714008Z","end":"2026-04-27T21:47:57.932622Z","steps":["trace[1002740901] 'process raft request' (duration: 217.800905ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.932693Z","caller":"traceutil/trace.go:171","msg":"trace[1990330660] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"218.825256ms","start":"2026-04-27T21:47:57.713854Z","end":"2026-04-27T21:47:57.932679Z","steps":["trace[1990330660] 'process raft request' (duration: 217.877967ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:57.932456Z","caller":"traceutil/trace.go:171","msg":"trace[1104112585] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"218.640353ms","start":"2026-04-27T21:47:57.713771Z","end":"2026-04-27T21:47:57.932411Z","steps":["trace[1104112585] 'process raft request' (duration: 217.904808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:58.189061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.871986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:58.189223Z","caller":"traceutil/trace.go:171","msg":"trace[2057348643] linearizableReadLoop","detail":"{readStateIndex:25543; appliedIndex:25542; }","duration":"200.661334ms","start":"2026-04-27T21:47:57.988545Z","end":"2026-04-27T21:47:58.189207Z","steps":["trace[2057348643] 'read index received' (duration: 54.489813ms)","trace[2057348643] 'applied index is now lower than readState.Index' (duration: 146.167521ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:58.189288Z","caller":"traceutil/trace.go:171","msg":"trace[2041126716] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"450.48014ms","start":"2026-04-27T21:47:57.738799Z","end":"2026-04-27T21:47:58.189279Z","steps":["trace[2041126716] 'process raft request' (duration: 304.296519ms)","trace[2041126716] 'compare' (duration: 145.743553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:58.189336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.738782Z","time spent":"450.531432ms","remote":"127.0.0.1:53384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:58.18946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.902758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:47:58.189555Z","caller":"traceutil/trace.go:171","msg":"trace[1301893920] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24739; }","duration":"201.050551ms","start":"2026-04-27T21:47:57.988483Z","end":"2026-04-27T21:47:58.189534Z","steps":["trace[1301893920] 'agreement among raft nodes before linearized reading' (duration: 200.845907ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:58.470799Z","caller":"traceutil/trace.go:171","msg":"trace[705322702] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"396.957956ms","start":"2026-04-27T21:47:58.073813Z","end":"2026-04-27T21:47:58.470771Z","steps":["trace[705322702] 'process raft request' (duration: 396.774453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:58.470943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:58.073784Z","time spent":"397.080508ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:58.735888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.97609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:47:58.736152Z","caller":"traceutil/trace.go:171","msg":"trace[1852147610] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"600.601386ms","start":"2026-04-27T21:47:58.135527Z","end":"2026-04-27T21:47:58.736129Z","steps":["trace[1852147610] 'process raft request' (duration: 456.294939ms)","trace[1852147610] 'compare' (duration: 143.792147ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:58.736194Z","caller":"traceutil/trace.go:171","msg":"trace[1417268378] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"595.281588ms","start":"2026-04-27T21:47:58.140891Z","end":"2026-04-27T21:47:58.736173Z","steps":["trace[1417268378] 'process raft request' (duration: 595.116995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:58.736245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:58.135502Z","time spent":"600.704999ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:58.736301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:58.140877Z","time spent":"595.365809ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:58.73637Z","caller":"traceutil/trace.go:171","msg":"trace[1651094930] linearizableReadLoop","detail":"{readStateIndex:25546; appliedIndex:25543; }","duration":"547.112433ms","start":"2026-04-27T21:47:58.189248Z","end":"2026-04-27T21:47:58.736361Z","steps":["trace[1651094930] 'read index received' (duration: 281.359848ms)","trace[1651094930] 'applied index is now lower than readState.Index' (duration: 265.750845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:47:58.736472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.905883ms","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-04-27T21:47:58.736464Z","caller":"traceutil/trace.go:171","msg":"trace[16827414] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"419.50947ms","start":"2026-04-27T21:47:58.316921Z","end":"2026-04-27T21:47:58.73643Z","steps":["trace[16827414] 'process raft request' (duration: 419.195094ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:58.736496Z","caller":"traceutil/trace.go:171","msg":"trace[1896897415] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24743; }","duration":"744.952565ms","start":"2026-04-27T21:47:57.991537Z","end":"2026-04-27T21:47:58.736489Z","steps":["trace[1896897415] 'agreement among raft nodes before linearized reading' (duration: 744.888243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:58.736534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:57.991494Z","time spent":"745.035035ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-27T21:47:58.736621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:58.3169Z","time spent":"419.632492ms","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:47:58.736709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.09096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:58.736738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.952249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:47:58.736749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.003743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-04-27T21:47:58.736785Z","caller":"traceutil/trace.go:171","msg":"trace[79841022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24743; }","duration":"315.200393ms","start":"2026-04-27T21:47:58.421562Z","end":"2026-04-27T21:47:58.736762Z","steps":["trace[79841022] 'agreement among raft nodes before linearized reading' (duration: 315.06903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:58.736824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:58.421544Z","time spent":"315.272574ms","remote":"127.0.0.1:53350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:47:58.736821Z","caller":"traceutil/trace.go:171","msg":"trace[1845494574] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24743; }","duration":"483.085142ms","start":"2026-04-27T21:47:58.253714Z","end":"2026-04-27T21:47:58.7368Z","steps":["trace[1845494574] 'agreement among raft nodes before linearized reading' (duration: 482.932199ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:58.736825Z","caller":"traceutil/trace.go:171","msg":"trace[1774641088] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24743; }","duration":"545.120715ms","start":"2026-04-27T21:47:58.191686Z","end":"2026-04-27T21:47:58.736806Z","steps":["trace[1774641088] 'agreement among raft nodes before linearized reading' (duration: 544.925892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:58.736868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:58.191665Z","time spent":"545.194536ms","remote":"127.0.0.1:53530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-27T21:47:58.736866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:47:58.253687Z","time spent":"483.168913ms","remote":"127.0.0.1:51786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:47:59.013543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.726045ms","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-04-27T21:47:59.013633Z","caller":"traceutil/trace.go:171","msg":"trace[689654710] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:24743; }","duration":"112.932738ms","start":"2026-04-27T21:47:58.900682Z","end":"2026-04-27T21:47:59.013614Z","steps":["trace[689654710] 'count revisions from in-memory index tree' (duration: 112.626473ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:59.860289Z","caller":"traceutil/trace.go:171","msg":"trace[657297862] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"144.727334ms","start":"2026-04-27T21:47:59.715544Z","end":"2026-04-27T21:47:59.860271Z","steps":["trace[657297862] 'process raft request' (duration: 144.607622ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.133945Z","caller":"traceutil/trace.go:171","msg":"trace[1660198774] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"135.146306ms","start":"2026-04-27T21:47:59.998772Z","end":"2026-04-27T21:48:00.133919Z","steps":["trace[1660198774] 'process raft request' (duration: 132.310743ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.133948Z","caller":"traceutil/trace.go:171","msg":"trace[711255345] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"135.115685ms","start":"2026-04-27T21:47:59.998809Z","end":"2026-04-27T21:48:00.133925Z","steps":["trace[711255345] 'process raft request' (duration: 135.018603ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.134236Z","caller":"traceutil/trace.go:171","msg":"trace[945120702] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"134.703187ms","start":"2026-04-27T21:47:59.9995Z","end":"2026-04-27T21:48:00.134204Z","steps":["trace[945120702] 'process raft request' (duration: 134.374921ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.134355Z","caller":"traceutil/trace.go:171","msg":"trace[1705297438] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"133.26084ms","start":"2026-04-27T21:48:00.001082Z","end":"2026-04-27T21:48:00.134343Z","steps":["trace[1705297438] 'process raft request' (duration: 133.180328ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.13436Z","caller":"traceutil/trace.go:171","msg":"trace[2120038129] linearizableReadLoop","detail":"{readStateIndex:25557; appliedIndex:25550; }","duration":"123.197591ms","start":"2026-04-27T21:48:00.01114Z","end":"2026-04-27T21:48:00.134337Z","steps":["trace[2120038129] 'read index received' (duration: 2.571849ms)","trace[2120038129] 'applied index is now lower than readState.Index' (duration: 120.624182ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:00.134389Z","caller":"traceutil/trace.go:171","msg":"trace[1698989982] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"134.607314ms","start":"2026-04-27T21:47:59.999728Z","end":"2026-04-27T21:48:00.134335Z","steps":["trace[1698989982] 'process raft request' (duration: 134.496892ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:00.134407Z","caller":"traceutil/trace.go:171","msg":"trace[1302968378] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"134.693036ms","start":"2026-04-27T21:47:59.999681Z","end":"2026-04-27T21:48:00.134374Z","steps":["trace[1302968378] 'process raft request' (duration: 134.434211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:00.134654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.418365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:48:00.134701Z","caller":"traceutil/trace.go:171","msg":"trace[1393323056] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24753; }","duration":"123.554298ms","start":"2026-04-27T21:48:00.011135Z","end":"2026-04-27T21:48:00.134689Z","steps":["trace[1393323056] 'agreement among raft nodes before linearized reading' (duration: 123.267243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:02.743639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.089088ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T21:48:12.127876Z","caller":"traceutil/trace.go:171","msg":"trace[592112330] linearizableReadLoop","detail":"{readStateIndex:25638; appliedIndex:25637; }","duration":"209.368057ms","start":"2026-04-27T21:48:11.918471Z","end":"2026-04-27T21:48:12.127839Z","steps":["trace[592112330] 'read index received' (duration: 209.088802ms)","trace[592112330] 'applied index is now lower than readState.Index' (duration: 276.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:12.127994Z","caller":"traceutil/trace.go:171","msg":"trace[1498314599] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"220.823872ms","start":"2026-04-27T21:48:11.907142Z","end":"2026-04-27T21:48:12.127966Z","steps":["trace[1498314599] 'process raft request' (duration: 220.454415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:12.128169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.666802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-27T21:48:12.128238Z","caller":"traceutil/trace.go:171","msg":"trace[2080653686] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24832; }","duration":"209.810355ms","start":"2026-04-27T21:48:11.918405Z","end":"2026-04-27T21:48:12.128216Z","steps":["trace[2080653686] 'agreement among raft nodes before linearized reading' (duration: 209.567831ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:12.44158Z","caller":"traceutil/trace.go:171","msg":"trace[1859120240] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"241.685973ms","start":"2026-04-27T21:48:12.199872Z","end":"2026-04-27T21:48:12.441558Z","steps":["trace[1859120240] 'process raft request' (duration: 241.600082ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:12.441895Z","caller":"traceutil/trace.go:171","msg":"trace[448722204] linearizableReadLoop","detail":"{readStateIndex:25645; appliedIndex:25642; }","duration":"123.053808ms","start":"2026-04-27T21:48:12.318813Z","end":"2026-04-27T21:48:12.441867Z","steps":["trace[448722204] 'read index received' (duration: 122.457157ms)","trace[448722204] 'applied index is now lower than readState.Index' (duration: 595.291µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:12.441583Z","caller":"traceutil/trace.go:171","msg":"trace[1181502329] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"242.075501ms","start":"2026-04-27T21:48:12.19948Z","end":"2026-04-27T21:48:12.441556Z","steps":["trace[1181502329] 'process raft request' (duration: 241.807796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:12.44218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.345664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:12.442233Z","caller":"traceutil/trace.go:171","msg":"trace[1473264889] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24839; }","duration":"123.457596ms","start":"2026-04-27T21:48:12.318761Z","end":"2026-04-27T21:48:12.442219Z","steps":["trace[1473264889] 'agreement among raft nodes before linearized reading' (duration: 123.359904ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:12.4421Z","caller":"traceutil/trace.go:171","msg":"trace[1040156718] transaction","detail":"{read_only:false; response_revision:24839; number_of_response:1; }","duration":"241.276407ms","start":"2026-04-27T21:48:12.200811Z","end":"2026-04-27T21:48:12.442087Z","steps":["trace[1040156718] 'process raft request' (duration: 240.711135ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:12.464469Z","caller":"traceutil/trace.go:171","msg":"trace[1003686342] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"118.90686ms","start":"2026-04-27T21:48:12.345539Z","end":"2026-04-27T21:48:12.464446Z","steps":["trace[1003686342] 'process raft request' (duration: 118.196756ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:12.609658Z","caller":"traceutil/trace.go:171","msg":"trace[1757368461] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"137.580671ms","start":"2026-04-27T21:48:12.47204Z","end":"2026-04-27T21:48:12.60962Z","steps":["trace[1757368461] 'process raft request' (duration: 122.634871ms)","trace[1757368461] 'compare' (duration: 14.513352ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:12.651789Z","caller":"traceutil/trace.go:171","msg":"trace[1882804340] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"130.524569ms","start":"2026-04-27T21:48:12.521222Z","end":"2026-04-27T21:48:12.651747Z","steps":["trace[1882804340] 'process raft request' (duration: 130.141912ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:15.08011Z","caller":"traceutil/trace.go:171","msg":"trace[676903221] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"128.513711ms","start":"2026-04-27T21:48:14.95158Z","end":"2026-04-27T21:48:15.080094Z","steps":["trace[676903221] 'process raft request' (duration: 62.270847ms)","trace[676903221] 'compare' (duration: 66.150602ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:15.116314Z","caller":"traceutil/trace.go:171","msg":"trace[1182741727] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"164.603698ms","start":"2026-04-27T21:48:14.951692Z","end":"2026-04-27T21:48:15.116296Z","steps":["trace[1182741727] 'process raft request' (duration: 164.429134ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:15.116685Z","caller":"traceutil/trace.go:171","msg":"trace[989674453] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"164.322282ms","start":"2026-04-27T21:48:14.952352Z","end":"2026-04-27T21:48:15.116674Z","steps":["trace[989674453] 'process raft request' (duration: 163.893984ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:15.117061Z","caller":"traceutil/trace.go:171","msg":"trace[939788920] linearizableReadLoop","detail":"{readStateIndex:25770; appliedIndex:25767; }","duration":"152.113004ms","start":"2026-04-27T21:48:14.964919Z","end":"2026-04-27T21:48:15.117032Z","steps":["trace[939788920] 'read index received' (duration: 48.951659ms)","trace[939788920] 'applied index is now lower than readState.Index' (duration: 103.160465ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:15.117068Z","caller":"traceutil/trace.go:171","msg":"trace[1244851873] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"152.085432ms","start":"2026-04-27T21:48:14.964946Z","end":"2026-04-27T21:48:15.117032Z","steps":["trace[1244851873] 'process raft request' (duration: 151.611724ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:15.1172Z","caller":"traceutil/trace.go:171","msg":"trace[1062801663] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"152.194354ms","start":"2026-04-27T21:48:14.964993Z","end":"2026-04-27T21:48:15.117187Z","steps":["trace[1062801663] 'process raft request' (duration: 151.893199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:15.117369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.665696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-04-27T21:48:15.117427Z","caller":"traceutil/trace.go:171","msg":"trace[1077704345] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:24964; }","duration":"150.761397ms","start":"2026-04-27T21:48:14.96665Z","end":"2026-04-27T21:48:15.117411Z","steps":["trace[1077704345] 'agreement among raft nodes before linearized reading' (duration: 150.612674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:15.117649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.8415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/manila-data\" ","response":"range_response_count:1 size:6580"} {"level":"warn","ts":"2026-04-27T21:48:15.117658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.720236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/manila-data-799ff9c4c8-5qvdc\" ","response":"range_response_count:1 size:5736"} {"level":"info","ts":"2026-04-27T21:48:15.117689Z","caller":"traceutil/trace.go:171","msg":"trace[2081785562] range","detail":"{range_begin:/registry/deployments/openstack/manila-data; range_end:; response_count:1; response_revision:24964; }","duration":"150.897641ms","start":"2026-04-27T21:48:14.966781Z","end":"2026-04-27T21:48:15.117678Z","steps":["trace[2081785562] 'agreement among raft nodes before linearized reading' (duration: 150.796649ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:15.117699Z","caller":"traceutil/trace.go:171","msg":"trace[1248996967] range","detail":"{range_begin:/registry/pods/openstack/manila-data-799ff9c4c8-5qvdc; range_end:; response_count:1; response_revision:24964; }","duration":"152.799717ms","start":"2026-04-27T21:48:14.964888Z","end":"2026-04-27T21:48:15.117688Z","steps":["trace[1248996967] 'agreement among raft nodes before linearized reading' (duration: 152.640903ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:19.103998Z","caller":"traceutil/trace.go:171","msg":"trace[391193292] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"149.879022ms","start":"2026-04-27T21:48:18.954088Z","end":"2026-04-27T21:48:19.103967Z","steps":["trace[391193292] 'process raft request' (duration: 149.633927ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:19.127038Z","caller":"traceutil/trace.go:171","msg":"trace[571768935] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"155.988086ms","start":"2026-04-27T21:48:18.971015Z","end":"2026-04-27T21:48:19.127003Z","steps":["trace[571768935] 'process raft request' (duration: 155.6571ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:20.714361Z","caller":"traceutil/trace.go:171","msg":"trace[2071997927] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"134.979103ms","start":"2026-04-27T21:48:20.57936Z","end":"2026-04-27T21:48:20.714339Z","steps":["trace[2071997927] 'process raft request' (duration: 134.83452ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:20.728136Z","caller":"traceutil/trace.go:171","msg":"trace[1755634315] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"148.72705ms","start":"2026-04-27T21:48:20.579394Z","end":"2026-04-27T21:48:20.728121Z","steps":["trace[1755634315] 'process raft request' (duration: 148.355112ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:27.939612Z","caller":"traceutil/trace.go:171","msg":"trace[2056762978] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25939; }","duration":"121.173893ms","start":"2026-04-27T21:48:27.818417Z","end":"2026-04-27T21:48:27.93959Z","steps":["trace[2056762978] 'read index received' (duration: 120.921378ms)","trace[2056762978] 'applied index is now lower than readState.Index' (duration: 251.675µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:27.939698Z","caller":"traceutil/trace.go:171","msg":"trace[814154433] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"142.765908ms","start":"2026-04-27T21:48:27.796902Z","end":"2026-04-27T21:48:27.939668Z","steps":["trace[814154433] 'process raft request' (duration: 142.531634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:27.939806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.364726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:48:27.939876Z","caller":"traceutil/trace.go:171","msg":"trace[92755051] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25128; }","duration":"121.512239ms","start":"2026-04-27T21:48:27.818349Z","end":"2026-04-27T21:48:27.939861Z","steps":["trace[92755051] 'agreement among raft nodes before linearized reading' (duration: 121.334616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:28.101449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.170365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:48:28.101542Z","caller":"traceutil/trace.go:171","msg":"trace[723982290] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"127.38392ms","start":"2026-04-27T21:48:27.974142Z","end":"2026-04-27T21:48:28.101525Z","steps":["trace[723982290] 'compare' (duration: 120.072053ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:35.016906Z","caller":"traceutil/trace.go:171","msg":"trace[331528470] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"152.776656ms","start":"2026-04-27T21:48:34.864102Z","end":"2026-04-27T21:48:35.016879Z","steps":["trace[331528470] 'process raft request' (duration: 152.478021ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:35.017084Z","caller":"traceutil/trace.go:171","msg":"trace[762560417] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"150.590414ms","start":"2026-04-27T21:48:34.866464Z","end":"2026-04-27T21:48:35.017054Z","steps":["trace[762560417] 'process raft request' (duration: 150.36029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:48:35.38003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.087444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:48:35.38027Z","caller":"traceutil/trace.go:171","msg":"trace[1139957418] linearizableReadLoop","detail":"{readStateIndex:26004; appliedIndex:26002; }","duration":"117.727328ms","start":"2026-04-27T21:48:35.262521Z","end":"2026-04-27T21:48:35.380248Z","steps":["trace[1139957418] 'read index received' (duration: 82.65503ms)","trace[1139957418] 'applied index is now lower than readState.Index' (duration: 35.071348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:48:35.380373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.851142ms","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-04-27T21:48:35.380419Z","caller":"traceutil/trace.go:171","msg":"trace[485614604] 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:25191; }","duration":"117.933293ms","start":"2026-04-27T21:48:35.262475Z","end":"2026-04-27T21:48:35.380408Z","steps":["trace[485614604] 'agreement among raft nodes before linearized reading' (duration: 117.832281ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:35.38034Z","caller":"traceutil/trace.go:171","msg":"trace[328089457] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"183.996221ms","start":"2026-04-27T21:48:35.196274Z","end":"2026-04-27T21:48:35.38027Z","steps":["trace[328089457] 'process raft request' (duration: 56.575061ms)","trace[328089457] 'compare' (duration: 126.87188ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:48:35.38049Z","caller":"traceutil/trace.go:171","msg":"trace[1333419958] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"159.682426ms","start":"2026-04-27T21:48:35.220773Z","end":"2026-04-27T21:48:35.380455Z","steps":["trace[1333419958] 'process raft request' (duration: 159.384769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:09.450287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.979691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:49:09.450429Z","caller":"traceutil/trace.go:171","msg":"trace[968675432] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25466; }","duration":"126.150214ms","start":"2026-04-27T21:49:09.324231Z","end":"2026-04-27T21:49:09.450381Z","steps":["trace[968675432] 'count revisions from in-memory index tree' (duration: 125.90595ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:55.843152Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006869.wal"} {"level":"info","ts":"2026-04-27T21:50:44.064428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23495} {"level":"info","ts":"2026-04-27T21:50:44.194163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23495,"took":"126.149764ms","hash":2406614731,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":37232640,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-27T21:50:44.194226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2406614731,"revision":23495,"compact-revision":20304} {"level":"info","ts":"2026-04-27T21:55:44.070791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26410} {"level":"info","ts":"2026-04-27T21:55:44.211143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26410,"took":"136.515757ms","hash":733960035,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":32145408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T21:55:44.211214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":733960035,"revision":26410,"compact-revision":23495} {"level":"info","ts":"2026-04-27T21:56:13.158401Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"19a81b20c4b111c3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:56:13.163415Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-27T21:56:13.163584Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-27T22:00:44.076343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28870} {"level":"info","ts":"2026-04-27T22:00:44.205143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28870,"took":"123.725928ms","hash":95332928,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":30322688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-27T22:00:44.205191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":95332928,"revision":28870,"compact-revision":26410} {"level":"info","ts":"2026-04-27T22:05:44.084699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31156} {"level":"info","ts":"2026-04-27T22:05:44.201896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31156,"took":"114.128878ms","hash":1982808864,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":32497664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T22:05:44.201945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1982808864,"revision":31156,"compact-revision":28870} {"level":"info","ts":"2026-04-27T22:10:44.091885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33521} {"level":"info","ts":"2026-04-27T22:10:44.241284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33521,"took":"145.15694ms","hash":3214489078,"current-db-size-bytes":59809792,"current-db-size":"60 MB","current-db-size-in-use-bytes":33030144,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-27T22:10:44.241358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3214489078,"revision":33521,"compact-revision":31156} {"level":"info","ts":"2026-04-27T22:15:44.097775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35748} {"level":"info","ts":"2026-04-27T22:15:44.233071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35748,"took":"130.790421ms","hash":1857674856,"current-db-size-bytes":59809792,"current-db-size":"60 MB","current-db-size-in-use-bytes":30294016,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-27T22:15:44.233131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1857674856,"revision":35748,"compact-revision":33521}