{"level":"warn","ts":"2026-04-20T10:18:45.217274Z","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-20T10:18:45.217387Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-20T10:18:45.217477Z","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-20T10:18:45.217491Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-20T10:18:45.217519Z","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-20T10:18:45.218173Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-04-20T10:18:45.218252Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-20T10:18:45.221918Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.429039ms"} {"level":"info","ts":"2026-04-20T10:18:45.23454Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-04-20T10:18:45.23467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:18:45.234696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-04-20T10:18:45.234711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:18:45.23473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-04-20T10:18:45.234783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-04-20T10:18:45.240203Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:18:45.241988Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:18:45.243821Z","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-20T10:18:45.24594Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:18:45.2462Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:18:45.246448Z","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-20T10:18:45.246532Z","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-20T10:18:45.246557Z","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-20T10:18:45.248445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-04-20T10:18:45.24858Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-20T10:18:45.250837Z","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-20T10:18:45.250983Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-20T10:18:45.251036Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-20T10:18:45.251127Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:18:45.251176Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:18:45.935149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:18:45.935222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:18:45.935266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-04-20T10:18:45.935294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:18:45.93531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-20T10:18:45.935347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-04-20T10:18:45.935372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-20T10:18:45.936556Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:45.936924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:18:45.936955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:18:45.936991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:18:45.93731Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:18:45.937446Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:18:45.937645Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:45.937718Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:45.93774Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:18:45.938699Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-04-20T10:18:45.940378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:19:26.366096Z","caller":"traceutil/trace.go:171","msg":"trace[1798512450] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"233.81975ms","start":"2026-04-20T10:19:26.132248Z","end":"2026-04-20T10:19:26.366067Z","steps":["trace[1798512450] 'read index received' (duration: 232.13114ms)","trace[1798512450] 'applied index is now lower than readState.Index' (duration: 1.68759ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:26.366199Z","caller":"traceutil/trace.go:171","msg":"trace[1440231910] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"244.823528ms","start":"2026-04-20T10:19:26.121317Z","end":"2026-04-20T10:19:26.366141Z","steps":["trace[1440231910] 'process raft request' (duration: 243.429647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.366308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.021626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.36639Z","caller":"traceutil/trace.go:171","msg":"trace[1443533278] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"234.16717ms","start":"2026-04-20T10:19:26.13221Z","end":"2026-04-20T10:19:26.366377Z","steps":["trace[1443533278] 'agreement among raft nodes before linearized reading' (duration: 234.026776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.366613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.210792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:26.366813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.076945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:26.366886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.724565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.366924Z","caller":"traceutil/trace.go:171","msg":"trace[1786382015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"172.781847ms","start":"2026-04-20T10:19:26.19412Z","end":"2026-04-20T10:19:26.366902Z","steps":["trace[1786382015] 'agreement among raft nodes before linearized reading' (duration: 172.745276ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:26.36689Z","caller":"traceutil/trace.go:171","msg":"trace[2026627800] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"143.186847ms","start":"2026-04-20T10:19:26.223685Z","end":"2026-04-20T10:19:26.366872Z","steps":["trace[2026627800] 'agreement among raft nodes before linearized reading' (duration: 143.073024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.367013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.926639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:26.367061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.843421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:26.367144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.313173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.367171Z","caller":"traceutil/trace.go:171","msg":"trace[1579769189] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"234.389405ms","start":"2026-04-20T10:19:26.132773Z","end":"2026-04-20T10:19:26.367163Z","steps":["trace[1579769189] 'agreement among raft nodes before linearized reading' (duration: 234.338364ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:26.36717Z","caller":"traceutil/trace.go:171","msg":"trace[1041082072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"144.092413ms","start":"2026-04-20T10:19:26.223023Z","end":"2026-04-20T10:19:26.367116Z","steps":["trace[1041082072] 'agreement among raft nodes before linearized reading' (duration: 143.929418ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:26.367192Z","caller":"traceutil/trace.go:171","msg":"trace[2044550333] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:627; }","duration":"118.040917ms","start":"2026-04-20T10:19:26.24912Z","end":"2026-04-20T10:19:26.367161Z","steps":["trace[2044550333] 'agreement among raft nodes before linearized reading' (duration: 117.870272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.366883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.943119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:26.367343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.623138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kbprk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T10:19:26.367411Z","caller":"traceutil/trace.go:171","msg":"trace[1405233816] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"144.430623ms","start":"2026-04-20T10:19:26.222864Z","end":"2026-04-20T10:19:26.367294Z","steps":["trace[1405233816] 'agreement among raft nodes before linearized reading' (duration: 143.829436ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:26.367477Z","caller":"traceutil/trace.go:171","msg":"trace[2018764829] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kbprk; range_end:; response_count:1; response_revision:627; }","duration":"197.798563ms","start":"2026-04-20T10:19:26.169665Z","end":"2026-04-20T10:19:26.367463Z","steps":["trace[2018764829] 'agreement among raft nodes before linearized reading' (duration: 197.189506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.367065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.605733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.367641Z","caller":"traceutil/trace.go:171","msg":"trace[613922433] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:627; }","duration":"119.19167ms","start":"2026-04-20T10:19:26.248422Z","end":"2026-04-20T10:19:26.367614Z","steps":["trace[613922433] 'agreement among raft nodes before linearized reading' (duration: 118.612044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.366845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.722085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.367809Z","caller":"traceutil/trace.go:171","msg":"trace[141130756] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:627; }","duration":"173.707984ms","start":"2026-04-20T10:19:26.194086Z","end":"2026-04-20T10:19:26.367794Z","steps":["trace[141130756] 'agreement among raft nodes before linearized reading' (duration: 172.717474ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:26.366822Z","caller":"traceutil/trace.go:171","msg":"trace[450380036] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"234.51126ms","start":"2026-04-20T10:19:26.132278Z","end":"2026-04-20T10:19:26.366789Z","steps":["trace[450380036] 'agreement among raft nodes before linearized reading' (duration: 234.18583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.368185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.923453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.368323Z","caller":"traceutil/trace.go:171","msg":"trace[1806046452] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:627; }","duration":"119.696966ms","start":"2026-04-20T10:19:26.248537Z","end":"2026-04-20T10:19:26.368234Z","steps":["trace[1806046452] 'agreement among raft nodes before linearized reading' (duration: 117.933925ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:26.565166Z","caller":"traceutil/trace.go:171","msg":"trace[1366504598] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"126.04382ms","start":"2026-04-20T10:19:26.439093Z","end":"2026-04-20T10:19:26.565137Z","steps":["trace[1366504598] 'read index received' (duration: 90.634882ms)","trace[1366504598] 'applied index is now lower than readState.Index' (duration: 35.407488ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:19:26.565264Z","caller":"traceutil/trace.go:171","msg":"trace[915270044] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"140.409967ms","start":"2026-04-20T10:19:26.424801Z","end":"2026-04-20T10:19:26.565211Z","steps":["trace[915270044] 'process raft request' (duration: 104.847834ms)","trace[915270044] 'compare' (duration: 35.329056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:19:26.565537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.643748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.565599Z","caller":"traceutil/trace.go:171","msg":"trace[1031662042] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:629; }","duration":"125.765952ms","start":"2026-04-20T10:19:26.439818Z","end":"2026-04-20T10:19:26.565584Z","steps":["trace[1031662042] 'agreement among raft nodes before linearized reading' (duration: 125.638328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:26.565955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.77446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:26.565971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.852173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:26.56603Z","caller":"traceutil/trace.go:171","msg":"trace[934513615] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:629; }","duration":"126.953366ms","start":"2026-04-20T10:19:26.439062Z","end":"2026-04-20T10:19:26.566015Z","steps":["trace[934513615] 'agreement among raft nodes before linearized reading' (duration: 126.233135ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:26.566033Z","caller":"traceutil/trace.go:171","msg":"trace[313606156] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:629; }","duration":"126.918615ms","start":"2026-04-20T10:19:26.439082Z","end":"2026-04-20T10:19:26.566Z","steps":["trace[313606156] 'agreement among raft nodes before linearized reading' (duration: 126.258336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:27.05953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.758293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:27.059609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.291629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:27.059647Z","caller":"traceutil/trace.go:171","msg":"trace[561712169] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:629; }","duration":"278.948519ms","start":"2026-04-20T10:19:26.780678Z","end":"2026-04-20T10:19:27.059627Z","steps":["trace[561712169] 'range keys from in-memory index tree' (duration: 278.65219ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:27.059695Z","caller":"traceutil/trace.go:171","msg":"trace[1392724013] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:629; }","duration":"279.437964ms","start":"2026-04-20T10:19:26.780235Z","end":"2026-04-20T10:19:27.059673Z","steps":["trace[1392724013] 'count revisions from in-memory index tree' (duration: 279.191726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:27.059672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.330707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:19:27.05973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.659329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:27.059822Z","caller":"traceutil/trace.go:171","msg":"trace[2076963454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"302.502504ms","start":"2026-04-20T10:19:26.75727Z","end":"2026-04-20T10:19:27.059773Z","steps":["trace[2076963454] 'range keys from in-memory index tree' (duration: 302.026449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:27.059811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.671239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:27.059859Z","caller":"traceutil/trace.go:171","msg":"trace[2030212191] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:629; }","duration":"261.827273ms","start":"2026-04-20T10:19:26.798004Z","end":"2026-04-20T10:19:27.059831Z","steps":["trace[2030212191] 'count revisions from in-memory index tree' (duration: 261.569395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:27.059727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.432574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:27.059899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.616739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:19:27.059874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:19:26.757242Z","time spent":"302.617137ms","remote":"127.0.0.1:60794","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-20T10:19:27.059925Z","caller":"traceutil/trace.go:171","msg":"trace[2084652917] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:629; }","duration":"261.846193ms","start":"2026-04-20T10:19:26.798051Z","end":"2026-04-20T10:19:27.059898Z","steps":["trace[2084652917] 'range keys from in-memory index tree' (duration: 261.561086ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:27.059971Z","caller":"traceutil/trace.go:171","msg":"trace[396655925] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"124.727642ms","start":"2026-04-20T10:19:26.93522Z","end":"2026-04-20T10:19:27.059948Z","steps":["trace[396655925] 'range keys from in-memory index tree' (duration: 124.527937ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:27.059956Z","caller":"traceutil/trace.go:171","msg":"trace[1053291849] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"124.701471ms","start":"2026-04-20T10:19:26.93522Z","end":"2026-04-20T10:19:27.059922Z","steps":["trace[1053291849] 'count revisions from in-memory index tree' (duration: 124.33788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:27.059976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.069701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:27.060293Z","caller":"traceutil/trace.go:171","msg":"trace[1884465704] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:629; }","duration":"261.447412ms","start":"2026-04-20T10:19:26.798815Z","end":"2026-04-20T10:19:27.060263Z","steps":["trace[1884465704] 'range keys from in-memory index tree' (duration: 260.968288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:19:27.060272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.878548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:19:27.060663Z","caller":"traceutil/trace.go:171","msg":"trace[189936411] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:629; }","duration":"279.157356ms","start":"2026-04-20T10:19:26.78122Z","end":"2026-04-20T10:19:27.060377Z","steps":["trace[189936411] 'range keys from in-memory index tree' (duration: 278.749325ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:19:41.651175Z","caller":"traceutil/trace.go:171","msg":"trace[1482887407] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"130.439857ms","start":"2026-04-20T10:19:41.520698Z","end":"2026-04-20T10:19:41.651138Z","steps":["trace[1482887407] 'process raft request' (duration: 130.275203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:10.090122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.127766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:20:10.090253Z","caller":"traceutil/trace.go:171","msg":"trace[651867258] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1189; }","duration":"163.316132ms","start":"2026-04-20T10:20:09.926912Z","end":"2026-04-20T10:20:10.090228Z","steps":["trace[651867258] 'count revisions from in-memory index tree' (duration: 163.050114ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:56.924766Z","caller":"traceutil/trace.go:171","msg":"trace[1066607974] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"215.170668ms","start":"2026-04-20T10:20:56.709568Z","end":"2026-04-20T10:20:56.924739Z","steps":["trace[1066607974] 'process raft request' (duration: 214.988133ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:56.945611Z","caller":"traceutil/trace.go:171","msg":"trace[1668512325] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1925; }","duration":"165.513827ms","start":"2026-04-20T10:20:56.780071Z","end":"2026-04-20T10:20:56.945585Z","steps":["trace[1668512325] 'read index received' (duration: 144.567147ms)","trace[1668512325] 'applied index is now lower than readState.Index' (duration: 20.94558ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:56.945737Z","caller":"traceutil/trace.go:171","msg":"trace[512926119] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"183.928015ms","start":"2026-04-20T10:20:56.761763Z","end":"2026-04-20T10:20:56.945691Z","steps":["trace[512926119] 'process raft request' (duration: 183.690098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:56.945971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.871677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T10:20:56.946049Z","caller":"traceutil/trace.go:171","msg":"trace[1294382973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1852; }","duration":"166.010741ms","start":"2026-04-20T10:20:56.780018Z","end":"2026-04-20T10:20:56.946029Z","steps":["trace[1294382973] 'agreement among raft nodes before linearized reading' (duration: 165.679162ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:57.16972Z","caller":"traceutil/trace.go:171","msg":"trace[1718519237] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"215.602541ms","start":"2026-04-20T10:20:56.954017Z","end":"2026-04-20T10:20:57.16962Z","steps":["trace[1718519237] 'read index received' (duration: 207.723545ms)","trace[1718519237] 'applied index is now lower than readState.Index' (duration: 7.877466ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:20:57.169839Z","caller":"traceutil/trace.go:171","msg":"trace[1082240100] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"218.329649ms","start":"2026-04-20T10:20:56.95147Z","end":"2026-04-20T10:20:57.1698Z","steps":["trace[1082240100] 'process raft request' (duration: 210.202846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:57.169921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.733189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:20:57.169958Z","caller":"traceutil/trace.go:171","msg":"trace[1997664876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"103.824551ms","start":"2026-04-20T10:20:57.066123Z","end":"2026-04-20T10:20:57.169947Z","steps":["trace[1997664876] 'agreement among raft nodes before linearized reading' (duration: 103.744979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:20:57.170034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.987652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-51c6060d-c660-4d92-ba84-2e63f3dfe0a4\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-20T10:20:57.170133Z","caller":"traceutil/trace.go:171","msg":"trace[813581372] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-51c6060d-c660-4d92-ba84-2e63f3dfe0a4; range_end:; response_count:1; response_revision:1853; }","duration":"216.132826ms","start":"2026-04-20T10:20:56.953972Z","end":"2026-04-20T10:20:57.170105Z","steps":["trace[813581372] 'agreement among raft nodes before linearized reading' (duration: 215.863808ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:11.117956Z","caller":"traceutil/trace.go:171","msg":"trace[2047889880] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"117.705609ms","start":"2026-04-20T10:21:11.000224Z","end":"2026-04-20T10:21:11.11793Z","steps":["trace[2047889880] 'process raft request' (duration: 117.449961ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:41.569136Z","caller":"traceutil/trace.go:171","msg":"trace[1529557113] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"118.460712ms","start":"2026-04-20T10:21:41.45064Z","end":"2026-04-20T10:21:41.569101Z","steps":["trace[1529557113] 'process raft request' (duration: 118.265287ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:41.981937Z","caller":"traceutil/trace.go:171","msg":"trace[1906263383] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"106.706226ms","start":"2026-04-20T10:21:41.875189Z","end":"2026-04-20T10:21:41.981895Z","steps":["trace[1906263383] 'process raft request' (duration: 97.444392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:47.845281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.792409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T10:21:47.845411Z","caller":"traceutil/trace.go:171","msg":"trace[527895107] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2181; }","duration":"140.970574ms","start":"2026-04-20T10:21:47.704416Z","end":"2026-04-20T10:21:47.845386Z","steps":["trace[527895107] 'range keys from in-memory index tree' (duration: 140.596703ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:11.098371Z","caller":"traceutil/trace.go:171","msg":"trace[1357107720] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"202.328777ms","start":"2026-04-20T10:23:10.896003Z","end":"2026-04-20T10:23:11.098332Z","steps":["trace[1357107720] 'process raft request' (duration: 141.739597ms)","trace[1357107720] 'compare' (duration: 60.296432ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:23:11.122173Z","caller":"traceutil/trace.go:171","msg":"trace[485107411] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"212.740274ms","start":"2026-04-20T10:23:10.909398Z","end":"2026-04-20T10:23:11.122138Z","steps":["trace[485107411] 'process raft request' (duration: 211.697415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:23:11.45186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.069945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:23:11.451977Z","caller":"traceutil/trace.go:171","msg":"trace[1792943373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2827; }","duration":"163.252921ms","start":"2026-04-20T10:23:11.288695Z","end":"2026-04-20T10:23:11.451948Z","steps":["trace[1792943373] 'range keys from in-memory index tree' (duration: 162.802448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:23:11.452067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.870281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T10:23:11.452233Z","caller":"traceutil/trace.go:171","msg":"trace[1855058191] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2827; }","duration":"107.108438ms","start":"2026-04-20T10:23:11.345091Z","end":"2026-04-20T10:23:11.4522Z","steps":["trace[1855058191] 'range keys from in-memory index tree' (duration: 106.600893ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:11.589078Z","caller":"traceutil/trace.go:171","msg":"trace[170853264] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"131.391ms","start":"2026-04-20T10:23:11.457647Z","end":"2026-04-20T10:23:11.589038Z","steps":["trace[170853264] 'process raft request' (duration: 131.090742ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:11.619079Z","caller":"traceutil/trace.go:171","msg":"trace[939754616] transaction","detail":"{read_only:false; response_revision:2829; number_of_response:1; }","duration":"160.877273ms","start":"2026-04-20T10:23:11.458167Z","end":"2026-04-20T10:23:11.619044Z","steps":["trace[939754616] 'process raft request' (duration: 160.690507ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:58.958779Z","caller":"traceutil/trace.go:171","msg":"trace[931064700] transaction","detail":"{read_only:false; response_revision:3909; number_of_response:1; }","duration":"243.017811ms","start":"2026-04-20T10:25:58.715729Z","end":"2026-04-20T10:25:58.958746Z","steps":["trace[931064700] 'process raft request' (duration: 242.825746ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:32.403195Z","caller":"traceutil/trace.go:171","msg":"trace[2055101816] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"118.401541ms","start":"2026-04-20T10:27:32.28475Z","end":"2026-04-20T10:27:32.403151Z","steps":["trace[2055101816] 'process raft request' (duration: 96.145977ms)","trace[2055101816] 'compare' (duration: 21.927975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:27:34.20019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.132451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:27:34.200468Z","caller":"traceutil/trace.go:171","msg":"trace[1883407348] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"252.663186ms","start":"2026-04-20T10:27:33.947746Z","end":"2026-04-20T10:27:34.200409Z","steps":["trace[1883407348] 'process raft request' (duration: 75.666511ms)","trace[1883407348] 'compare' (duration: 175.833822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:27:34.200704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.190619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tkw8s\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:27:34.200748Z","caller":"traceutil/trace.go:171","msg":"trace[1994001415] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tkw8s; range_end:; response_count:1; response_revision:5168; }","duration":"199.301042ms","start":"2026-04-20T10:27:34.001437Z","end":"2026-04-20T10:27:34.200738Z","steps":["trace[1994001415] 'agreement among raft nodes before linearized reading' (duration: 199.106616ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:27:34.200512Z","caller":"traceutil/trace.go:171","msg":"trace[1076884012] linearizableReadLoop","detail":"{readStateIndex:5369; appliedIndex:5367; }","duration":"198.987913ms","start":"2026-04-20T10:27:34.001504Z","end":"2026-04-20T10:27:34.200491Z","steps":["trace[1076884012] 'read index received' (duration: 22.001238ms)","trace[1076884012] 'applied index is now lower than readState.Index' (duration: 176.985105ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:34.201078Z","caller":"traceutil/trace.go:171","msg":"trace[102135790] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"203.676106ms","start":"2026-04-20T10:27:33.997343Z","end":"2026-04-20T10:27:34.201019Z","steps":["trace[102135790] 'process raft request' (duration: 203.003017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:34.201151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.742066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:27:34.201242Z","caller":"traceutil/trace.go:171","msg":"trace[1765536725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5168; }","duration":"135.911061ms","start":"2026-04-20T10:27:34.065309Z","end":"2026-04-20T10:27:34.20122Z","steps":["trace[1765536725] 'agreement among raft nodes before linearized reading' (duration: 135.727815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:34.201127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.139627ms","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-20T10:27:34.20137Z","caller":"traceutil/trace.go:171","msg":"trace[1925512050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5168; }","duration":"199.436215ms","start":"2026-04-20T10:27:34.001915Z","end":"2026-04-20T10:27:34.201351Z","steps":["trace[1925512050] 'agreement among raft nodes before linearized reading' (duration: 198.969602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:27:44.504597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.69818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:27:44.504896Z","caller":"traceutil/trace.go:171","msg":"trace[848034472] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"133.607415ms","start":"2026-04-20T10:27:44.37122Z","end":"2026-04-20T10:27:44.504827Z","steps":["trace[848034472] 'process raft request' (duration: 14.575066ms)","trace[848034472] 'compare' (duration: 118.442033ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:27:44.579547Z","caller":"traceutil/trace.go:171","msg":"trace[837241121] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"134.244874ms","start":"2026-04-20T10:27:44.445249Z","end":"2026-04-20T10:27:44.579494Z","steps":["trace[837241121] 'process raft request' (duration: 134.026498ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:28:05.00421Z","caller":"traceutil/trace.go:171","msg":"trace[255238696] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"125.20561ms","start":"2026-04-20T10:28:04.878968Z","end":"2026-04-20T10:28:05.004173Z","steps":["trace[255238696] 'process raft request' (duration: 124.941063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:28:45.970936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3018} {"level":"info","ts":"2026-04-20T10:28:46.059155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3018,"took":"86.266896ms","hash":1115580996,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":31010816,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-20T10:28:46.059256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1115580996,"revision":3018,"compact-revision":-1} {"level":"info","ts":"2026-04-20T10:33:45.978196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6054} {"level":"info","ts":"2026-04-20T10:33:46.082019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6054,"took":"101.565412ms","hash":3306683098,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":21716992,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-20T10:33:46.082085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3306683098,"revision":6054,"compact-revision":3018} {"level":"warn","ts":"2026-04-20T10:34:19.236878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.041687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:34:19.236997Z","caller":"traceutil/trace.go:171","msg":"trace[272165228] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8486; }","duration":"304.210312ms","start":"2026-04-20T10:34:18.932759Z","end":"2026-04-20T10:34:19.236969Z","steps":["trace[272165228] 'count revisions from in-memory index tree' (duration: 303.865501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:19.237045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:18.93274Z","time spent":"304.292973ms","remote":"127.0.0.1:50928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:34:19.74917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306958866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:34:20.250084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306958866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:34:20.681554Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.433469879s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T10:34:20.707966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.444281Z","time spent":"1.263677128s","remote":"127.0.0.1:60494","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T10:34:20.708574Z","caller":"traceutil/trace.go:171","msg":"trace[1529113438] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"1.460575883s","start":"2026-04-20T10:34:19.24798Z","end":"2026-04-20T10:34:20.708556Z","steps":["trace[1529113438] 'process raft request' (duration: 1.433862789s)","trace[1529113438] 'compare' (duration: 26.574689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:34:20.708845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.247962Z","time spent":"1.460652966s","remote":"127.0.0.1:60534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:34:20.721609Z","caller":"traceutil/trace.go:171","msg":"trace[2101002469] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"1.358118126s","start":"2026-04-20T10:34:19.363455Z","end":"2026-04-20T10:34:20.721573Z","steps":["trace[2101002469] 'process raft request' (duration: 1.357192179s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:20.721645Z","caller":"traceutil/trace.go:171","msg":"trace[1461039937] linearizableReadLoop","detail":"{readStateIndex:8795; appliedIndex:8791; }","duration":"1.473382299s","start":"2026-04-20T10:34:19.248232Z","end":"2026-04-20T10:34:20.721615Z","steps":["trace[1461039937] 'read index received' (duration: 1.433569871s)","trace[1461039937] 'applied index is now lower than readState.Index' (duration: 39.811148ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:20.721703Z","caller":"traceutil/trace.go:171","msg":"trace[1181089671] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"582.637998ms","start":"2026-04-20T10:34:20.139036Z","end":"2026-04-20T10:34:20.721674Z","steps":["trace[1181089671] 'process raft request' (duration: 582.379721ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:20.721719Z","caller":"traceutil/trace.go:171","msg":"trace[475300382] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"199.911221ms","start":"2026-04-20T10:34:20.521763Z","end":"2026-04-20T10:34:20.721674Z","steps":["trace[475300382] 'process raft request' (duration: 199.799118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.721804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.139009Z","time spent":"582.7347ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:34:20.721808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.363431Z","time spent":"1.35827127s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:34:20.721609Z","caller":"traceutil/trace.go:171","msg":"trace[600508284] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"596.084793ms","start":"2026-04-20T10:34:20.125497Z","end":"2026-04-20T10:34:20.721582Z","steps":["trace[600508284] 'process raft request' (duration: 595.848666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.721982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.323815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:34:20.721969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473726698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19443"} {"level":"info","ts":"2026-04-20T10:34:20.722008Z","caller":"traceutil/trace.go:171","msg":"trace[1927735665] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8491; }","duration":"283.396128ms","start":"2026-04-20T10:34:20.438604Z","end":"2026-04-20T10:34:20.722Z","steps":["trace[1927735665] 'agreement among raft nodes before linearized reading' (duration: 283.350916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.721983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100799004s","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:7"} {"level":"info","ts":"2026-04-20T10:34:20.722021Z","caller":"traceutil/trace.go:171","msg":"trace[44393458] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8491; }","duration":"1.473803411s","start":"2026-04-20T10:34:19.248204Z","end":"2026-04-20T10:34:20.722008Z","steps":["trace[44393458] 'agreement among raft nodes before linearized reading' (duration: 1.473544773s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.721984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.125485Z","time spent":"596.374491ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:34:20.722029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.276588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:34:20.722028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.167242ms","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:7"} {"level":"warn","ts":"2026-04-20T10:34:20.722057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.248191Z","time spent":"1.473858192s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19466,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T10:34:20.722121Z","caller":"traceutil/trace.go:171","msg":"trace[312561083] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8491; }","duration":"462.396041ms","start":"2026-04-20T10:34:20.259709Z","end":"2026-04-20T10:34:20.722105Z","steps":["trace[312561083] 'agreement among raft nodes before linearized reading' (duration: 462.225927ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:20.722149Z","caller":"traceutil/trace.go:171","msg":"trace[1268552698] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8491; }","duration":"948.338586ms","start":"2026-04-20T10:34:19.773787Z","end":"2026-04-20T10:34:20.722125Z","steps":["trace[1268552698] 'agreement among raft nodes before linearized reading' (duration: 948.088829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.722157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.259601Z","time spent":"462.548537ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:34:20.722056Z","caller":"traceutil/trace.go:171","msg":"trace[160162354] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8491; }","duration":"1.100956308s","start":"2026-04-20T10:34:19.621079Z","end":"2026-04-20T10:34:20.722036Z","steps":["trace[160162354] 'agreement among raft nodes before linearized reading' (duration: 1.100821534s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.722199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.77378Z","time spent":"948.408119ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:34:20.722212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.52956ms","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:5"} {"level":"warn","ts":"2026-04-20T10:34:20.722226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.621036Z","time spent":"1.101167833s","remote":"127.0.0.1:53216","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-20T10:34:20.722259Z","caller":"traceutil/trace.go:171","msg":"trace[1273420370] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8491; }","duration":"648.565621ms","start":"2026-04-20T10:34:20.073671Z","end":"2026-04-20T10:34:20.722236Z","steps":["trace[1273420370] 'agreement among raft nodes before linearized reading' (duration: 648.51762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.722259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.63763ms","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:7"} {"level":"warn","ts":"2026-04-20T10:34:20.722042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.609502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:34:20.722284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.073608Z","time spent":"648.669604ms","remote":"127.0.0.1:42884","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":"info","ts":"2026-04-20T10:34:20.722307Z","caller":"traceutil/trace.go:171","msg":"trace[1111512844] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8491; }","duration":"566.749374ms","start":"2026-04-20T10:34:20.155545Z","end":"2026-04-20T10:34:20.722294Z","steps":["trace[1111512844] 'agreement among raft nodes before linearized reading' (duration: 566.667711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:20.722317Z","caller":"traceutil/trace.go:171","msg":"trace[995778943] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8491; }","duration":"606.89636ms","start":"2026-04-20T10:34:20.115409Z","end":"2026-04-20T10:34:20.722305Z","steps":["trace[995778943] 'agreement among raft nodes before linearized reading' (duration: 606.479808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.72234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.155522Z","time spent":"566.808396ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:34:20.722356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.115282Z","time spent":"607.062595ms","remote":"127.0.0.1:60872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":57,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:34:20.722775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036516416s","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-20T10:34:20.722805Z","caller":"traceutil/trace.go:171","msg":"trace[1546814705] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8491; }","duration":"1.036587739s","start":"2026-04-20T10:34:19.686206Z","end":"2026-04-20T10:34:20.722794Z","steps":["trace[1546814705] 'agreement among raft nodes before linearized reading' (duration: 1.035672222s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.722843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.686186Z","time spent":"1.0366523s","remote":"127.0.0.1:60794","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-20T10:34:20.722936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459998058s","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-20T10:34:20.722959Z","caller":"traceutil/trace.go:171","msg":"trace[2111515366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8491; }","duration":"1.460061979s","start":"2026-04-20T10:34:19.262893Z","end":"2026-04-20T10:34:20.722955Z","steps":["trace[2111515366] 'agreement among raft nodes before linearized reading' (duration: 1.459212774s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.722973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.26287Z","time spent":"1.460099551s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:34:20.723378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463621141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:34:20.723428Z","caller":"traceutil/trace.go:171","msg":"trace[2077072378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8491; }","duration":"1.463694982s","start":"2026-04-20T10:34:19.259719Z","end":"2026-04-20T10:34:20.723414Z","steps":["trace[2077072378] 'agreement among raft nodes before linearized reading' (duration: 1.462113587s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.723474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:19.259705Z","time spent":"1.463762725s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:34:20.723584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.010684ms","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":"warn","ts":"2026-04-20T10:34:20.723914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.383182ms","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-20T10:34:20.72392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.543198ms","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-20T10:34:20.723975Z","caller":"traceutil/trace.go:171","msg":"trace[1414746710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8491; }","duration":"208.661572ms","start":"2026-04-20T10:34:20.515298Z","end":"2026-04-20T10:34:20.72396Z","steps":["trace[1414746710] 'agreement among raft nodes before linearized reading' (duration: 206.617823ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:20.723938Z","caller":"traceutil/trace.go:171","msg":"trace[2092242478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8491; }","duration":"384.447915ms","start":"2026-04-20T10:34:20.339483Z","end":"2026-04-20T10:34:20.723931Z","steps":["trace[2092242478] 'agreement among raft nodes before linearized reading' (duration: 382.439607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.724102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.33946Z","time spent":"384.598078ms","remote":"127.0.0.1:60794","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-20T10:34:20.723601Z","caller":"traceutil/trace.go:171","msg":"trace[312391970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8491; }","duration":"600.101866ms","start":"2026-04-20T10:34:20.123493Z","end":"2026-04-20T10:34:20.723595Z","steps":["trace[312391970] 'agreement among raft nodes before linearized reading' (duration: 598.369717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:20.724372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.123464Z","time spent":"600.863308ms","remote":"127.0.0.1:60794","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-20T10:34:21.75234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.74349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:34:21.752503Z","caller":"traceutil/trace.go:171","msg":"trace[1843788842] linearizableReadLoop","detail":"{readStateIndex:8803; appliedIndex:8802; }","duration":"907.546562ms","start":"2026-04-20T10:34:20.84492Z","end":"2026-04-20T10:34:21.752466Z","steps":["trace[1843788842] 'read index received' (duration: 246.452363ms)","trace[1843788842] 'applied index is now lower than readState.Index' (duration: 661.092249ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:34:21.752845Z","caller":"traceutil/trace.go:171","msg":"trace[887163087] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"940.490492ms","start":"2026-04-20T10:34:20.812335Z","end":"2026-04-20T10:34:21.752825Z","steps":["trace[887163087] 'process raft request' (duration: 279.107825ms)","trace[887163087] 'compare' (duration: 659.819553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:34:21.752944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.812301Z","time spent":"940.596895ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:34:21.753229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.302433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:34:21.753302Z","caller":"traceutil/trace.go:171","msg":"trace[1500772478] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8498; }","duration":"908.420456ms","start":"2026-04-20T10:34:20.844864Z","end":"2026-04-20T10:34:21.753284Z","steps":["trace[1500772478] 'agreement among raft nodes before linearized reading' (duration: 908.305793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:21.753357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:20.844838Z","time spent":"908.503999ms","remote":"127.0.0.1:60984","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-20T10:34:21.753584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.826164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:34:21.75375Z","caller":"traceutil/trace.go:171","msg":"trace[430482850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8498; }","duration":"688.06157ms","start":"2026-04-20T10:34:21.065657Z","end":"2026-04-20T10:34:21.753718Z","steps":["trace[430482850] 'agreement among raft nodes before linearized reading' (duration: 687.733081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:21.753825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:21.065627Z","time spent":"688.182424ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:34:21.754394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.800516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tkw8s\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:34:21.754476Z","caller":"traceutil/trace.go:171","msg":"trace[1639738101] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tkw8s; range_end:; response_count:1; response_revision:8498; }","duration":"384.935439ms","start":"2026-04-20T10:34:21.369527Z","end":"2026-04-20T10:34:21.754462Z","steps":["trace[1639738101] 'agreement among raft nodes before linearized reading' (duration: 384.800745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:21.754469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.804064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T10:34:21.754525Z","caller":"traceutil/trace.go:171","msg":"trace[1466158590] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8498; }","duration":"356.975361ms","start":"2026-04-20T10:34:21.397533Z","end":"2026-04-20T10:34:21.754508Z","steps":["trace[1466158590] 'agreement among raft nodes before linearized reading' (duration: 356.742994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:21.754558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:21.397498Z","time spent":"357.053423ms","remote":"127.0.0.1:60682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-20T10:34:21.755003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:21.36949Z","time spent":"385.016411ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-tkw8s\" "} {"level":"warn","ts":"2026-04-20T10:34:24.056165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306958895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:34:24.556818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306958895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:34:24.649494Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.920575387s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:34:24.650019Z","caller":"traceutil/trace.go:171","msg":"trace[1955704194] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"1.921239935s","start":"2026-04-20T10:34:22.728753Z","end":"2026-04-20T10:34:24.649993Z","steps":["trace[1955704194] 'process raft request' (duration: 1.92105254s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.650199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.728724Z","time spent":"1.92138638s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:34:24.686583Z","caller":"traceutil/trace.go:171","msg":"trace[683615023] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"1.956784311s","start":"2026-04-20T10:34:22.729767Z","end":"2026-04-20T10:34:24.686552Z","steps":["trace[683615023] 'process raft request' (duration: 1.952765506s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.686842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.729732Z","time spent":"1.957004118s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:34:24.686955Z","caller":"traceutil/trace.go:171","msg":"trace[1228996296] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"1.956620057s","start":"2026-04-20T10:34:22.730285Z","end":"2026-04-20T10:34:24.686905Z","steps":["trace[1228996296] 'process raft request' (duration: 1.956194535s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.687191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.730247Z","time spent":"1.956811303s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:34:24.699756Z","caller":"traceutil/trace.go:171","msg":"trace[1467248974] linearizableReadLoop","detail":"{readStateIndex:8807; appliedIndex:8803; }","duration":"1.952505569s","start":"2026-04-20T10:34:22.747207Z","end":"2026-04-20T10:34:24.699713Z","steps":["trace[1467248974] 'read index received' (duration: 1.902600923s)","trace[1467248974] 'applied index is now lower than readState.Index' (duration: 49.903006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:34:24.700056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634131261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:34:24.700167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.952917061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:34:24.700286Z","caller":"traceutil/trace.go:171","msg":"trace[552494978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8501; }","duration":"1.953103676s","start":"2026-04-20T10:34:22.747158Z","end":"2026-04-20T10:34:24.700262Z","steps":["trace[552494978] 'agreement among raft nodes before linearized reading' (duration: 1.952683574s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.700305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.924093927s","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-20T10:34:24.700308Z","caller":"traceutil/trace.go:171","msg":"trace[1538261384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8501; }","duration":"1.634329638s","start":"2026-04-20T10:34:23.065836Z","end":"2026-04-20T10:34:24.700165Z","steps":["trace[1538261384] 'agreement among raft nodes before linearized reading' (duration: 1.634120012s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.700345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.747136Z","time spent":"1.953194198s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T10:34:24.70036Z","caller":"traceutil/trace.go:171","msg":"trace[397428457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8501; }","duration":"1.924285383s","start":"2026-04-20T10:34:22.776058Z","end":"2026-04-20T10:34:24.700344Z","steps":["trace[397428457] 'agreement among raft nodes before linearized reading' (duration: 1.923870661s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.700361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.448544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tkw8s\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-20T10:34:24.700341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919425914s","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-20T10:34:24.700373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:23.065811Z","time spent":"1.634550873s","remote":"127.0.0.1:60512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:34:24.700393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.921021909s","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-20T10:34:24.700407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.776031Z","time spent":"1.924362754s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:34:24.700395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.921785761s","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-20T10:34:24.700407Z","caller":"traceutil/trace.go:171","msg":"trace[1500377599] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tkw8s; range_end:; response_count:1; response_revision:8501; }","duration":"931.562517ms","start":"2026-04-20T10:34:23.768833Z","end":"2026-04-20T10:34:24.700396Z","steps":["trace[1500377599] 'agreement among raft nodes before linearized reading' (duration: 931.447574ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:24.700503Z","caller":"traceutil/trace.go:171","msg":"trace[499578151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8501; }","duration":"1.921960167s","start":"2026-04-20T10:34:22.778515Z","end":"2026-04-20T10:34:24.700476Z","steps":["trace[499578151] 'agreement among raft nodes before linearized reading' (duration: 1.921627167s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:34:24.700574Z","caller":"traceutil/trace.go:171","msg":"trace[1094907223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8501; }","duration":"1.921100681s","start":"2026-04-20T10:34:22.779333Z","end":"2026-04-20T10:34:24.700434Z","steps":["trace[1094907223] 'agreement among raft nodes before linearized reading' (duration: 1.920921586s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.700612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.77849Z","time spent":"1.922108102s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T10:34:24.700633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.704062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:34:24.700665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.779312Z","time spent":"1.921341799s","remote":"127.0.0.1:60794","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-20T10:34:24.700694Z","caller":"traceutil/trace.go:171","msg":"trace[1240296323] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8501; }","duration":"167.931688ms","start":"2026-04-20T10:34:24.532752Z","end":"2026-04-20T10:34:24.700684Z","steps":["trace[1240296323] 'agreement among raft nodes before linearized reading' (duration: 167.300499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.700189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92104835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:34:24.700787Z","caller":"traceutil/trace.go:171","msg":"trace[1452582207] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8501; }","duration":"1.921704229s","start":"2026-04-20T10:34:22.77907Z","end":"2026-04-20T10:34:24.700774Z","steps":["trace[1452582207] 'agreement among raft nodes before linearized reading' (duration: 1.920969657s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.700831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.779056Z","time spent":"1.921757751s","remote":"127.0.0.1:60662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:34:24.700592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:23.76881Z","time spent":"931.772534ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-tkw8s\" "} {"level":"info","ts":"2026-04-20T10:34:24.70043Z","caller":"traceutil/trace.go:171","msg":"trace[324743718] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8501; }","duration":"1.919543857s","start":"2026-04-20T10:34:22.780865Z","end":"2026-04-20T10:34:24.700409Z","steps":["trace[324743718] 'agreement among raft nodes before linearized reading' (duration: 1.919302871s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.701089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.780854Z","time spent":"1.920221147s","remote":"127.0.0.1:60794","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-20T10:34:24.700554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.298437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-556b8d6d7d-mcb25.18a80a2dafe3d73c\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T10:34:24.701201Z","caller":"traceutil/trace.go:171","msg":"trace[1506806154] range","detail":"{range_begin:/registry/events/openstack/barbican-api-556b8d6d7d-mcb25.18a80a2dafe3d73c; range_end:; response_count:1; response_revision:8501; }","duration":"360.060309ms","start":"2026-04-20T10:34:24.341129Z","end":"2026-04-20T10:34:24.70119Z","steps":["trace[1506806154] 'agreement among raft nodes before linearized reading' (duration: 359.10078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.701237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:24.341105Z","time spent":"360.121841ms","remote":"127.0.0.1:60594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1158,"request content":"key:\"/registry/events/openstack/barbican-api-556b8d6d7d-mcb25.18a80a2dafe3d73c\" "} {"level":"warn","ts":"2026-04-20T10:34:24.700583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923384147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T10:34:24.701301Z","caller":"traceutil/trace.go:171","msg":"trace[143222761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8501; }","duration":"1.924245352s","start":"2026-04-20T10:34:22.77704Z","end":"2026-04-20T10:34:24.701285Z","steps":["trace[143222761] 'agreement among raft nodes before linearized reading' (duration: 1.923199582s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:34:24.701353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:34:22.77701Z","time spent":"1.924326535s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:34:25.747575Z","caller":"traceutil/trace.go:171","msg":"trace[458791041] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"105.505464ms","start":"2026-04-20T10:34:25.64204Z","end":"2026-04-20T10:34:25.747545Z","steps":["trace[458791041] 'process raft request' (duration: 105.309989ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:01.71501Z","caller":"traceutil/trace.go:171","msg":"trace[656557926] transaction","detail":"{read_only:false; response_revision:8758; number_of_response:1; }","duration":"162.675848ms","start":"2026-04-20T10:35:01.552296Z","end":"2026-04-20T10:35:01.714971Z","steps":["trace[656557926] 'process raft request' (duration: 162.425861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:13.579054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306960003,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:35:14.079618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306960003,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:35:14.512696Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.437135133s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:35:14.51464Z","caller":"traceutil/trace.go:171","msg":"trace[725320328] transaction","detail":"{read_only:false; response_revision:8848; number_of_response:1; }","duration":"1.439050758s","start":"2026-04-20T10:35:13.075506Z","end":"2026-04-20T10:35:14.514556Z","steps":["trace[725320328] 'process raft request' (duration: 1.437501874s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:14.51474Z","caller":"traceutil/trace.go:171","msg":"trace[859808529] transaction","detail":"{read_only:false; response_revision:8849; number_of_response:1; }","duration":"1.438668216s","start":"2026-04-20T10:35:13.076043Z","end":"2026-04-20T10:35:14.514711Z","steps":["trace[859808529] 'process raft request' (duration: 1.438469901s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:14.514882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:13.075493Z","time spent":"1.439252423s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:35:14.514954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:13.076033Z","time spent":"1.438812312s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:35:14.515648Z","caller":"traceutil/trace.go:171","msg":"trace[419015560] transaction","detail":"{read_only:false; response_revision:8850; number_of_response:1; }","duration":"205.992186ms","start":"2026-04-20T10:35:14.309634Z","end":"2026-04-20T10:35:14.515626Z","steps":["trace[419015560] 'process raft request' (duration: 205.022138ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:14.525338Z","caller":"traceutil/trace.go:171","msg":"trace[1058320821] linearizableReadLoop","detail":"{readStateIndex:9170; appliedIndex:9168; }","duration":"1.447131019s","start":"2026-04-20T10:35:13.078164Z","end":"2026-04-20T10:35:14.525295Z","steps":["trace[1058320821] 'read index received' (duration: 1.434836018s)","trace[1058320821] 'applied index is now lower than readState.Index' (duration: 12.292621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:35:14.525919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411719607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tkw8s\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:35:14.525971Z","caller":"traceutil/trace.go:171","msg":"trace[1267832464] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tkw8s; range_end:; response_count:1; response_revision:8850; }","duration":"1.411847391s","start":"2026-04-20T10:35:13.114111Z","end":"2026-04-20T10:35:14.525958Z","steps":["trace[1267832464] 'agreement among raft nodes before linearized reading' (duration: 1.411707536s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:14.525993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:13.114086Z","time spent":"1.411902382s","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-tkw8s\" "} {"level":"warn","ts":"2026-04-20T10:35:14.525973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.575194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:35:14.525997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.72279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-556b8d6d7d-mcb25.18a80a2dafe3d73c\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T10:35:14.526053Z","caller":"traceutil/trace.go:171","msg":"trace[243457755] range","detail":"{range_begin:/registry/events/openstack/barbican-api-556b8d6d7d-mcb25.18a80a2dafe3d73c; range_end:; response_count:1; response_revision:8850; }","duration":"183.881335ms","start":"2026-04-20T10:35:14.342155Z","end":"2026-04-20T10:35:14.526037Z","steps":["trace[243457755] 'agreement among raft nodes before linearized reading' (duration: 183.676068ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:14.52605Z","caller":"traceutil/trace.go:171","msg":"trace[1802899952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8850; }","duration":"387.707629ms","start":"2026-04-20T10:35:14.138319Z","end":"2026-04-20T10:35:14.526026Z","steps":["trace[1802899952] 'agreement among raft nodes before linearized reading' (duration: 387.571174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:14.526091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:14.138295Z","time spent":"387.788821ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:35:14.526169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447965103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T10:35:14.52624Z","caller":"traceutil/trace.go:171","msg":"trace[243552753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8850; }","duration":"1.448077466s","start":"2026-04-20T10:35:13.078146Z","end":"2026-04-20T10:35:14.526223Z","steps":["trace[243552753] 'agreement among raft nodes before linearized reading' (duration: 1.447709125s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:14.526291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:13.078139Z","time spent":"1.448137378s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:35:15.337459Z","caller":"traceutil/trace.go:171","msg":"trace[1469074147] transaction","detail":"{read_only:false; response_revision:8853; number_of_response:1; }","duration":"452.073656ms","start":"2026-04-20T10:35:14.885351Z","end":"2026-04-20T10:35:15.337425Z","steps":["trace[1469074147] 'process raft request' (duration: 449.379239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:15.337648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:14.885313Z","time spent":"452.231332ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:35:15.338087Z","caller":"traceutil/trace.go:171","msg":"trace[908497795] linearizableReadLoop","detail":"{readStateIndex:9177; appliedIndex:9174; }","duration":"265.072844ms","start":"2026-04-20T10:35:15.073003Z","end":"2026-04-20T10:35:15.338076Z","steps":["trace[908497795] 'read index received' (duration: 261.743199ms)","trace[908497795] 'applied index is now lower than readState.Index' (duration: 3.328225ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:35:15.33817Z","caller":"traceutil/trace.go:171","msg":"trace[1210400529] transaction","detail":"{read_only:false; response_revision:8855; number_of_response:1; }","duration":"429.875162ms","start":"2026-04-20T10:35:14.908286Z","end":"2026-04-20T10:35:15.338162Z","steps":["trace[1210400529] 'process raft request' (duration: 429.756919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:15.338224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:14.908251Z","time spent":"429.942154ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:35:15.338436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.439735ms","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-20T10:35:15.338462Z","caller":"traceutil/trace.go:171","msg":"trace[1127785866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8855; }","duration":"265.503346ms","start":"2026-04-20T10:35:15.072952Z","end":"2026-04-20T10:35:15.338456Z","steps":["trace[1127785866] 'agreement among raft nodes before linearized reading' (duration: 265.394032ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:15.338187Z","caller":"traceutil/trace.go:171","msg":"trace[969190502] transaction","detail":"{read_only:false; response_revision:8854; number_of_response:1; }","duration":"437.902192ms","start":"2026-04-20T10:35:14.900244Z","end":"2026-04-20T10:35:15.338146Z","steps":["trace[969190502] 'process raft request' (duration: 437.730057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:15.338628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:14.900216Z","time spent":"438.316304ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:35:15.338676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.529253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T10:35:15.338722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.927393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:35:15.338751Z","caller":"traceutil/trace.go:171","msg":"trace[277644970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8855; }","duration":"261.654026ms","start":"2026-04-20T10:35:15.07708Z","end":"2026-04-20T10:35:15.338734Z","steps":["trace[277644970] 'agreement among raft nodes before linearized reading' (duration: 261.42192ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:15.338764Z","caller":"traceutil/trace.go:171","msg":"trace[2123915284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8855; }","duration":"263.024526ms","start":"2026-04-20T10:35:15.075731Z","end":"2026-04-20T10:35:15.338756Z","steps":["trace[2123915284] 'agreement among raft nodes before linearized reading' (duration: 262.761108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.061614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.166274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T10:35:17.061698Z","caller":"traceutil/trace.go:171","msg":"trace[431950750] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8859; }","duration":"541.310338ms","start":"2026-04-20T10:35:16.520364Z","end":"2026-04-20T10:35:17.061674Z","steps":["trace[431950750] 'range keys from in-memory index tree' (duration: 541.002169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.061814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:16.520346Z","time spent":"541.38167ms","remote":"127.0.0.1:60794","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-20T10:35:17.062228Z","caller":"traceutil/trace.go:171","msg":"trace[606633679] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"226.676047ms","start":"2026-04-20T10:35:16.835537Z","end":"2026-04-20T10:35:17.062213Z","steps":["trace[606633679] 'process raft request' (duration: 191.344557ms)","trace[606633679] 'compare' (duration: 34.581229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:35:17.062307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.337187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:35:17.06243Z","caller":"traceutil/trace.go:171","msg":"trace[1965948091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8859; }","duration":"541.556153ms","start":"2026-04-20T10:35:16.520854Z","end":"2026-04-20T10:35:17.06241Z","steps":["trace[1965948091] 'range keys from in-memory index tree' (duration: 541.188923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.062496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:16.520827Z","time spent":"541.659966ms","remote":"127.0.0.1:60794","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-20T10:35:17.062327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.416854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tkw8s\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:35:17.062582Z","caller":"traceutil/trace.go:171","msg":"trace[923725810] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tkw8s; range_end:; response_count:1; response_revision:8859; }","duration":"524.707172ms","start":"2026-04-20T10:35:16.53785Z","end":"2026-04-20T10:35:17.062557Z","steps":["trace[923725810] 'range keys from in-memory index tree' (duration: 524.321592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.062641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:16.537837Z","time spent":"524.790704ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-tkw8s\" "} {"level":"warn","ts":"2026-04-20T10:35:17.062718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.817132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T10:35:17.062784Z","caller":"traceutil/trace.go:171","msg":"trace[1610709185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8859; }","duration":"520.896034ms","start":"2026-04-20T10:35:16.541879Z","end":"2026-04-20T10:35:17.062775Z","steps":["trace[1610709185] 'range keys from in-memory index tree' (duration: 520.115672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.062811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:16.541874Z","time spent":"520.930364ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:35:17.554736Z","caller":"traceutil/trace.go:171","msg":"trace[217395750] transaction","detail":"{read_only:false; response_revision:8867; number_of_response:1; }","duration":"211.33898ms","start":"2026-04-20T10:35:17.343365Z","end":"2026-04-20T10:35:17.554704Z","steps":["trace[217395750] 'process raft request' (duration: 211.094143ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:17.561695Z","caller":"traceutil/trace.go:171","msg":"trace[863078876] linearizableReadLoop","detail":"{readStateIndex:9191; appliedIndex:9188; }","duration":"214.011824ms","start":"2026-04-20T10:35:17.34766Z","end":"2026-04-20T10:35:17.561672Z","steps":["trace[863078876] 'read index received' (duration: 206.921792ms)","trace[863078876] 'applied index is now lower than readState.Index' (duration: 7.088512ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:35:17.561793Z","caller":"traceutil/trace.go:171","msg":"trace[752373440] transaction","detail":"{read_only:false; response_revision:8868; number_of_response:1; }","duration":"217.563796ms","start":"2026-04-20T10:35:17.344177Z","end":"2026-04-20T10:35:17.56174Z","steps":["trace[752373440] 'process raft request' (duration: 217.270408ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:17.561854Z","caller":"traceutil/trace.go:171","msg":"trace[1319890512] transaction","detail":"{read_only:false; response_revision:8869; number_of_response:1; }","duration":"215.631091ms","start":"2026-04-20T10:35:17.346177Z","end":"2026-04-20T10:35:17.561808Z","steps":["trace[1319890512] 'process raft request' (duration: 215.399275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.561907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.23178ms","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-20T10:35:17.561945Z","caller":"traceutil/trace.go:171","msg":"trace[1635545044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8869; }","duration":"214.332294ms","start":"2026-04-20T10:35:17.347604Z","end":"2026-04-20T10:35:17.561936Z","steps":["trace[1635545044] 'agreement among raft nodes before linearized reading' (duration: 214.18685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.562079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.947298ms","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-20T10:35:17.562096Z","caller":"traceutil/trace.go:171","msg":"trace[253202514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8869; }","duration":"203.964388ms","start":"2026-04-20T10:35:17.358127Z","end":"2026-04-20T10:35:17.562091Z","steps":["trace[253202514] 'agreement among raft nodes before linearized reading' (duration: 203.913157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:17.562165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.632384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:35:17.562222Z","caller":"traceutil/trace.go:171","msg":"trace[1157264679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8869; }","duration":"213.725817ms","start":"2026-04-20T10:35:17.348482Z","end":"2026-04-20T10:35:17.562208Z","steps":["trace[1157264679] 'agreement among raft nodes before linearized reading' (duration: 213.515501ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:17.815563Z","caller":"traceutil/trace.go:171","msg":"trace[369527554] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8879; }","duration":"174.453206ms","start":"2026-04-20T10:35:17.641087Z","end":"2026-04-20T10:35:17.81554Z","steps":["trace[369527554] 'process raft request' (duration: 174.278191ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:17.841569Z","caller":"traceutil/trace.go:171","msg":"trace[143519675] transaction","detail":"{read_only:false; response_revision:8880; number_of_response:1; }","duration":"198.947834ms","start":"2026-04-20T10:35:17.642595Z","end":"2026-04-20T10:35:17.841542Z","steps":["trace[143519675] 'process raft request' (duration: 198.240904ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:18.416728Z","caller":"traceutil/trace.go:171","msg":"trace[795616525] linearizableReadLoop","detail":"{readStateIndex:9204; appliedIndex:9203; }","duration":"237.095583ms","start":"2026-04-20T10:35:18.179601Z","end":"2026-04-20T10:35:18.416696Z","steps":["trace[795616525] 'read index received' (duration: 236.751684ms)","trace[795616525] 'applied index is now lower than readState.Index' (duration: 342.929µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:35:18.416846Z","caller":"traceutil/trace.go:171","msg":"trace[825163220] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"237.740412ms","start":"2026-04-20T10:35:18.17906Z","end":"2026-04-20T10:35:18.416801Z","steps":["trace[825163220] 'process raft request' (duration: 237.363542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:18.417144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.499516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6fdxx\" ","response":"range_response_count:1 size:4642"} {"level":"info","ts":"2026-04-20T10:35:18.417262Z","caller":"traceutil/trace.go:171","msg":"trace[1754119506] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6fdxx; range_end:; response_count:1; response_revision:8882; }","duration":"237.661111ms","start":"2026-04-20T10:35:18.179573Z","end":"2026-04-20T10:35:18.417234Z","steps":["trace[1754119506] 'agreement among raft nodes before linearized reading' (duration: 237.262828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:18.650456Z","caller":"traceutil/trace.go:171","msg":"trace[981534970] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"224.518865ms","start":"2026-04-20T10:35:18.425895Z","end":"2026-04-20T10:35:18.650414Z","steps":["trace[981534970] 'process raft request' (duration: 224.230987ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:19.26417Z","caller":"traceutil/trace.go:171","msg":"trace[1434070354] transaction","detail":"{read_only:false; response_revision:8890; number_of_response:1; }","duration":"186.588932ms","start":"2026-04-20T10:35:19.077553Z","end":"2026-04-20T10:35:19.264142Z","steps":["trace[1434070354] 'process raft request' (duration: 186.388517ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:19.264292Z","caller":"traceutil/trace.go:171","msg":"trace[2077028122] linearizableReadLoop","detail":"{readStateIndex:9212; appliedIndex:9212; }","duration":"186.065797ms","start":"2026-04-20T10:35:19.078185Z","end":"2026-04-20T10:35:19.264251Z","steps":["trace[2077028122] 'read index received' (duration: 185.844161ms)","trace[2077028122] 'applied index is now lower than readState.Index' (duration: 217.746µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:35:19.264691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.468239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tkw8s\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:35:19.264837Z","caller":"traceutil/trace.go:171","msg":"trace[273008978] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tkw8s; range_end:; response_count:1; response_revision:8890; }","duration":"186.677614ms","start":"2026-04-20T10:35:19.078101Z","end":"2026-04-20T10:35:19.264778Z","steps":["trace[273008978] 'agreement among raft nodes before linearized reading' (duration: 186.289083ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:19.269845Z","caller":"traceutil/trace.go:171","msg":"trace[2025834686] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"189.67407ms","start":"2026-04-20T10:35:19.08015Z","end":"2026-04-20T10:35:19.269824Z","steps":["trace[2025834686] 'process raft request' (duration: 189.568257ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:19.269848Z","caller":"traceutil/trace.go:171","msg":"trace[798122049] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"191.511092ms","start":"2026-04-20T10:35:19.0783Z","end":"2026-04-20T10:35:19.269811Z","steps":["trace[798122049] 'process raft request' (duration: 191.254335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:21.00963Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.361056829s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T10:35:21.317715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.719423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:35:21.317881Z","caller":"traceutil/trace.go:171","msg":"trace[1852005031] linearizableReadLoop","detail":"{readStateIndex:9223; appliedIndex:9220; }","duration":"1.695126485s","start":"2026-04-20T10:35:19.622738Z","end":"2026-04-20T10:35:21.317865Z","steps":["trace[1852005031] 'read index received' (duration: 13.177427ms)","trace[1852005031] 'applied index is now lower than readState.Index' (duration: 1.681948088s)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:35:21.317972Z","caller":"traceutil/trace.go:171","msg":"trace[2121454297] transaction","detail":"{read_only:false; response_revision:8900; number_of_response:1; }","duration":"1.710907415s","start":"2026-04-20T10:35:19.607053Z","end":"2026-04-20T10:35:21.317961Z","steps":["trace[2121454297] 'process raft request' (duration: 1.710753981s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:21.317993Z","caller":"traceutil/trace.go:171","msg":"trace[479100117] transaction","detail":"{read_only:false; response_revision:8899; number_of_response:1; }","duration":"1.711472792s","start":"2026-04-20T10:35:19.606503Z","end":"2026-04-20T10:35:21.317976Z","steps":["trace[479100117] 'process raft request' (duration: 1.403375568s)","trace[479100117] 'compare' (duration: 307.084175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:35:21.318025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:19.607031Z","time spent":"1.710966957s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:35:21.318056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:19.606476Z","time spent":"1.711545244s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:35:21.318219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538907291s","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-20T10:35:21.318291Z","caller":"traceutil/trace.go:171","msg":"trace[861405006] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8900; }","duration":"1.539042244s","start":"2026-04-20T10:35:19.779229Z","end":"2026-04-20T10:35:21.318271Z","steps":["trace[861405006] 'agreement among raft nodes before linearized reading' (duration: 1.53890194s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:21.318318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:19.779209Z","time spent":"1.539103236s","remote":"127.0.0.1:42806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:35:21.31865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695916016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:35:21.318685Z","caller":"traceutil/trace.go:171","msg":"trace[549170834] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-config; range_end:; response_count:0; response_revision:8900; }","duration":"1.696002089s","start":"2026-04-20T10:35:19.622673Z","end":"2026-04-20T10:35:21.318675Z","steps":["trace[549170834] 'agreement among raft nodes before linearized reading' (duration: 1.695883326s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:21.318799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253059633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:35:21.31882Z","caller":"traceutil/trace.go:171","msg":"trace[879652032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8900; }","duration":"1.253122215s","start":"2026-04-20T10:35:20.065692Z","end":"2026-04-20T10:35:21.318814Z","steps":["trace[879652032] 'agreement among raft nodes before linearized reading' (duration: 1.253089695s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:21.318836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:20.065664Z","time spent":"1.253168386s","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:35:21.318864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:19.622653Z","time spent":"1.696127462s","remote":"127.0.0.1:60614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/openstack/rook-ceph-config\" "} {"level":"warn","ts":"2026-04-20T10:35:22.114827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306960158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:35:22.247176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.434949ms","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-20T10:35:22.247295Z","caller":"traceutil/trace.go:171","msg":"trace[563613948] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8904; }","duration":"738.569783ms","start":"2026-04-20T10:35:21.508673Z","end":"2026-04-20T10:35:22.247243Z","steps":["trace[563613948] 'count revisions from in-memory index tree' (duration: 738.336827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.247348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:21.508649Z","time spent":"738.687146ms","remote":"127.0.0.1:42830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:35:22.247606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.480742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:35:22.247641Z","caller":"traceutil/trace.go:171","msg":"trace[1813606132] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8904; }","duration":"702.550624ms","start":"2026-04-20T10:35:21.545079Z","end":"2026-04-20T10:35:22.24763Z","steps":["trace[1813606132] 'count revisions from in-memory index tree' (duration: 702.40019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.247669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:21.545054Z","time spent":"702.607826ms","remote":"127.0.0.1:42898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:35:22.247886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.214781ms","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-20T10:35:22.247979Z","caller":"traceutil/trace.go:171","msg":"trace[204557201] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8904; }","duration":"109.307623ms","start":"2026-04-20T10:35:22.13866Z","end":"2026-04-20T10:35:22.247967Z","steps":["trace[204557201] 'range keys from in-memory index tree' (duration: 109.20461ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:22.248064Z","caller":"traceutil/trace.go:171","msg":"trace[1555946819] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"639.723979ms","start":"2026-04-20T10:35:21.60833Z","end":"2026-04-20T10:35:22.248054Z","steps":["trace[1555946819] 'process raft request' (duration: 637.903336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.248186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:21.608302Z","time spent":"639.820061ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:35:22.255114Z","caller":"traceutil/trace.go:171","msg":"trace[382392402] linearizableReadLoop","detail":"{readStateIndex:9231; appliedIndex:9227; }","duration":"641.303124ms","start":"2026-04-20T10:35:21.613784Z","end":"2026-04-20T10:35:22.255087Z","steps":["trace[382392402] 'read index received' (duration: 632.526662ms)","trace[382392402] 'applied index is now lower than readState.Index' (duration: 8.775362ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:35:22.255377Z","caller":"traceutil/trace.go:171","msg":"trace[619265118] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"646.945085ms","start":"2026-04-20T10:35:21.608417Z","end":"2026-04-20T10:35:22.255362Z","steps":["trace[619265118] 'process raft request' (duration: 646.46139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.255535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:21.608398Z","time spent":"647.023277ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:35:22.255733Z","caller":"traceutil/trace.go:171","msg":"trace[634454874] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"647.096269ms","start":"2026-04-20T10:35:21.608627Z","end":"2026-04-20T10:35:22.255723Z","steps":["trace[634454874] 'process raft request' (duration: 646.371629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.255794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:21.608596Z","time spent":"647.169191ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:35:22.256102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.453686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:35:22.256149Z","caller":"traceutil/trace.go:171","msg":"trace[1350565464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8909; }","duration":"615.576329ms","start":"2026-04-20T10:35:21.64056Z","end":"2026-04-20T10:35:22.256137Z","steps":["trace[1350565464] 'agreement among raft nodes before linearized reading' (duration: 615.414734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.256184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:21.64053Z","time spent":"615.647401ms","remote":"127.0.0.1:60794","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-20T10:35:22.256237Z","caller":"traceutil/trace.go:171","msg":"trace[1134750226] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"234.705976ms","start":"2026-04-20T10:35:22.021513Z","end":"2026-04-20T10:35:22.256219Z","steps":["trace[1134750226] 'process raft request' (duration: 233.517802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.256958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.062306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-20T10:35:22.257003Z","caller":"traceutil/trace.go:171","msg":"trace[1972538221] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8909; }","duration":"173.162898ms","start":"2026-04-20T10:35:22.083828Z","end":"2026-04-20T10:35:22.25699Z","steps":["trace[1972538221] 'agreement among raft nodes before linearized reading' (duration: 173.057665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.258032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.208327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-04-20T10:35:22.258074Z","caller":"traceutil/trace.go:171","msg":"trace[697347354] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8909; }","duration":"644.3289ms","start":"2026-04-20T10:35:21.613734Z","end":"2026-04-20T10:35:22.258062Z","steps":["trace[697347354] 'agreement among raft nodes before linearized reading' (duration: 642.545709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.258138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:21.613715Z","time spent":"644.413483ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2211012,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-04-20T10:35:22.887464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.626866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-20T10:35:22.887597Z","caller":"traceutil/trace.go:171","msg":"trace[358193146] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:8910; }","duration":"380.819711ms","start":"2026-04-20T10:35:22.506746Z","end":"2026-04-20T10:35:22.887566Z","steps":["trace[358193146] 'range keys from in-memory index tree' (duration: 380.371149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.887671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:22.506718Z","time spent":"380.928065ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-04-20T10:35:22.888153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.083385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6fdxx\" ","response":"range_response_count:1 size:4647"} {"level":"warn","ts":"2026-04-20T10:35:22.888172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.171806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-6fdxx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:35:22.888223Z","caller":"traceutil/trace.go:171","msg":"trace[455715162] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6fdxx; range_end:; response_count:1; response_revision:8910; }","duration":"412.200208ms","start":"2026-04-20T10:35:22.476007Z","end":"2026-04-20T10:35:22.888207Z","steps":["trace[455715162] 'range keys from in-memory index tree' (duration: 411.837867ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:35:22.888235Z","caller":"traceutil/trace.go:171","msg":"trace[1580573090] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-6fdxx; range_end:; response_count:0; response_revision:8910; }","duration":"490.280369ms","start":"2026-04-20T10:35:22.397938Z","end":"2026-04-20T10:35:22.888218Z","steps":["trace[1580573090] 'range keys from in-memory index tree' (duration: 490.101883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.888268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:22.475986Z","time spent":"412.269741ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4670,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6fdxx\" "} {"level":"warn","ts":"2026-04-20T10:35:22.88767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.259468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111915"} {"level":"info","ts":"2026-04-20T10:35:22.888655Z","caller":"traceutil/trace.go:171","msg":"trace[1656495447] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8910; }","duration":"540.310068ms","start":"2026-04-20T10:35:22.348323Z","end":"2026-04-20T10:35:22.888633Z","steps":["trace[1656495447] 'range keys from in-memory index tree' (duration: 538.866547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:35:22.888347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:22.397917Z","time spent":"490.350181ms","remote":"127.0.0.1:53222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-6fdxx\" "} {"level":"warn","ts":"2026-04-20T10:35:22.888746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:35:22.348303Z","time spent":"540.427202ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":16,"response size":111938,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-04-20T10:35:29.822019Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002465.wal"} {"level":"info","ts":"2026-04-20T10:36:39.071248Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T10:36:39.077252Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T10:36:39.077354Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T10:38:12.280686Z","caller":"traceutil/trace.go:171","msg":"trace[1530312654] transaction","detail":"{read_only:false; response_revision:10307; number_of_response:1; }","duration":"201.58013ms","start":"2026-04-20T10:38:12.079069Z","end":"2026-04-20T10:38:12.280649Z","steps":["trace[1530312654] 'process raft request' (duration: 201.373894ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:12.498585Z","caller":"traceutil/trace.go:171","msg":"trace[896089183] transaction","detail":"{read_only:false; response_revision:10311; number_of_response:1; }","duration":"126.154165ms","start":"2026-04-20T10:38:12.372393Z","end":"2026-04-20T10:38:12.498547Z","steps":["trace[896089183] 'process raft request' (duration: 102.628092ms)","trace[896089183] 'compare' (duration: 23.293976ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:38:12.498834Z","caller":"traceutil/trace.go:171","msg":"trace[600041462] transaction","detail":"{read_only:false; response_revision:10312; number_of_response:1; }","duration":"125.28009ms","start":"2026-04-20T10:38:12.373517Z","end":"2026-04-20T10:38:12.498797Z","steps":["trace[600041462] 'process raft request' (duration: 124.977451ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:14.573203Z","caller":"traceutil/trace.go:171","msg":"trace[1884503214] transaction","detail":"{read_only:false; response_revision:10319; number_of_response:1; }","duration":"132.841735ms","start":"2026-04-20T10:38:14.440331Z","end":"2026-04-20T10:38:14.573172Z","steps":["trace[1884503214] 'process raft request' (duration: 132.326111ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:14.573492Z","caller":"traceutil/trace.go:171","msg":"trace[1983973043] transaction","detail":"{read_only:false; response_revision:10320; number_of_response:1; }","duration":"133.099883ms","start":"2026-04-20T10:38:14.440378Z","end":"2026-04-20T10:38:14.573478Z","steps":["trace[1983973043] 'process raft request' (duration: 132.441684ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:15.071652Z","caller":"traceutil/trace.go:171","msg":"trace[1300112215] transaction","detail":"{read_only:false; response_revision:10324; number_of_response:1; }","duration":"110.508057ms","start":"2026-04-20T10:38:14.96111Z","end":"2026-04-20T10:38:15.071618Z","steps":["trace[1300112215] 'process raft request' (duration: 110.303251ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:15.106696Z","caller":"traceutil/trace.go:171","msg":"trace[1051907214] transaction","detail":"{read_only:false; response_revision:10325; number_of_response:1; }","duration":"105.777703ms","start":"2026-04-20T10:38:15.000879Z","end":"2026-04-20T10:38:15.106657Z","steps":["trace[1051907214] 'process raft request' (duration: 105.440204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:43.289314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306964503,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:38:43.789798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306964503,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:38:44.290831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306964503,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:38:44.791747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306964503,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:38:45.063291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000408643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T10:38:45.063402Z","caller":"traceutil/trace.go:171","msg":"trace[895308962] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000607649s","start":"2026-04-20T10:38:43.062772Z","end":"2026-04-20T10:38:45.063379Z","steps":["trace[895308962] 'agreement among raft nodes before linearized reading' (duration: 2.000443004s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:45.063557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:43.062745Z","time spent":"2.000766834s","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 10:38:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T10:38:45.292859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306964503,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:38:45.79396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605272306964503,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T10:38:46.251101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.469885657s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T10:38:46.251672Z","caller":"traceutil/trace.go:171","msg":"trace[488703945] transaction","detail":"{read_only:false; response_revision:10507; number_of_response:1; }","duration":"3.470535916s","start":"2026-04-20T10:38:42.781103Z","end":"2026-04-20T10:38:46.251639Z","steps":["trace[488703945] 'process raft request' (duration: 3.47031797s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.251924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:42.781085Z","time spent":"3.470709961s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:38:46.269148Z","caller":"traceutil/trace.go:171","msg":"trace[2015074441] transaction","detail":"{read_only:false; response_revision:10508; number_of_response:1; }","duration":"3.046578582s","start":"2026-04-20T10:38:43.222549Z","end":"2026-04-20T10:38:46.269128Z","steps":["trace[2015074441] 'process raft request' (duration: 3.037825501s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.269255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:43.222508Z","time spent":"3.046692126s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:38:46.269502Z","caller":"traceutil/trace.go:171","msg":"trace[223393210] transaction","detail":"{read_only:false; response_revision:10510; number_of_response:1; }","duration":"3.042512596s","start":"2026-04-20T10:38:43.226967Z","end":"2026-04-20T10:38:46.26948Z","steps":["trace[223393210] 'process raft request' (duration: 3.042361782s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:46.270187Z","caller":"traceutil/trace.go:171","msg":"trace[1674383063] transaction","detail":"{read_only:false; response_revision:10509; number_of_response:1; }","duration":"3.04718796s","start":"2026-04-20T10:38:43.222597Z","end":"2026-04-20T10:38:46.269785Z","steps":["trace[1674383063] 'process raft request' (duration: 3.04647996s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.270234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:43.226938Z","time spent":"3.043241427s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:38:46.272251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:43.222552Z","time spent":"3.047768816s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:38:46.27563Z","caller":"traceutil/trace.go:171","msg":"trace[1698005347] transaction","detail":"{read_only:false; response_revision:10511; number_of_response:1; }","duration":"1.291785977s","start":"2026-04-20T10:38:44.983815Z","end":"2026-04-20T10:38:46.275601Z","steps":["trace[1698005347] 'process raft request' (duration: 1.291489808s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.275716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:44.983783Z","time spent":"1.29188821s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:38:46.276333Z","caller":"traceutil/trace.go:171","msg":"trace[1695168349] transaction","detail":"{read_only:false; response_revision:10512; number_of_response:1; }","duration":"409.298394ms","start":"2026-04-20T10:38:45.866958Z","end":"2026-04-20T10:38:46.276256Z","steps":["trace[1695168349] 'process raft request' (duration: 408.596114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.2764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:45.866919Z","time spent":"409.444758ms","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:38:46.27678Z","caller":"traceutil/trace.go:171","msg":"trace[1794510855] linearizableReadLoop","detail":"{readStateIndex:10885; appliedIndex:10879; }","duration":"3.487954293s","start":"2026-04-20T10:38:42.788728Z","end":"2026-04-20T10:38:46.276683Z","steps":["trace[1794510855] 'read index received' (duration: 3.462797475s)","trace[1794510855] 'applied index is now lower than readState.Index' (duration: 25.152028ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:38:46.276841Z","caller":"traceutil/trace.go:171","msg":"trace[1249994949] transaction","detail":"{read_only:false; response_revision:10513; number_of_response:1; }","duration":"296.77322ms","start":"2026-04-20T10:38:45.979956Z","end":"2026-04-20T10:38:46.276729Z","steps":["trace[1249994949] 'process raft request' (duration: 296.253985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.488268352s","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-20T10:38:46.277128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213322654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:38:46.277153Z","caller":"traceutil/trace.go:171","msg":"trace[1002893291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10513; }","duration":"3.488400576s","start":"2026-04-20T10:38:42.788724Z","end":"2026-04-20T10:38:46.277124Z","steps":["trace[1002893291] 'agreement among raft nodes before linearized reading' (duration: 3.488101477s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:42.788691Z","time spent":"3.488511489s","remote":"127.0.0.1:60794","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-20T10:38:46.277239Z","caller":"traceutil/trace.go:171","msg":"trace[1253074796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10513; }","duration":"1.21347656s","start":"2026-04-20T10:38:45.063717Z","end":"2026-04-20T10:38:46.277194Z","steps":["trace[1253074796] 'agreement among raft nodes before linearized reading' (duration: 1.213291955s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534151631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:38:46.277299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:45.0637Z","time spent":"1.213586422s","remote":"127.0.0.1:60512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:38:46.277279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.488404106s","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-20T10:38:46.277345Z","caller":"traceutil/trace.go:171","msg":"trace[981246542] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:10513; }","duration":"1.534261854s","start":"2026-04-20T10:38:44.743041Z","end":"2026-04-20T10:38:46.277303Z","steps":["trace[981246542] 'agreement among raft nodes before linearized reading' (duration: 1.534148191s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.487735977s","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-20T10:38:46.2774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:44.743018Z","time spent":"1.534368188s","remote":"127.0.0.1:46602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:38:46.277383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.747429235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T10:38:46.277427Z","caller":"traceutil/trace.go:171","msg":"trace[2008243792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10513; }","duration":"3.488608113s","start":"2026-04-20T10:38:42.788768Z","end":"2026-04-20T10:38:46.277376Z","steps":["trace[2008243792] 'agreement among raft nodes before linearized reading' (duration: 3.48818983s)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:38:46.277452Z","caller":"traceutil/trace.go:171","msg":"trace[1570540359] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10513; }","duration":"3.487830281s","start":"2026-04-20T10:38:42.789568Z","end":"2026-04-20T10:38:46.277398Z","steps":["trace[1570540359] 'agreement among raft nodes before linearized reading' (duration: 3.487638324s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.177259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:38:46.277515Z","caller":"traceutil/trace.go:171","msg":"trace[297256043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10513; }","duration":"2.747538749s","start":"2026-04-20T10:38:43.529921Z","end":"2026-04-20T10:38:46.27746Z","steps":["trace[297256043] 'agreement among raft nodes before linearized reading' (duration: 2.747271101s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:42.789557Z","time spent":"3.487926104s","remote":"127.0.0.1:60794","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-20T10:38:46.277581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:43.529822Z","time spent":"2.747749925s","remote":"127.0.0.1:60794","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-20T10:38:46.277603Z","caller":"traceutil/trace.go:171","msg":"trace[1365751372] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:10513; }","duration":"466.414977ms","start":"2026-04-20T10:38:45.811124Z","end":"2026-04-20T10:38:46.277539Z","steps":["trace[1365751372] 'agreement among raft nodes before linearized reading' (duration: 466.077256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077572006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:38:46.277699Z","caller":"traceutil/trace.go:171","msg":"trace[12715951] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:10513; }","duration":"1.077852554s","start":"2026-04-20T10:38:45.199813Z","end":"2026-04-20T10:38:46.277666Z","steps":["trace[12715951] 'agreement among raft nodes before linearized reading' (duration: 1.077729561s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:45.811099Z","time spent":"466.564591ms","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":21,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:38:46.277708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.48885473s","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-20T10:38:46.277737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:45.199801Z","time spent":"1.077925347s","remote":"127.0.0.1:60778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:38:46.27777Z","caller":"traceutil/trace.go:171","msg":"trace[1224008788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10513; }","duration":"3.488944322s","start":"2026-04-20T10:38:42.788794Z","end":"2026-04-20T10:38:46.277738Z","steps":["trace[1224008788] 'agreement among raft nodes before linearized reading' (duration: 3.488760987s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.996899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:38:46.277812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:42.788766Z","time spent":"3.489036765s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T10:38:46.277493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:42.78873Z","time spent":"3.488748407s","remote":"127.0.0.1:60794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:38:46.277881Z","caller":"traceutil/trace.go:171","msg":"trace[207798686] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:10513; }","duration":"645.148633ms","start":"2026-04-20T10:38:45.632689Z","end":"2026-04-20T10:38:46.277837Z","steps":["trace[207798686] 'agreement among raft nodes before linearized reading' (duration: 644.752152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.277982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:45.632658Z","time spent":"645.305067ms","remote":"127.0.0.1:40256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:38:46.278547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.36426507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tkw8s\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T10:38:46.278621Z","caller":"traceutil/trace.go:171","msg":"trace[1078517474] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tkw8s; range_end:; response_count:1; response_revision:10513; }","duration":"3.364374452s","start":"2026-04-20T10:38:42.914213Z","end":"2026-04-20T10:38:46.278587Z","steps":["trace[1078517474] 'agreement among raft nodes before linearized reading' (duration: 3.362969291s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:38:46.278649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:38:42.914188Z","time spent":"3.364453835s","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-tkw8s\" "} {"level":"info","ts":"2026-04-20T10:38:46.287815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8135} {"level":"info","ts":"2026-04-20T10:38:46.379034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8135,"took":"87.800099ms","hash":816861990,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":28360704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-20T10:38:46.379093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":816861990,"revision":8135,"compact-revision":6054} {"level":"info","ts":"2026-04-20T10:43:46.294327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10513} {"level":"info","ts":"2026-04-20T10:43:46.383061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10513,"took":"85.743139ms","hash":418671323,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":22196224,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-20T10:43:46.383156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":418671323,"revision":10513,"compact-revision":8135}