{"level":"warn","ts":"2026-05-12T16:34:27.202325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:34:27.202412Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.3:2380","--initial-cluster=instance=https://199.19.213.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.3: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-05-12T16:34:27.202474Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:34:27.202484Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-05-12T16:34:27.2025Z","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-05-12T16:34:27.203616Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"]} {"level":"info","ts":"2026-05-12T16:34:27.203714Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.3: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-05-12T16:34:27.209077Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.086597ms"} {"level":"info","ts":"2026-05-12T16:34:27.223954Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cf904ec39d3e333","cluster-id":"7b1ae51097bedd0e"} {"level":"info","ts":"2026-05-12T16:34:27.224046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T16:34:27.224068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 0"} {"level":"info","ts":"2026-05-12T16:34:27.224359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cf904ec39d3e333 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T16:34:27.224377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 1"} {"level":"info","ts":"2026-05-12T16:34:27.224418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"warn","ts":"2026-05-12T16:34:27.233082Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T16:34:27.234819Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T16:34:27.237348Z","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-05-12T16:34:27.239681Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cf904ec39d3e333","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T16:34:27.239888Z","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-05-12T16:34:27.240035Z","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-05-12T16:34:27.240052Z","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-05-12T16:34:27.240012Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cf904ec39d3e333","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T16:34:27.240663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"info","ts":"2026-05-12T16:34:27.240869Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","added-peer-id":"3cf904ec39d3e333","added-peer-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-05-12T16:34:27.246468Z","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-05-12T16:34:27.246565Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-05-12T16:34:27.246634Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-05-12T16:34:27.246871Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cf904ec39d3e333","initial-advertise-peer-urls":["https://199.19.213.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T16:34:27.246915Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T16:34:27.925464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T16:34:27.925548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T16:34:27.92558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgPreVoteResp from 3cf904ec39d3e333 at term 1"} {"level":"info","ts":"2026-05-12T16:34:27.925601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became candidate at term 2"} {"level":"info","ts":"2026-05-12T16:34:27.925613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgVoteResp from 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-05-12T16:34:27.92563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became leader at term 2"} {"level":"info","ts":"2026-05-12T16:34:27.925644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cf904ec39d3e333 elected leader 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-05-12T16:34:27.926868Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cf904ec39d3e333","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.3:2379]}","request-path":"/0/members/3cf904ec39d3e333/attributes","cluster-id":"7b1ae51097bedd0e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T16:34:27.926876Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:34:27.926889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:34:27.926922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:34:27.92721Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T16:34:27.927424Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T16:34:27.927983Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:34:27.928073Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:34:27.928107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:34:27.930635Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.3:2379"} {"level":"info","ts":"2026-05-12T16:34:27.930637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-12T16:34:37.937816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.792531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:34:37.938011Z","caller":"traceutil/trace.go:171","msg":"trace[87700999] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:361; }","duration":"134.430462ms","start":"2026-05-12T16:34:37.803565Z","end":"2026-05-12T16:34:37.937995Z","steps":["trace[87700999] 'read index received' (duration: 19.930707ms)","trace[87700999] 'applied index is now lower than readState.Index' (duration: 114.498925ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:34:37.938109Z","caller":"traceutil/trace.go:171","msg":"trace[519501695] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"168.344921ms","start":"2026-05-12T16:34:37.769754Z","end":"2026-05-12T16:34:37.938099Z","steps":["trace[519501695] 'process raft request' (duration: 168.186408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:37.938283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.731718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-12T16:34:37.93832Z","caller":"traceutil/trace.go:171","msg":"trace[1404604832] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:354; }","duration":"134.81942ms","start":"2026-05-12T16:34:37.803491Z","end":"2026-05-12T16:34:37.938311Z","steps":["trace[1404604832] 'agreement among raft nodes before linearized reading' (duration: 134.716198ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:37.938309Z","caller":"traceutil/trace.go:171","msg":"trace[487582535] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"172.932957ms","start":"2026-05-12T16:34:37.765352Z","end":"2026-05-12T16:34:37.938285Z","steps":["trace[487582535] 'process raft request' (duration: 58.166207ms)","trace[487582535] 'compare' (duration: 113.663568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:34:37.938496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.560045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T16:34:37.938614Z","caller":"traceutil/trace.go:171","msg":"trace[1999157511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:354; }","duration":"113.723639ms","start":"2026-05-12T16:34:37.824867Z","end":"2026-05-12T16:34:37.938591Z","steps":["trace[1999157511] 'agreement among raft nodes before linearized reading' (duration: 113.430033ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.128Z","caller":"traceutil/trace.go:171","msg":"trace[832515849] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:363; }","duration":"176.159286ms","start":"2026-05-12T16:34:37.951816Z","end":"2026-05-12T16:34:38.127975Z","steps":["trace[832515849] 'read index received' (duration: 24.221827ms)","trace[832515849] 'applied index is now lower than readState.Index' (duration: 151.936259ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:34:38.128027Z","caller":"traceutil/trace.go:171","msg":"trace[239522151] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"182.807204ms","start":"2026-05-12T16:34:37.945179Z","end":"2026-05-12T16:34:38.127986Z","steps":["trace[239522151] 'process raft request' (duration: 182.508328ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.128103Z","caller":"traceutil/trace.go:171","msg":"trace[2086939054] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"182.11894ms","start":"2026-05-12T16:34:37.945924Z","end":"2026-05-12T16:34:38.128043Z","steps":["trace[2086939054] 'process raft request' (duration: 181.956667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:38.128253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.414891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-12T16:34:38.128251Z","caller":"traceutil/trace.go:171","msg":"trace[2107287765] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"181.519298ms","start":"2026-05-12T16:34:37.946642Z","end":"2026-05-12T16:34:38.128162Z","steps":["trace[2107287765] 'process raft request' (duration: 181.292783ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.12832Z","caller":"traceutil/trace.go:171","msg":"trace[1247487839] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:358; }","duration":"176.549153ms","start":"2026-05-12T16:34:37.951753Z","end":"2026-05-12T16:34:38.128302Z","steps":["trace[1247487839] 'agreement among raft nodes before linearized reading' (duration: 176.39107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:38.128471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.654269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-12T16:34:38.128569Z","caller":"traceutil/trace.go:171","msg":"trace[311095457] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:358; }","duration":"125.814543ms","start":"2026-05-12T16:34:38.002736Z","end":"2026-05-12T16:34:38.12855Z","steps":["trace[311095457] 'agreement among raft nodes before linearized reading' (duration: 125.545187ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.337371Z","caller":"traceutil/trace.go:171","msg":"trace[1510276380] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"158.619097ms","start":"2026-05-12T16:34:38.178707Z","end":"2026-05-12T16:34:38.337326Z","steps":["trace[1510276380] 'process raft request' (duration: 113.097915ms)","trace[1510276380] 'compare' (duration: 45.298758ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:34:38.337968Z","caller":"traceutil/trace.go:171","msg":"trace[443916749] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:372; }","duration":"108.99722ms","start":"2026-05-12T16:34:38.228944Z","end":"2026-05-12T16:34:38.337941Z","steps":["trace[443916749] 'read index received' (duration: 62.913915ms)","trace[443916749] 'applied index is now lower than readState.Index' (duration: 46.082175ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:34:38.338112Z","caller":"traceutil/trace.go:171","msg":"trace[1970879831] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"108.203864ms","start":"2026-05-12T16:34:38.229872Z","end":"2026-05-12T16:34:38.338076Z","steps":["trace[1970879831] 'process raft request' (duration: 107.973249ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.338176Z","caller":"traceutil/trace.go:171","msg":"trace[1326731340] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"110.88641ms","start":"2026-05-12T16:34:38.227276Z","end":"2026-05-12T16:34:38.338163Z","steps":["trace[1326731340] 'process raft request' (duration: 110.311489ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.338262Z","caller":"traceutil/trace.go:171","msg":"trace[1993824147] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"105.961426ms","start":"2026-05-12T16:34:38.232285Z","end":"2026-05-12T16:34:38.338247Z","steps":["trace[1993824147] 'process raft request' (duration: 105.613749ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.338433Z","caller":"traceutil/trace.go:171","msg":"trace[11526115] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"108.662934ms","start":"2026-05-12T16:34:38.229754Z","end":"2026-05-12T16:34:38.338417Z","steps":["trace[11526115] 'process raft request' (duration: 108.036631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:38.338494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.539432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-12T16:34:38.338497Z","caller":"traceutil/trace.go:171","msg":"trace[1192298471] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"110.831359ms","start":"2026-05-12T16:34:38.227652Z","end":"2026-05-12T16:34:38.338483Z","steps":["trace[1192298471] 'process raft request' (duration: 110.049663ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.338541Z","caller":"traceutil/trace.go:171","msg":"trace[986175745] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"109.632833ms","start":"2026-05-12T16:34:38.228897Z","end":"2026-05-12T16:34:38.33853Z","steps":["trace[986175745] 'agreement among raft nodes before linearized reading' (duration: 109.541071ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:38.338371Z","caller":"traceutil/trace.go:171","msg":"trace[1828023428] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"109.914279ms","start":"2026-05-12T16:34:38.228438Z","end":"2026-05-12T16:34:38.338352Z","steps":["trace[1828023428] 'process raft request' (duration: 109.319597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:53.042863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.176778ms","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-05-12T16:34:53.04297Z","caller":"traceutil/trace.go:171","msg":"trace[1433027945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"103.349012ms","start":"2026-05-12T16:34:52.9396Z","end":"2026-05-12T16:34:53.042949Z","steps":["trace[1433027945] 'range keys from in-memory index tree' (duration: 102.881282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:53.042916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.300222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:34:53.043068Z","caller":"traceutil/trace.go:171","msg":"trace[893919329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"267.537967ms","start":"2026-05-12T16:34:52.775499Z","end":"2026-05-12T16:34:53.043037Z","steps":["trace[893919329] 'range keys from in-memory index tree' (duration: 267.051566ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:05.832877Z","caller":"traceutil/trace.go:171","msg":"trace[803565164] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"141.967018ms","start":"2026-05-12T16:35:05.690884Z","end":"2026-05-12T16:35:05.832851Z","steps":["trace[803565164] 'process raft request' (duration: 140.972116ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:06.170987Z","caller":"traceutil/trace.go:171","msg":"trace[945903806] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"257.684171ms","start":"2026-05-12T16:35:05.913275Z","end":"2026-05-12T16:35:06.170959Z","steps":["trace[945903806] 'process raft request' (duration: 257.544518ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:06.897742Z","caller":"traceutil/trace.go:171","msg":"trace[348352876] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"319.277681ms","start":"2026-05-12T16:35:06.578433Z","end":"2026-05-12T16:35:06.897711Z","steps":["trace[348352876] 'process raft request' (duration: 319.055377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:06.897908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:06.578406Z","time spent":"319.417465ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:06.907269Z","caller":"traceutil/trace.go:171","msg":"trace[1821927096] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"309.043145ms","start":"2026-05-12T16:35:06.598159Z","end":"2026-05-12T16:35:06.907202Z","steps":["trace[1821927096] 'process raft request' (duration: 302.63062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:06.907507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:35:06.598107Z","time spent":"309.29312ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:35:06.921797Z","caller":"traceutil/trace.go:171","msg":"trace[2118693775] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"144.702205ms","start":"2026-05-12T16:35:06.77703Z","end":"2026-05-12T16:35:06.921732Z","steps":["trace[2118693775] 'read index received' (duration: 120.426103ms)","trace[2118693775] 'applied index is now lower than readState.Index' (duration: 24.274832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:06.922017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.103595ms","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-05-12T16:35:06.922048Z","caller":"traceutil/trace.go:171","msg":"trace[1028539245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"137.202467ms","start":"2026-05-12T16:35:06.784837Z","end":"2026-05-12T16:35:06.922039Z","steps":["trace[1028539245] 'agreement among raft nodes before linearized reading' (duration: 137.058844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:35:06.922002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.935189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:35:06.922124Z","caller":"traceutil/trace.go:171","msg":"trace[2128801452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"145.123293ms","start":"2026-05-12T16:35:06.776969Z","end":"2026-05-12T16:35:06.922093Z","steps":["trace[2128801452] 'agreement among raft nodes before linearized reading' (duration: 144.875498ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:23.338712Z","caller":"traceutil/trace.go:171","msg":"trace[1062661093] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"281.561296ms","start":"2026-05-12T16:35:23.057107Z","end":"2026-05-12T16:35:23.338668Z","steps":["trace[1062661093] 'process raft request' (duration: 281.239679ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:36:13.12146Z","caller":"traceutil/trace.go:171","msg":"trace[1786309787] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"135.28702ms","start":"2026-05-12T16:36:12.98615Z","end":"2026-05-12T16:36:13.121437Z","steps":["trace[1786309787] 'process raft request' (duration: 135.105756ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:36:36.627886Z","caller":"traceutil/trace.go:171","msg":"trace[442167084] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"181.752508ms","start":"2026-05-12T16:36:36.446102Z","end":"2026-05-12T16:36:36.627854Z","steps":["trace[442167084] 'read index received' (duration: 181.414091ms)","trace[442167084] 'applied index is now lower than readState.Index' (duration: 336.577µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:36:36.62794Z","caller":"traceutil/trace.go:171","msg":"trace[1624980831] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"229.360201ms","start":"2026-05-12T16:36:36.398537Z","end":"2026-05-12T16:36:36.627897Z","steps":["trace[1624980831] 'process raft request' (duration: 229.084975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:36.62814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.849244ms","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":"info","ts":"2026-05-12T16:36:36.628183Z","caller":"traceutil/trace.go:171","msg":"trace[498881899] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1847; }","duration":"128.977797ms","start":"2026-05-12T16:36:36.499195Z","end":"2026-05-12T16:36:36.628172Z","steps":["trace[498881899] 'agreement among raft nodes before linearized reading' (duration: 128.861064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:36:36.628412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.241559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-505befaa-23ed-451e-8381-61f90a5eccf4\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-05-12T16:36:36.628599Z","caller":"traceutil/trace.go:171","msg":"trace[775625400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-505befaa-23ed-451e-8381-61f90a5eccf4; range_end:; response_count:1; response_revision:1847; }","duration":"182.464024ms","start":"2026-05-12T16:36:36.446054Z","end":"2026-05-12T16:36:36.628518Z","steps":["trace[775625400] 'agreement among raft nodes before linearized reading' (duration: 181.962563ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:21.51542Z","caller":"traceutil/trace.go:171","msg":"trace[1813447317] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"128.252802ms","start":"2026-05-12T16:37:21.38714Z","end":"2026-05-12T16:37:21.515393Z","steps":["trace[1813447317] 'process raft request' (duration: 128.083468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:37:21.728759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.122049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:37:21.728965Z","caller":"traceutil/trace.go:171","msg":"trace[1585337869] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"235.417359ms","start":"2026-05-12T16:37:21.493523Z","end":"2026-05-12T16:37:21.72894Z","steps":["trace[1585337869] 'read index received' (duration: 21.722658ms)","trace[1585337869] 'applied index is now lower than readState.Index' (duration: 213.692671ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:37:21.729117Z","caller":"traceutil/trace.go:171","msg":"trace[1220357955] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"313.908373ms","start":"2026-05-12T16:37:21.415134Z","end":"2026-05-12T16:37:21.729043Z","steps":["trace[1220357955] 'process raft request' (duration: 205.123931ms)","trace[1220357955] 'compare' (duration: 107.966035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:37:21.729229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.774037ms","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":"warn","ts":"2026-05-12T16:37:21.729271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.354826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T16:37:21.729383Z","caller":"traceutil/trace.go:171","msg":"trace[99190054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2145; }","duration":"223.58854ms","start":"2026-05-12T16:37:21.505783Z","end":"2026-05-12T16:37:21.729371Z","steps":["trace[99190054] 'agreement among raft nodes before linearized reading' (duration: 223.296834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:37:21.729312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.841146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-12T16:37:21.729533Z","caller":"traceutil/trace.go:171","msg":"trace[1723053627] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:2145; }","duration":"168.105141ms","start":"2026-05-12T16:37:21.561411Z","end":"2026-05-12T16:37:21.729516Z","steps":["trace[1723053627] 'agreement among raft nodes before linearized reading' (duration: 167.801885ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:21.729321Z","caller":"traceutil/trace.go:171","msg":"trace[1459174153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2145; }","duration":"235.91865ms","start":"2026-05-12T16:37:21.493383Z","end":"2026-05-12T16:37:21.729302Z","steps":["trace[1459174153] 'agreement among raft nodes before linearized reading' (duration: 235.687665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:37:21.729322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:37:21.415094Z","time spent":"314.130277ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:37:21.948513Z","caller":"traceutil/trace.go:171","msg":"trace[214105499] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"213.839995ms","start":"2026-05-12T16:37:21.734629Z","end":"2026-05-12T16:37:21.948469Z","steps":["trace[214105499] 'process raft request' (duration: 213.480806ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:21.948508Z","caller":"traceutil/trace.go:171","msg":"trace[1627266522] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"213.840785ms","start":"2026-05-12T16:37:21.73463Z","end":"2026-05-12T16:37:21.94847Z","steps":["trace[1627266522] 'process raft request' (duration: 213.689991ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:37:21.952168Z","caller":"traceutil/trace.go:171","msg":"trace[1736014271] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"169.255046ms","start":"2026-05-12T16:37:21.78289Z","end":"2026-05-12T16:37:21.952145Z","steps":["trace[1736014271] 'process raft request' (duration: 169.132823ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:50.588737Z","caller":"traceutil/trace.go:171","msg":"trace[347518015] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"129.473143ms","start":"2026-05-12T16:38:50.459227Z","end":"2026-05-12T16:38:50.5887Z","steps":["trace[347518015] 'process raft request' (duration: 129.279969ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:50.816619Z","caller":"traceutil/trace.go:171","msg":"trace[1328075831] linearizableReadLoop","detail":"{readStateIndex:2926; appliedIndex:2923; }","duration":"353.895008ms","start":"2026-05-12T16:38:50.462695Z","end":"2026-05-12T16:38:50.81659Z","steps":["trace[1328075831] 'read index received' (duration: 125.814215ms)","trace[1328075831] 'applied index is now lower than readState.Index' (duration: 228.079823ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:38:50.817219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.519081ms","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-05-12T16:38:50.817258Z","caller":"traceutil/trace.go:171","msg":"trace[1156255688] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2824; }","duration":"354.610863ms","start":"2026-05-12T16:38:50.462637Z","end":"2026-05-12T16:38:50.817248Z","steps":["trace[1156255688] 'agreement among raft nodes before linearized reading' (duration: 354.46837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:50.817313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:50.462622Z","time spent":"354.683415ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-12T16:38:50.817398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.389883ms","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-05-12T16:38:50.817515Z","caller":"traceutil/trace.go:171","msg":"trace[505419492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2824; }","duration":"349.553255ms","start":"2026-05-12T16:38:50.467934Z","end":"2026-05-12T16:38:50.817487Z","steps":["trace[505419492] 'agreement among raft nodes before linearized reading' (duration: 349.218258ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:38:50.817538Z","caller":"traceutil/trace.go:171","msg":"trace[34025302] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"357.839891ms","start":"2026-05-12T16:38:50.459684Z","end":"2026-05-12T16:38:50.817524Z","steps":["trace[34025302] 'process raft request' (duration: 356.535953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:50.817574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:50.467907Z","time spent":"349.657978ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T16:38:50.817637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:50.459673Z","time spent":"357.889622ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:38:50.817853Z","caller":"traceutil/trace.go:171","msg":"trace[1629503520] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"356.973672ms","start":"2026-05-12T16:38:50.46087Z","end":"2026-05-12T16:38:50.817844Z","steps":["trace[1629503520] 'process raft request' (duration: 355.670975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:38:50.817902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:38:50.460831Z","time spent":"357.045544ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:41:27.604636Z","caller":"traceutil/trace.go:171","msg":"trace[1981773409] transaction","detail":"{read_only:false; response_revision:3847; number_of_response:1; }","duration":"160.767102ms","start":"2026-05-12T16:41:27.443815Z","end":"2026-05-12T16:41:27.604582Z","steps":["trace[1981773409] 'process raft request' (duration: 160.414425ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:27.732962Z","caller":"traceutil/trace.go:171","msg":"trace[117913272] transaction","detail":"{read_only:false; response_revision:3848; number_of_response:1; }","duration":"122.923037ms","start":"2026-05-12T16:41:27.610005Z","end":"2026-05-12T16:41:27.732928Z","steps":["trace[117913272] 'process raft request' (duration: 106.575012ms)","trace[117913272] 'compare' (duration: 16.12957ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:41:27.733121Z","caller":"traceutil/trace.go:171","msg":"trace[1616586871] linearizableReadLoop","detail":"{readStateIndex:3989; appliedIndex:3987; }","duration":"100.171609ms","start":"2026-05-12T16:41:27.6329Z","end":"2026-05-12T16:41:27.733071Z","steps":["trace[1616586871] 'read index received' (duration: 83.670951ms)","trace[1616586871] 'applied index is now lower than readState.Index' (duration: 16.498317ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:41:27.733179Z","caller":"traceutil/trace.go:171","msg":"trace[1310304593] transaction","detail":"{read_only:false; response_revision:3849; number_of_response:1; }","duration":"120.000025ms","start":"2026-05-12T16:41:27.613142Z","end":"2026-05-12T16:41:27.733142Z","steps":["trace[1310304593] 'process raft request' (duration: 119.734269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:41:27.733521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.587717ms","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-05-12T16:41:27.733579Z","caller":"traceutil/trace.go:171","msg":"trace[1458534942] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3849; }","duration":"100.70158ms","start":"2026-05-12T16:41:27.632858Z","end":"2026-05-12T16:41:27.73356Z","steps":["trace[1458534942] 'agreement among raft nodes before linearized reading' (duration: 100.433794ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:42:49.356982Z","caller":"traceutil/trace.go:171","msg":"trace[1702170064] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:5001; }","duration":"103.522472ms","start":"2026-05-12T16:42:49.25344Z","end":"2026-05-12T16:42:49.356962Z","steps":["trace[1702170064] 'read index received' (duration: 103.337448ms)","trace[1702170064] 'applied index is now lower than readState.Index' (duration: 184.354µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:42:49.357286Z","caller":"traceutil/trace.go:171","msg":"trace[645635940] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"108.216131ms","start":"2026-05-12T16:42:49.249059Z","end":"2026-05-12T16:42:49.357275Z","steps":["trace[645635940] 'process raft request' (duration: 107.769182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:42:49.35759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.138814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96591bf0-4bd0-48f7-be83-e6aa1e50a0ab\" ","response":"range_response_count:1 size:3772"} {"level":"info","ts":"2026-05-12T16:42:49.357788Z","caller":"traceutil/trace.go:171","msg":"trace[2077990785] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-96591bf0-4bd0-48f7-be83-e6aa1e50a0ab; range_end:; response_count:1; response_revision:4825; }","duration":"104.220046ms","start":"2026-05-12T16:42:49.253397Z","end":"2026-05-12T16:42:49.357617Z","steps":["trace[2077990785] 'agreement among raft nodes before linearized reading' (duration: 104.098374ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:42:54.796137Z","caller":"traceutil/trace.go:171","msg":"trace[1984660409] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"232.981171ms","start":"2026-05-12T16:42:54.563134Z","end":"2026-05-12T16:42:54.796115Z","steps":["trace[1984660409] 'process raft request' (duration: 232.855449ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:42:54.830206Z","caller":"traceutil/trace.go:171","msg":"trace[1003963788] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"216.900431ms","start":"2026-05-12T16:42:54.613288Z","end":"2026-05-12T16:42:54.830189Z","steps":["trace[1003963788] 'process raft request' (duration: 216.616015ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:42:55.142641Z","caller":"traceutil/trace.go:171","msg":"trace[786552954] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"128.444817ms","start":"2026-05-12T16:42:55.014156Z","end":"2026-05-12T16:42:55.142601Z","steps":["trace[786552954] 'process raft request' (duration: 116.991625ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:43:06.809141Z","caller":"traceutil/trace.go:171","msg":"trace[141101937] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"229.696631ms","start":"2026-05-12T16:43:06.579428Z","end":"2026-05-12T16:43:06.809125Z","steps":["trace[141101937] 'process raft request' (duration: 225.144385ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:43:17.688697Z","caller":"traceutil/trace.go:171","msg":"trace[30672012] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"158.414619ms","start":"2026-05-12T16:43:17.530245Z","end":"2026-05-12T16:43:17.68866Z","steps":["trace[30672012] 'process raft request' (duration: 158.117122ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:43:17.69061Z","caller":"traceutil/trace.go:171","msg":"trace[882287219] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"102.54015ms","start":"2026-05-12T16:43:17.588049Z","end":"2026-05-12T16:43:17.690589Z","steps":["trace[882287219] 'process raft request' (duration: 102.377067ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:44:27.969494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3026} {"level":"info","ts":"2026-05-12T16:44:28.057592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3026,"took":"86.919223ms","hash":3816525087,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T16:44:28.057655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3816525087,"revision":3026,"compact-revision":-1} {"level":"info","ts":"2026-05-12T16:49:17.93512Z","caller":"traceutil/trace.go:171","msg":"trace[34884651] linearizableReadLoop","detail":"{readStateIndex:8541; appliedIndex:8540; }","duration":"159.333858ms","start":"2026-05-12T16:49:17.775761Z","end":"2026-05-12T16:49:17.935095Z","steps":["trace[34884651] 'read index received' (duration: 159.176334ms)","trace[34884651] 'applied index is now lower than readState.Index' (duration: 156.674µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:49:17.935269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.495311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:49:17.935295Z","caller":"traceutil/trace.go:171","msg":"trace[62747972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8255; }","duration":"159.539842ms","start":"2026-05-12T16:49:17.775749Z","end":"2026-05-12T16:49:17.935289Z","steps":["trace[62747972] 'agreement among raft nodes before linearized reading' (duration: 159.46804ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:49:17.935818Z","caller":"traceutil/trace.go:171","msg":"trace[386858710] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"216.272194ms","start":"2026-05-12T16:49:17.718885Z","end":"2026-05-12T16:49:17.935157Z","steps":["trace[386858710] 'process raft request' (duration: 216.041579ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:49:27.976229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6110} {"level":"info","ts":"2026-05-12T16:49:28.077817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6110,"took":"98.912327ms","hash":3009734369,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":23683072,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-12T16:49:28.077901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3009734369,"revision":6110,"compact-revision":3026} {"level":"info","ts":"2026-05-12T16:49:30.852433Z","caller":"traceutil/trace.go:171","msg":"trace[1578326655] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"121.781521ms","start":"2026-05-12T16:49:30.730613Z","end":"2026-05-12T16:49:30.852395Z","steps":["trace[1578326655] 'process raft request' (duration: 121.545696ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:49:59.423699Z","caller":"traceutil/trace.go:171","msg":"trace[1700612659] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"245.702738ms","start":"2026-05-12T16:49:59.177965Z","end":"2026-05-12T16:49:59.423668Z","steps":["trace[1700612659] 'process raft request' (duration: 245.531634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:49:59.642466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.183028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:49:59.642607Z","caller":"traceutil/trace.go:171","msg":"trace[475849818] linearizableReadLoop","detail":"{readStateIndex:8829; appliedIndex:8827; }","duration":"357.17789ms","start":"2026-05-12T16:49:59.285409Z","end":"2026-05-12T16:49:59.642587Z","steps":["trace[475849818] 'read index received' (duration: 138.040325ms)","trace[475849818] 'applied index is now lower than readState.Index' (duration: 219.136285ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:49:59.642713Z","caller":"traceutil/trace.go:171","msg":"trace[1929864122] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"359.607372ms","start":"2026-05-12T16:49:59.283094Z","end":"2026-05-12T16:49:59.642701Z","steps":["trace[1929864122] 'process raft request' (duration: 221.093937ms)","trace[1929864122] 'compare' (duration: 138.025145ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:49:59.64278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:49:59.283062Z","time spent":"359.686533ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:49:59.64321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.766684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-12T16:49:59.643307Z","caller":"traceutil/trace.go:171","msg":"trace[1126084325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8531; }","duration":"357.923896ms","start":"2026-05-12T16:49:59.285362Z","end":"2026-05-12T16:49:59.643286Z","steps":["trace[1126084325] 'agreement among raft nodes before linearized reading' (duration: 357.562988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:49:59.643357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:49:59.285342Z","time spent":"358.006559ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T16:50:01.784374Z","caller":"traceutil/trace.go:171","msg":"trace[1607797639] linearizableReadLoop","detail":"{readStateIndex:8840; appliedIndex:8838; }","duration":"130.65468ms","start":"2026-05-12T16:50:01.653681Z","end":"2026-05-12T16:50:01.784335Z","steps":["trace[1607797639] 'read index received' (duration: 1.38541ms)","trace[1607797639] 'applied index is now lower than readState.Index' (duration: 129.26787ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:50:01.784413Z","caller":"traceutil/trace.go:171","msg":"trace[591979853] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"133.732225ms","start":"2026-05-12T16:50:01.65063Z","end":"2026-05-12T16:50:01.784362Z","steps":["trace[591979853] 'process raft request' (duration: 60.344779ms)","trace[591979853] 'compare' (duration: 73.190812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:50:01.784672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.966127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-12T16:50:01.784732Z","caller":"traceutil/trace.go:171","msg":"trace[1324562627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8541; }","duration":"131.073358ms","start":"2026-05-12T16:50:01.653644Z","end":"2026-05-12T16:50:01.784717Z","steps":["trace[1324562627] 'agreement among raft nodes before linearized reading' (duration: 130.836863ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:50:12.048025Z","caller":"traceutil/trace.go:171","msg":"trace[964188279] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"144.721448ms","start":"2026-05-12T16:50:11.903247Z","end":"2026-05-12T16:50:12.047968Z","steps":["trace[964188279] 'process raft request' (duration: 144.476723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:50:12.048366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.039132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-05-12T16:50:12.048527Z","caller":"traceutil/trace.go:171","msg":"trace[250061804] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:8631; }","duration":"142.417729ms","start":"2026-05-12T16:50:11.906073Z","end":"2026-05-12T16:50:12.048491Z","steps":["trace[250061804] 'agreement among raft nodes before linearized reading' (duration: 141.955879ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:50:12.047963Z","caller":"traceutil/trace.go:171","msg":"trace[249153848] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8934; }","duration":"141.782005ms","start":"2026-05-12T16:50:11.906123Z","end":"2026-05-12T16:50:12.047906Z","steps":["trace[249153848] 'read index received' (duration: 141.446128ms)","trace[249153848] 'applied index is now lower than readState.Index' (duration: 334.477µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:50:12.049453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.9596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-05-12T16:50:12.049496Z","caller":"traceutil/trace.go:171","msg":"trace[916790765] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8631; }","duration":"143.076353ms","start":"2026-05-12T16:50:11.906404Z","end":"2026-05-12T16:50:12.04948Z","steps":["trace[916790765] 'agreement among raft nodes before linearized reading' (duration: 142.844409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:50:12.049734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.247746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pvjfs\" ","response":"range_response_count:1 size:4631"} {"level":"info","ts":"2026-05-12T16:50:12.049792Z","caller":"traceutil/trace.go:171","msg":"trace[1675028942] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pvjfs; range_end:; response_count:1; response_revision:8631; }","duration":"143.328648ms","start":"2026-05-12T16:50:11.906447Z","end":"2026-05-12T16:50:12.049775Z","steps":["trace[1675028942] 'agreement among raft nodes before linearized reading' (duration: 143.188056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:50:12.049752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.750436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-12T16:50:12.049852Z","caller":"traceutil/trace.go:171","msg":"trace[1617008770] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8631; }","duration":"142.887629ms","start":"2026-05-12T16:50:11.906944Z","end":"2026-05-12T16:50:12.049832Z","steps":["trace[1617008770] 'agreement among raft nodes before linearized reading' (duration: 142.617213ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:50:12.675081Z","caller":"traceutil/trace.go:171","msg":"trace[1102402751] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"183.768585ms","start":"2026-05-12T16:50:12.491275Z","end":"2026-05-12T16:50:12.675044Z","steps":["trace[1102402751] 'process raft request' (duration: 183.533729ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:50:12.700737Z","caller":"traceutil/trace.go:171","msg":"trace[134732602] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"157.11697ms","start":"2026-05-12T16:50:12.543479Z","end":"2026-05-12T16:50:12.700596Z","steps":["trace[134732602] 'process raft request' (duration: 156.992318ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:50:12.838795Z","caller":"traceutil/trace.go:171","msg":"trace[461712686] linearizableReadLoop","detail":"{readStateIndex:8945; appliedIndex:8943; }","duration":"130.630489ms","start":"2026-05-12T16:50:12.708132Z","end":"2026-05-12T16:50:12.838763Z","steps":["trace[461712686] 'read index received' (duration: 12.181329ms)","trace[461712686] 'applied index is now lower than readState.Index' (duration: 118.44787ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:50:12.839007Z","caller":"traceutil/trace.go:171","msg":"trace[852090821] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"132.09373ms","start":"2026-05-12T16:50:12.706844Z","end":"2026-05-12T16:50:12.838938Z","steps":["trace[852090821] 'process raft request' (duration: 128.508084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:50:12.839182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.027127ms","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-05-12T16:50:12.839249Z","caller":"traceutil/trace.go:171","msg":"trace[1875298289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8640; }","duration":"131.15702ms","start":"2026-05-12T16:50:12.708075Z","end":"2026-05-12T16:50:12.839232Z","steps":["trace[1875298289] 'agreement among raft nodes before linearized reading' (duration: 130.884414ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:50:13.915778Z","caller":"traceutil/trace.go:171","msg":"trace[1636591833] linearizableReadLoop","detail":"{readStateIndex:8956; appliedIndex:8955; }","duration":"139.68717ms","start":"2026-05-12T16:50:13.776057Z","end":"2026-05-12T16:50:13.915745Z","steps":["trace[1636591833] 'read index received' (duration: 63.425444ms)","trace[1636591833] 'applied index is now lower than readState.Index' (duration: 76.260856ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:50:13.915961Z","caller":"traceutil/trace.go:171","msg":"trace[210936710] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"146.045285ms","start":"2026-05-12T16:50:13.769864Z","end":"2026-05-12T16:50:13.91591Z","steps":["trace[210936710] 'process raft request' (duration: 69.632356ms)","trace[210936710] 'compare' (duration: 75.96943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:50:13.916015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.943856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:50:13.916282Z","caller":"traceutil/trace.go:171","msg":"trace[712203048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8651; }","duration":"140.229782ms","start":"2026-05-12T16:50:13.77601Z","end":"2026-05-12T16:50:13.91624Z","steps":["trace[712203048] 'agreement among raft nodes before linearized reading' (duration: 139.926445ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:50:57.083026Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002491.wal"} {"level":"info","ts":"2026-05-12T16:51:29.624629Z","caller":"traceutil/trace.go:171","msg":"trace[334294850] transaction","detail":"{read_only:false; response_revision:9394; number_of_response:1; }","duration":"250.729664ms","start":"2026-05-12T16:51:29.373864Z","end":"2026-05-12T16:51:29.624594Z","steps":["trace[334294850] 'process raft request' (duration: 240.761923ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:51:29.631716Z","caller":"traceutil/trace.go:171","msg":"trace[1341271725] linearizableReadLoop","detail":"{readStateIndex:9721; appliedIndex:9719; }","duration":"145.26788ms","start":"2026-05-12T16:51:29.486411Z","end":"2026-05-12T16:51:29.631679Z","steps":["trace[1341271725] 'read index received' (duration: 128.320931ms)","trace[1341271725] 'applied index is now lower than readState.Index' (duration: 16.945469ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:51:29.631885Z","caller":"traceutil/trace.go:171","msg":"trace[1692962471] transaction","detail":"{read_only:false; response_revision:9395; number_of_response:1; }","duration":"231.864974ms","start":"2026-05-12T16:51:29.399993Z","end":"2026-05-12T16:51:29.631858Z","steps":["trace[1692962471] 'process raft request' (duration: 231.491857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:51:29.632117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.683588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-05-12T16:51:29.632095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.954538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:51:29.632299Z","caller":"traceutil/trace.go:171","msg":"trace[211658469] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:9395; }","duration":"133.222784ms","start":"2026-05-12T16:51:29.49905Z","end":"2026-05-12T16:51:29.632273Z","steps":["trace[211658469] 'agreement among raft nodes before linearized reading' (duration: 132.957878ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:51:29.632167Z","caller":"traceutil/trace.go:171","msg":"trace[2063538029] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:9395; }","duration":"145.815271ms","start":"2026-05-12T16:51:29.486339Z","end":"2026-05-12T16:51:29.632154Z","steps":["trace[2063538029] 'agreement among raft nodes before linearized reading' (duration: 145.593966ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:52:06.974568Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cf904ec39d3e333","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T16:52:06.987062Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-12T16:52:06.98719Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-12T16:54:27.982467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8306} {"level":"info","ts":"2026-05-12T16:54:28.059172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8306,"took":"74.258117ms","hash":2387012634,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":25706496,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-12T16:54:28.059259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2387012634,"revision":8306,"compact-revision":6110} {"level":"info","ts":"2026-05-12T16:54:54.595196Z","caller":"traceutil/trace.go:171","msg":"trace[1318404649] transaction","detail":"{read_only:false; response_revision:11139; number_of_response:1; }","duration":"119.205681ms","start":"2026-05-12T16:54:54.475948Z","end":"2026-05-12T16:54:54.595153Z","steps":["trace[1318404649] 'process raft request' (duration: 53.085027ms)","trace[1318404649] 'compare' (duration: 65.793787ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:55:13.016301Z","caller":"traceutil/trace.go:171","msg":"trace[703424178] transaction","detail":"{read_only:false; response_revision:11245; number_of_response:1; }","duration":"101.509115ms","start":"2026-05-12T16:55:12.914756Z","end":"2026-05-12T16:55:13.016266Z","steps":["trace[703424178] 'process raft request' (duration: 101.281261ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:59:27.989231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10797} {"level":"info","ts":"2026-05-12T16:59:28.069686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10797,"took":"77.575316ms","hash":2171472729,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":27521024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-12T16:59:28.069847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2171472729,"revision":10797,"compact-revision":8306} {"level":"info","ts":"2026-05-12T17:01:07.449828Z","caller":"traceutil/trace.go:171","msg":"trace[1039653530] transaction","detail":"{read_only:false; response_revision:13656; number_of_response:1; }","duration":"105.17436ms","start":"2026-05-12T17:01:07.344622Z","end":"2026-05-12T17:01:07.449797Z","steps":["trace[1039653530] 'process raft request' (duration: 104.942365ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:01:10.61905Z","caller":"traceutil/trace.go:171","msg":"trace[1057122229] transaction","detail":"{read_only:false; response_revision:13672; number_of_response:1; }","duration":"112.734561ms","start":"2026-05-12T17:01:10.506269Z","end":"2026-05-12T17:01:10.619003Z","steps":["trace[1057122229] 'process raft request' (duration: 112.481246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:01:31.784473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.107921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-05-12T17:01:31.784516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.968085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-12T17:01:31.784567Z","caller":"traceutil/trace.go:171","msg":"trace[1454146994] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13787; }","duration":"124.268434ms","start":"2026-05-12T17:01:31.660276Z","end":"2026-05-12T17:01:31.784544Z","steps":["trace[1454146994] 'range keys from in-memory index tree' (duration: 123.862816ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:01:31.784616Z","caller":"traceutil/trace.go:171","msg":"trace[678037817] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:13787; }","duration":"139.132429ms","start":"2026-05-12T17:01:31.64546Z","end":"2026-05-12T17:01:31.784592Z","steps":["trace[678037817] 'range keys from in-memory index tree' (duration: 138.638629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:01:31.784828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.146488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-12T17:01:31.784856Z","caller":"traceutil/trace.go:171","msg":"trace[287491130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13787; }","duration":"122.21092ms","start":"2026-05-12T17:01:31.662636Z","end":"2026-05-12T17:01:31.784847Z","steps":["trace[287491130] 'range keys from in-memory index tree' (duration: 122.025847ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:33.896675Z","caller":"traceutil/trace.go:171","msg":"trace[34909898] linearizableReadLoop","detail":"{readStateIndex:14805; appliedIndex:14804; }","duration":"122.15634ms","start":"2026-05-12T17:02:33.774467Z","end":"2026-05-12T17:02:33.896623Z","steps":["trace[34909898] 'read index received' (duration: 119.231038ms)","trace[34909898] 'applied index is now lower than readState.Index' (duration: 2.922872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:02:33.896843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.343523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T17:02:33.897117Z","caller":"traceutil/trace.go:171","msg":"trace[2017709309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14313; }","duration":"122.507257ms","start":"2026-05-12T17:02:33.774412Z","end":"2026-05-12T17:02:33.896919Z","steps":["trace[2017709309] 'agreement among raft nodes before linearized reading' (duration: 122.347004ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:33.896717Z","caller":"traceutil/trace.go:171","msg":"trace[795230872] transaction","detail":"{read_only:false; response_revision:14313; number_of_response:1; }","duration":"128.048105ms","start":"2026-05-12T17:02:33.768582Z","end":"2026-05-12T17:02:33.89663Z","steps":["trace[795230872] 'process raft request' (duration: 125.088092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:34.22594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.988511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b7944af-847f-4640-9bf7-b5455b2a83ef\" ","response":"range_response_count:1 size:3635"} {"level":"info","ts":"2026-05-12T17:02:34.226058Z","caller":"traceutil/trace.go:171","msg":"trace[1598901115] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b7944af-847f-4640-9bf7-b5455b2a83ef; range_end:; response_count:1; response_revision:14314; }","duration":"134.177765ms","start":"2026-05-12T17:02:34.091851Z","end":"2026-05-12T17:02:34.226028Z","steps":["trace[1598901115] 'range keys from in-memory index tree' (duration: 133.711545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:34.22621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.741215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T17:02:34.226331Z","caller":"traceutil/trace.go:171","msg":"trace[1323019586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14314; }","duration":"108.925139ms","start":"2026-05-12T17:02:34.117362Z","end":"2026-05-12T17:02:34.226287Z","steps":["trace[1323019586] 'range keys from in-memory index tree' (duration: 108.422589ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:03:28.944391Z","caller":"traceutil/trace.go:171","msg":"trace[1903393887] transaction","detail":"{read_only:false; response_revision:14959; number_of_response:1; }","duration":"139.094249ms","start":"2026-05-12T17:03:28.805251Z","end":"2026-05-12T17:03:28.944345Z","steps":["trace[1903393887] 'process raft request' (duration: 83.655165ms)","trace[1903393887] 'compare' (duration: 55.111357ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:03:38.971364Z","caller":"traceutil/trace.go:171","msg":"trace[851771177] linearizableReadLoop","detail":"{readStateIndex:15527; appliedIndex:15526; }","duration":"159.305557ms","start":"2026-05-12T17:03:38.812029Z","end":"2026-05-12T17:03:38.971335Z","steps":["trace[851771177] 'read index received' (duration: 158.982971ms)","trace[851771177] 'applied index is now lower than readState.Index' (duration: 321.406µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:03:38.971683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.622054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T17:03:38.971737Z","caller":"traceutil/trace.go:171","msg":"trace[1000497299] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:15015; }","duration":"159.711936ms","start":"2026-05-12T17:03:38.812011Z","end":"2026-05-12T17:03:38.971723Z","steps":["trace[1000497299] 'agreement among raft nodes before linearized reading' (duration: 159.457011ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:03:38.972204Z","caller":"traceutil/trace.go:171","msg":"trace[1911849146] transaction","detail":"{read_only:false; response_revision:15015; number_of_response:1; }","duration":"192.112492ms","start":"2026-05-12T17:03:38.780079Z","end":"2026-05-12T17:03:38.972192Z","steps":["trace[1911849146] 'process raft request' (duration: 191.022579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:03:39.409127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.112149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T17:03:39.409268Z","caller":"traceutil/trace.go:171","msg":"trace[1087564627] linearizableReadLoop","detail":"{readStateIndex:15528; appliedIndex:15527; }","duration":"352.560925ms","start":"2026-05-12T17:03:39.056681Z","end":"2026-05-12T17:03:39.409242Z","steps":["trace[1087564627] 'read index received' (duration: 205.14052ms)","trace[1087564627] 'applied index is now lower than readState.Index' (duration: 147.419305ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:03:39.409316Z","caller":"traceutil/trace.go:171","msg":"trace[2101629061] transaction","detail":"{read_only:false; response_revision:15016; number_of_response:1; }","duration":"418.383331ms","start":"2026-05-12T17:03:38.990867Z","end":"2026-05-12T17:03:39.40925Z","steps":["trace[2101629061] 'process raft request' (duration: 271.047667ms)","trace[2101629061] 'compare' (duration: 146.983036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:03:39.409506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:03:38.990839Z","time spent":"418.559824ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:03:39.409525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.083024ms","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-05-12T17:03:39.409564Z","caller":"traceutil/trace.go:171","msg":"trace[1640700718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15016; }","duration":"227.168546ms","start":"2026-05-12T17:03:39.182381Z","end":"2026-05-12T17:03:39.409549Z","steps":["trace[1640700718] 'agreement among raft nodes before linearized reading' (duration: 227.000172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:03:39.409599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.897272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cc7rh\" ","response":"range_response_count:1 size:9794"} {"level":"warn","ts":"2026-05-12T17:03:39.409661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.757945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T17:03:39.409728Z","caller":"traceutil/trace.go:171","msg":"trace[1032258774] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cc7rh; range_end:; response_count:1; response_revision:15016; }","duration":"353.090306ms","start":"2026-05-12T17:03:39.056609Z","end":"2026-05-12T17:03:39.409699Z","steps":["trace[1032258774] 'agreement among raft nodes before linearized reading' (duration: 352.748869ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:03:39.409759Z","caller":"traceutil/trace.go:171","msg":"trace[790346165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15016; }","duration":"158.887498ms","start":"2026-05-12T17:03:39.250848Z","end":"2026-05-12T17:03:39.409735Z","steps":["trace[790346165] 'agreement among raft nodes before linearized reading' (duration: 158.655163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:03:39.409794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:03:39.056579Z","time spent":"353.202409ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9816,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-cc7rh\" "} {"level":"info","ts":"2026-05-12T17:04:27.996872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12906} {"level":"info","ts":"2026-05-12T17:04:28.079596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12906,"took":"79.203209ms","hash":4256698730,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":29769728,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T17:04:28.079668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4256698730,"revision":12906,"compact-revision":10797} {"level":"info","ts":"2026-05-12T17:09:28.003348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15266} {"level":"info","ts":"2026-05-12T17:09:28.100723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15266,"took":"93.435605ms","hash":751304527,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":31891456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T17:09:28.100816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":751304527,"revision":15266,"compact-revision":12906} {"level":"warn","ts":"2026-05-12T17:09:58.867316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.616766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T17:09:58.867801Z","caller":"traceutil/trace.go:171","msg":"trace[154892977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17799; }","duration":"129.088666ms","start":"2026-05-12T17:09:58.738667Z","end":"2026-05-12T17:09:58.867756Z","steps":["trace[154892977] 'range keys from in-memory index tree' (duration: 128.439443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:09:59.427539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.676512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:09:59.427726Z","caller":"traceutil/trace.go:171","msg":"trace[1471566866] linearizableReadLoop","detail":"{readStateIndex:18404; appliedIndex:18403; }","duration":"200.266167ms","start":"2026-05-12T17:09:59.227431Z","end":"2026-05-12T17:09:59.427697Z","steps":["trace[1471566866] 'read index received' (duration: 56.561µs)","trace[1471566866] 'applied index is now lower than readState.Index' (duration: 200.206416ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:09:59.427919Z","caller":"traceutil/trace.go:171","msg":"trace[153053270] transaction","detail":"{read_only:false; response_revision:17801; number_of_response:1; }","duration":"453.863963ms","start":"2026-05-12T17:09:58.974036Z","end":"2026-05-12T17:09:59.4279Z","steps":["trace[153053270] 'process raft request' (duration: 196.708521ms)","trace[153053270] 'compare' (duration: 256.323193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:09:59.428016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:09:58.973984Z","time spent":"453.978665ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:09:59.428316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.024517ms","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:6"} {"level":"info","ts":"2026-05-12T17:09:59.428429Z","caller":"traceutil/trace.go:171","msg":"trace[1308063913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:17801; }","duration":"197.213661ms","start":"2026-05-12T17:09:59.231186Z","end":"2026-05-12T17:09:59.428399Z","steps":["trace[1308063913] 'agreement among raft nodes before linearized reading' (duration: 196.976656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:09:59.428716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.270239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-05-12T17:09:59.428949Z","caller":"traceutil/trace.go:171","msg":"trace[757282545] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17801; }","duration":"201.553835ms","start":"2026-05-12T17:09:59.227364Z","end":"2026-05-12T17:09:59.428918Z","steps":["trace[757282545] 'agreement among raft nodes before linearized reading' (duration: 201.107416ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:13:06.820326Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cf904ec39d3e333","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:13:06.827863Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-12T17:13:06.828039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-12T17:14:28.010994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17600} {"level":"info","ts":"2026-05-12T17:14:28.114588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17600,"took":"99.083279ms","hash":44220123,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":34525184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-12T17:14:28.114676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":44220123,"revision":17600,"compact-revision":15266} {"level":"info","ts":"2026-05-12T17:15:20.473817Z","caller":"traceutil/trace.go:171","msg":"trace[727209396] transaction","detail":"{read_only:false; response_revision:20350; number_of_response:1; }","duration":"108.681085ms","start":"2026-05-12T17:15:20.365088Z","end":"2026-05-12T17:15:20.473769Z","steps":["trace[727209396] 'process raft request' (duration: 107.846237ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:15:41.821845Z","caller":"traceutil/trace.go:171","msg":"trace[1033968919] linearizableReadLoop","detail":"{readStateIndex:21144; appliedIndex:21143; }","duration":"300.372965ms","start":"2026-05-12T17:15:41.521443Z","end":"2026-05-12T17:15:41.821816Z","steps":["trace[1033968919] 'read index received' (duration: 300.129549ms)","trace[1033968919] 'applied index is now lower than readState.Index' (duration: 242.326µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:15:41.821911Z","caller":"traceutil/trace.go:171","msg":"trace[1800061422] transaction","detail":"{read_only:false; response_revision:20454; number_of_response:1; }","duration":"307.19728ms","start":"2026-05-12T17:15:41.514669Z","end":"2026-05-12T17:15:41.821866Z","steps":["trace[1800061422] 'process raft request' (duration: 306.974664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:15:41.822186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.730822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T17:15:41.822215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.463996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T17:15:41.82223Z","caller":"traceutil/trace.go:171","msg":"trace[336195559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20454; }","duration":"300.831165ms","start":"2026-05-12T17:15:41.521386Z","end":"2026-05-12T17:15:41.822218Z","steps":["trace[336195559] 'agreement among raft nodes before linearized reading' (duration: 300.576279ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:15:41.82225Z","caller":"traceutil/trace.go:171","msg":"trace[695691759] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:20454; }","duration":"120.539178ms","start":"2026-05-12T17:15:41.701703Z","end":"2026-05-12T17:15:41.822242Z","steps":["trace[695691759] 'agreement among raft nodes before linearized reading' (duration: 120.444586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:15:41.82223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:15:41.514638Z","time spent":"307.479336ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:15:41.822215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.610128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T17:15:41.822268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:15:41.521352Z","time spent":"300.907756ms","remote":"127.0.0.1:48876","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-05-12T17:15:41.822318Z","caller":"traceutil/trace.go:171","msg":"trace[930365914] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20454; }","duration":"285.804462ms","start":"2026-05-12T17:15:41.536489Z","end":"2026-05-12T17:15:41.822294Z","steps":["trace[930365914] 'agreement among raft nodes before linearized reading' (duration: 285.521256ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:15:41.987626Z","caller":"traceutil/trace.go:171","msg":"trace[1030503079] linearizableReadLoop","detail":"{readStateIndex:21145; appliedIndex:21144; }","duration":"160.920512ms","start":"2026-05-12T17:15:41.826615Z","end":"2026-05-12T17:15:41.987536Z","steps":["trace[1030503079] 'read index received' (duration: 127.280143ms)","trace[1030503079] 'applied index is now lower than readState.Index' (duration: 33.637459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:15:41.987737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.104526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:15:41.987828Z","caller":"traceutil/trace.go:171","msg":"trace[1704569178] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20454; }","duration":"161.217009ms","start":"2026-05-12T17:15:41.826587Z","end":"2026-05-12T17:15:41.987804Z","steps":["trace[1704569178] 'agreement among raft nodes before linearized reading' (duration: 161.068916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:15:42.257631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.398935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:15:42.257923Z","caller":"traceutil/trace.go:171","msg":"trace[873131270] transaction","detail":"{read_only:false; response_revision:20455; number_of_response:1; }","duration":"425.624372ms","start":"2026-05-12T17:15:41.832249Z","end":"2026-05-12T17:15:42.257874Z","steps":["trace[873131270] 'process raft request' (duration: 268.774478ms)","trace[873131270] 'compare' (duration: 156.17703ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:15:42.258027Z","caller":"traceutil/trace.go:171","msg":"trace[615392012] transaction","detail":"{read_only:false; response_revision:20456; number_of_response:1; }","duration":"425.674314ms","start":"2026-05-12T17:15:41.832319Z","end":"2026-05-12T17:15:42.257993Z","steps":["trace[615392012] 'process raft request' (duration: 425.448709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:15:42.258131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:15:41.832232Z","time spent":"425.790297ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:15:42.258143Z","caller":"traceutil/trace.go:171","msg":"trace[12928163] linearizableReadLoop","detail":"{readStateIndex:21149; appliedIndex:21145; }","duration":"266.579832ms","start":"2026-05-12T17:15:41.991538Z","end":"2026-05-12T17:15:42.258117Z","steps":["trace[12928163] 'read index received' (duration: 109.85636ms)","trace[12928163] 'applied index is now lower than readState.Index' (duration: 156.721651ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:15:42.258192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:15:41.832312Z","time spent":"425.791946ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:15:42.258398Z","caller":"traceutil/trace.go:171","msg":"trace[400066753] transaction","detail":"{read_only:false; response_revision:20459; number_of_response:1; }","duration":"191.902954ms","start":"2026-05-12T17:15:42.06648Z","end":"2026-05-12T17:15:42.258383Z","steps":["trace[400066753] 'process raft request' (duration: 191.573177ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:15:42.258454Z","caller":"traceutil/trace.go:171","msg":"trace[1726993321] transaction","detail":"{read_only:false; response_revision:20457; number_of_response:1; }","duration":"414.342ms","start":"2026-05-12T17:15:41.844098Z","end":"2026-05-12T17:15:42.25844Z","steps":["trace[1726993321] 'process raft request' (duration: 413.768698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:15:42.258528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:15:41.844082Z","time spent":"414.408913ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:15:42.258542Z","caller":"traceutil/trace.go:171","msg":"trace[1621145753] transaction","detail":"{read_only:false; response_revision:20458; number_of_response:1; }","duration":"268.8485ms","start":"2026-05-12T17:15:41.989657Z","end":"2026-05-12T17:15:42.258506Z","steps":["trace[1621145753] 'process raft request' (duration: 268.316469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:15:42.258769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.216886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-12T17:15:42.258786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:15:42.258833Z","caller":"traceutil/trace.go:171","msg":"trace[819605363] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20459; }","duration":"267.304897ms","start":"2026-05-12T17:15:41.991512Z","end":"2026-05-12T17:15:42.258817Z","steps":["trace[819605363] 'agreement among raft nodes before linearized reading' (duration: 267.00787ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:15:42.258853Z","caller":"traceutil/trace.go:171","msg":"trace[173519068] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:20459; }","duration":"136.0341ms","start":"2026-05-12T17:15:42.1228Z","end":"2026-05-12T17:15:42.258834Z","steps":["trace[173519068] 'agreement among raft nodes before linearized reading' (duration: 135.911957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:15:42.530448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.737315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:15:42.530551Z","caller":"traceutil/trace.go:171","msg":"trace[308617014] transaction","detail":"{read_only:false; response_revision:20460; number_of_response:1; }","duration":"242.997637ms","start":"2026-05-12T17:15:42.287533Z","end":"2026-05-12T17:15:42.530531Z","steps":["trace[308617014] 'process raft request' (duration: 98.095738ms)","trace[308617014] 'compare' (duration: 144.561681ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:15:42.72003Z","caller":"traceutil/trace.go:171","msg":"trace[576461316] linearizableReadLoop","detail":"{readStateIndex:21152; appliedIndex:21151; }","duration":"111.975755ms","start":"2026-05-12T17:15:42.608012Z","end":"2026-05-12T17:15:42.719988Z","steps":["trace[576461316] 'read index received' (duration: 111.77434ms)","trace[576461316] 'applied index is now lower than readState.Index' (duration: 199.785µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:15:42.72021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.170159ms","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:6"} {"level":"info","ts":"2026-05-12T17:15:42.720145Z","caller":"traceutil/trace.go:171","msg":"trace[814080567] transaction","detail":"{read_only:false; response_revision:20461; number_of_response:1; }","duration":"112.358153ms","start":"2026-05-12T17:15:42.607741Z","end":"2026-05-12T17:15:42.720099Z","steps":["trace[814080567] 'process raft request' (duration: 112.019466ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:15:42.720265Z","caller":"traceutil/trace.go:171","msg":"trace[652596080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:20461; }","duration":"112.306472ms","start":"2026-05-12T17:15:42.607941Z","end":"2026-05-12T17:15:42.720248Z","steps":["trace[652596080] 'agreement among raft nodes before linearized reading' (duration: 112.179689ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:15:42.862805Z","caller":"traceutil/trace.go:171","msg":"trace[725359825] transaction","detail":"{read_only:false; response_revision:20462; number_of_response:1; }","duration":"116.988182ms","start":"2026-05-12T17:15:42.745776Z","end":"2026-05-12T17:15:42.862764Z","steps":["trace[725359825] 'process raft request' (duration: 116.072783ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:19:28.016521Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20020} {"level":"info","ts":"2026-05-12T17:19:28.143607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20020,"took":"122.165383ms","hash":647360265,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":40243200,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-05-12T17:19:28.143682Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":647360265,"revision":20020,"compact-revision":17600} {"level":"warn","ts":"2026-05-12T17:19:31.662111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.302739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:19:31.662234Z","caller":"traceutil/trace.go:171","msg":"trace[756384607] linearizableReadLoop","detail":"{readStateIndex:23579; appliedIndex:23578; }","duration":"276.807982ms","start":"2026-05-12T17:19:31.385396Z","end":"2026-05-12T17:19:31.662203Z","steps":["trace[756384607] 'read index received' (duration: 41.651µs)","trace[756384607] 'applied index is now lower than readState.Index' (duration: 276.764621ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:19:31.662293Z","caller":"traceutil/trace.go:171","msg":"trace[190408877] transaction","detail":"{read_only:false; response_revision:22816; number_of_response:1; }","duration":"389.308188ms","start":"2026-05-12T17:19:31.272951Z","end":"2026-05-12T17:19:31.66226Z","steps":["trace[190408877] 'process raft request' (duration: 99.764564ms)","trace[190408877] 'compare' (duration: 288.983722ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:19:31.6624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:19:31.272927Z","time spent":"389.429911ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:19:31.662964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.504577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5023"} {"level":"warn","ts":"2026-05-12T17:19:31.663077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.809414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T17:19:31.663141Z","caller":"traceutil/trace.go:171","msg":"trace[616080965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22816; }","duration":"119.918706ms","start":"2026-05-12T17:19:31.5432Z","end":"2026-05-12T17:19:31.663118Z","steps":["trace[616080965] 'agreement among raft nodes before linearized reading' (duration: 119.736982ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:19:31.663136Z","caller":"traceutil/trace.go:171","msg":"trace[577633116] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:22816; }","duration":"277.729712ms","start":"2026-05-12T17:19:31.385354Z","end":"2026-05-12T17:19:31.663084Z","steps":["trace[577633116] 'agreement among raft nodes before linearized reading' (duration: 277.18716ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:42.269007Z","caller":"traceutil/trace.go:171","msg":"trace[599353621] transaction","detail":"{read_only:false; response_revision:23665; number_of_response:1; }","duration":"141.358374ms","start":"2026-05-12T17:20:42.127616Z","end":"2026-05-12T17:20:42.268975Z","steps":["trace[599353621] 'process raft request' (duration: 64.129072ms)","trace[599353621] 'compare' (duration: 76.587908ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:20:42.26908Z","caller":"traceutil/trace.go:171","msg":"trace[1323557431] transaction","detail":"{read_only:false; response_revision:23666; number_of_response:1; }","duration":"138.35547ms","start":"2026-05-12T17:20:42.130702Z","end":"2026-05-12T17:20:42.269058Z","steps":["trace[1323557431] 'process raft request' (duration: 138.100715ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:42.269037Z","caller":"traceutil/trace.go:171","msg":"trace[677981672] linearizableReadLoop","detail":"{readStateIndex:24449; appliedIndex:24447; }","duration":"139.905203ms","start":"2026-05-12T17:20:42.129086Z","end":"2026-05-12T17:20:42.268991Z","steps":["trace[677981672] 'read index received' (duration: 4.930074ms)","trace[677981672] 'applied index is now lower than readState.Index' (duration: 134.973178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:20:42.269304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.187548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-12T17:20:42.269374Z","caller":"traceutil/trace.go:171","msg":"trace[1873734949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23666; }","duration":"140.27969ms","start":"2026-05-12T17:20:42.129083Z","end":"2026-05-12T17:20:42.269362Z","steps":["trace[1873734949] 'agreement among raft nodes before linearized reading' (duration: 140.042035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:49.029457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.245681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:49.029662Z","caller":"traceutil/trace.go:171","msg":"trace[1933712250] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"243.526219ms","start":"2026-05-12T17:22:48.786118Z","end":"2026-05-12T17:22:49.029644Z","steps":["trace[1933712250] 'process raft request' (duration: 109.045713ms)","trace[1933712250] 'compare' (duration: 133.933505ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:49.103854Z","caller":"traceutil/trace.go:171","msg":"trace[2080205663] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"293.654292ms","start":"2026-05-12T17:22:48.810147Z","end":"2026-05-12T17:22:49.103801Z","steps":["trace[2080205663] 'process raft request' (duration: 293.333415ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:49.103991Z","caller":"traceutil/trace.go:171","msg":"trace[463109190] linearizableReadLoop","detail":"{readStateIndex:25671; appliedIndex:25669; }","duration":"238.766049ms","start":"2026-05-12T17:22:48.864876Z","end":"2026-05-12T17:22:49.103642Z","steps":["trace[463109190] 'read index received' (duration: 30.117515ms)","trace[463109190] 'applied index is now lower than readState.Index' (duration: 208.646954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:49.104389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.478833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-12T17:22:49.104513Z","caller":"traceutil/trace.go:171","msg":"trace[615215887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24854; }","duration":"239.669417ms","start":"2026-05-12T17:22:48.864815Z","end":"2026-05-12T17:22:49.104485Z","steps":["trace[615215887] 'agreement among raft nodes before linearized reading' (duration: 239.2925ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:49.219442Z","caller":"traceutil/trace.go:171","msg":"trace[465236284] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"280.227314ms","start":"2026-05-12T17:22:48.939182Z","end":"2026-05-12T17:22:49.21941Z","steps":["trace[465236284] 'process raft request' (duration: 231.992702ms)","trace[465236284] 'compare' (duration: 47.814383ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:49.219668Z","caller":"traceutil/trace.go:171","msg":"trace[805609629] linearizableReadLoop","detail":"{readStateIndex:25673; appliedIndex:25671; }","duration":"115.547502ms","start":"2026-05-12T17:22:49.104107Z","end":"2026-05-12T17:22:49.219654Z","steps":["trace[805609629] 'read index received' (duration: 67.086816ms)","trace[805609629] 'applied index is now lower than readState.Index' (duration: 48.459316ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:49.219965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.27172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T17:22:49.220011Z","caller":"traceutil/trace.go:171","msg":"trace[112725604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24856; }","duration":"183.384043ms","start":"2026-05-12T17:22:49.036617Z","end":"2026-05-12T17:22:49.220001Z","steps":["trace[112725604] 'agreement among raft nodes before linearized reading' (duration: 183.210779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:49.219967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.083789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-12T17:22:49.220092Z","caller":"traceutil/trace.go:171","msg":"trace[1613919133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24856; }","duration":"149.247932ms","start":"2026-05-12T17:22:49.070823Z","end":"2026-05-12T17:22:49.220071Z","steps":["trace[1613919133] 'agreement among raft nodes before linearized reading' (duration: 148.936756ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:49.220132Z","caller":"traceutil/trace.go:171","msg":"trace[953105471] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"162.850463ms","start":"2026-05-12T17:22:49.057231Z","end":"2026-05-12T17:22:49.220082Z","steps":["trace[953105471] 'process raft request' (duration: 162.059437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:49.220256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.645112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-12T17:22:49.220357Z","caller":"traceutil/trace.go:171","msg":"trace[1308233797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24856; }","duration":"315.767245ms","start":"2026-05-12T17:22:48.904563Z","end":"2026-05-12T17:22:49.22033Z","steps":["trace[1308233797] 'agreement among raft nodes before linearized reading' (duration: 315.481199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:49.220411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:48.904492Z","time spent":"315.906207ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T17:22:49.308511Z","caller":"traceutil/trace.go:171","msg":"trace[1682990773] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"198.855734ms","start":"2026-05-12T17:22:49.10962Z","end":"2026-05-12T17:22:49.308476Z","steps":["trace[1682990773] 'process raft request' (duration: 198.458375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:49.308572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.385259ms","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:6"} {"level":"info","ts":"2026-05-12T17:22:49.308688Z","caller":"traceutil/trace.go:171","msg":"trace[1604224093] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24857; }","duration":"144.532042ms","start":"2026-05-12T17:22:49.164122Z","end":"2026-05-12T17:22:49.308654Z","steps":["trace[1604224093] 'agreement among raft nodes before linearized reading' (duration: 144.288297ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:49.463065Z","caller":"traceutil/trace.go:171","msg":"trace[336022021] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"237.642463ms","start":"2026-05-12T17:22:49.225383Z","end":"2026-05-12T17:22:49.463026Z","steps":["trace[336022021] 'process raft request' (duration: 237.520561ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:49.463095Z","caller":"traceutil/trace.go:171","msg":"trace[2112492784] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"238.108733ms","start":"2026-05-12T17:22:49.224952Z","end":"2026-05-12T17:22:49.46306Z","steps":["trace[2112492784] 'process raft request' (duration: 237.771506ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:49.463231Z","caller":"traceutil/trace.go:171","msg":"trace[2125991010] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"237.655584ms","start":"2026-05-12T17:22:49.225532Z","end":"2026-05-12T17:22:49.463187Z","steps":["trace[2125991010] 'process raft request' (duration: 237.412039ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:53.66436Z","caller":"traceutil/trace.go:171","msg":"trace[1934665386] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"184.203011ms","start":"2026-05-12T17:22:53.480119Z","end":"2026-05-12T17:22:53.664322Z","steps":["trace[1934665386] 'process raft request' (duration: 183.993846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:53.917787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.541107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:53.917965Z","caller":"traceutil/trace.go:171","msg":"trace[1179005600] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"437.461478ms","start":"2026-05-12T17:22:53.480484Z","end":"2026-05-12T17:22:53.917945Z","steps":["trace[1179005600] 'process raft request' (duration: 289.655156ms)","trace[1179005600] 'compare' (duration: 147.21787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:53.918019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:53.480464Z","time spent":"437.529109ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:22:53.918133Z","caller":"traceutil/trace.go:171","msg":"trace[885799081] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"437.456368ms","start":"2026-05-12T17:22:53.48066Z","end":"2026-05-12T17:22:53.918116Z","steps":["trace[885799081] 'process raft request' (duration: 437.228903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:53.918199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:53.480651Z","time spent":"437.511179ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:22:53.918435Z","caller":"traceutil/trace.go:171","msg":"trace[738189162] linearizableReadLoop","detail":"{readStateIndex:25713; appliedIndex:25710; }","duration":"428.816824ms","start":"2026-05-12T17:22:53.489608Z","end":"2026-05-12T17:22:53.918425Z","steps":["trace[738189162] 'read index received' (duration: 174.482194ms)","trace[738189162] 'applied index is now lower than readState.Index' (duration: 254.333289ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:53.918508Z","caller":"traceutil/trace.go:171","msg":"trace[296191193] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"273.53057ms","start":"2026-05-12T17:22:53.644938Z","end":"2026-05-12T17:22:53.918469Z","steps":["trace[296191193] 'process raft request' (duration: 273.121112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:53.918587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.025805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-12T17:22:53.918589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.978557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-12T17:22:53.918608Z","caller":"traceutil/trace.go:171","msg":"trace[209485673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24896; }","duration":"427.074605ms","start":"2026-05-12T17:22:53.491525Z","end":"2026-05-12T17:22:53.9186Z","steps":["trace[209485673] 'agreement among raft nodes before linearized reading' (duration: 426.992603ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:53.918623Z","caller":"traceutil/trace.go:171","msg":"trace[28123963] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24896; }","duration":"429.039937ms","start":"2026-05-12T17:22:53.489572Z","end":"2026-05-12T17:22:53.918612Z","steps":["trace[28123963] 'agreement among raft nodes before linearized reading' (duration: 428.909415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:53.918629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:53.49151Z","time spent":"427.113507ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T17:22:53.918642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:53.489558Z","time spent":"429.079859ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-12T17:22:53.918635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.919355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:53.918729Z","caller":"traceutil/trace.go:171","msg":"trace[390103258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24896; }","duration":"142.078158ms","start":"2026-05-12T17:22:53.776625Z","end":"2026-05-12T17:22:53.918703Z","steps":["trace[390103258] 'agreement among raft nodes before linearized reading' (duration: 141.921125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:54.21296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.940711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:54.213817Z","caller":"traceutil/trace.go:171","msg":"trace[87312983] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"290.839752ms","start":"2026-05-12T17:22:53.922925Z","end":"2026-05-12T17:22:54.213765Z","steps":["trace[87312983] 'process raft request' (duration: 143.98894ms)","trace[87312983] 'compare' (duration: 145.753718ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:54.213873Z","caller":"traceutil/trace.go:171","msg":"trace[186486796] linearizableReadLoop","detail":"{readStateIndex:25715; appliedIndex:25714; }","duration":"290.734649ms","start":"2026-05-12T17:22:53.923085Z","end":"2026-05-12T17:22:54.21382Z","steps":["trace[186486796] 'read index received' (duration: 143.923428ms)","trace[186486796] 'applied index is now lower than readState.Index' (duration: 146.808811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:54.214054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.960664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:54.214145Z","caller":"traceutil/trace.go:171","msg":"trace[123265270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24897; }","duration":"291.144878ms","start":"2026-05-12T17:22:53.922981Z","end":"2026-05-12T17:22:54.214126Z","steps":["trace[123265270] 'agreement among raft nodes before linearized reading' (duration: 290.958975ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:54.2245Z","caller":"traceutil/trace.go:171","msg":"trace[264692097] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"299.353623ms","start":"2026-05-12T17:22:53.925131Z","end":"2026-05-12T17:22:54.224484Z","steps":["trace[264692097] 'process raft request' (duration: 299.253231ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:54.497032Z","caller":"traceutil/trace.go:171","msg":"trace[1120489097] linearizableReadLoop","detail":"{readStateIndex:25717; appliedIndex:25716; }","duration":"282.891562ms","start":"2026-05-12T17:22:54.214112Z","end":"2026-05-12T17:22:54.497003Z","steps":["trace[1120489097] 'read index received' (duration: 10.356662ms)","trace[1120489097] 'applied index is now lower than readState.Index' (duration: 272.53338ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:54.497191Z","caller":"traceutil/trace.go:171","msg":"trace[1639439026] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"385.933176ms","start":"2026-05-12T17:22:54.111218Z","end":"2026-05-12T17:22:54.497151Z","steps":["trace[1639439026] 'process raft request' (duration: 385.530067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:54.497248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.689811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:54.497294Z","caller":"traceutil/trace.go:171","msg":"trace[244418871] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24899; }","duration":"565.784993ms","start":"2026-05-12T17:22:53.931493Z","end":"2026-05-12T17:22:54.497278Z","steps":["trace[244418871] 'agreement among raft nodes before linearized reading' (duration: 565.67492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:54.49735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:53.931469Z","time spent":"565.868315ms","remote":"127.0.0.1:54356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:22:54.497392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:54.111188Z","time spent":"386.1015ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:22:54.704711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.707036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:54.704853Z","caller":"traceutil/trace.go:171","msg":"trace[669415488] linearizableReadLoop","detail":"{readStateIndex:25719; appliedIndex:25717; }","duration":"207.699733ms","start":"2026-05-12T17:22:54.497139Z","end":"2026-05-12T17:22:54.704839Z","steps":["trace[669415488] 'read index received' (duration: 62.767262ms)","trace[669415488] 'applied index is now lower than readState.Index' (duration: 144.931661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:54.704948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.900568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:54.705125Z","caller":"traceutil/trace.go:171","msg":"trace[184445095] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24901; }","duration":"467.127022ms","start":"2026-05-12T17:22:54.237988Z","end":"2026-05-12T17:22:54.705115Z","steps":["trace[184445095] 'agreement among raft nodes before linearized reading' (duration: 466.888007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:54.705152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:54.237961Z","time spent":"467.184204ms","remote":"127.0.0.1:54230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"info","ts":"2026-05-12T17:22:54.705284Z","caller":"traceutil/trace.go:171","msg":"trace[512769144] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"408.750344ms","start":"2026-05-12T17:22:54.296521Z","end":"2026-05-12T17:22:54.705271Z","steps":["trace[512769144] 'process raft request' (duration: 408.278304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:54.705332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:54.296413Z","time spent":"408.895158ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:22:54.705269Z","caller":"traceutil/trace.go:171","msg":"trace[1478475159] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"408.460088ms","start":"2026-05-12T17:22:54.296497Z","end":"2026-05-12T17:22:54.704957Z","steps":["trace[1478475159] 'process raft request' (duration: 263.426165ms)","trace[1478475159] 'compare' (duration: 144.616764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:54.705433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.357997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-12T17:22:54.705657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:54.296457Z","time spent":"409.109792ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:22:54.705737Z","caller":"traceutil/trace.go:171","msg":"trace[1783105755] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24901; }","duration":"301.674693ms","start":"2026-05-12T17:22:54.404013Z","end":"2026-05-12T17:22:54.705688Z","steps":["trace[1783105755] 'agreement among raft nodes before linearized reading' (duration: 301.280974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:54.705934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:22:54.40397Z","time spent":"301.882537ms","remote":"127.0.0.1:48692","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-05-12T17:22:55.039879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.091575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:55.040117Z","caller":"traceutil/trace.go:171","msg":"trace[1561022020] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"287.93915ms","start":"2026-05-12T17:22:54.752152Z","end":"2026-05-12T17:22:55.040091Z","steps":["trace[1561022020] 'process raft request' (duration: 135.495688ms)","trace[1561022020] 'compare' (duration: 151.89771ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:55.040335Z","caller":"traceutil/trace.go:171","msg":"trace[1983725647] linearizableReadLoop","detail":"{readStateIndex:25720; appliedIndex:25719; }","duration":"261.686589ms","start":"2026-05-12T17:22:54.778599Z","end":"2026-05-12T17:22:55.040286Z","steps":["trace[1983725647] 'read index received' (duration: 108.994602ms)","trace[1983725647] 'applied index is now lower than readState.Index' (duration: 152.688997ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:55.040582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.953634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:55.040626Z","caller":"traceutil/trace.go:171","msg":"trace[395060245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24902; }","duration":"262.083167ms","start":"2026-05-12T17:22:54.778532Z","end":"2026-05-12T17:22:55.040615Z","steps":["trace[395060245] 'agreement among raft nodes before linearized reading' (duration: 261.946254ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:55.450453Z","caller":"traceutil/trace.go:171","msg":"trace[1823075665] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"211.184917ms","start":"2026-05-12T17:22:55.239234Z","end":"2026-05-12T17:22:55.450418Z","steps":["trace[1823075665] 'process raft request' (duration: 210.992783ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:55.466729Z","caller":"traceutil/trace.go:171","msg":"trace[1228822277] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25721; }","duration":"123.342129ms","start":"2026-05-12T17:22:55.34336Z","end":"2026-05-12T17:22:55.466702Z","steps":["trace[1228822277] 'read index received' (duration: 106.776705ms)","trace[1228822277] 'applied index is now lower than readState.Index' (duration: 16.564014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:55.466937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.555544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-12T17:22:55.466972Z","caller":"traceutil/trace.go:171","msg":"trace[1679929205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24905; }","duration":"123.645065ms","start":"2026-05-12T17:22:55.343317Z","end":"2026-05-12T17:22:55.466962Z","steps":["trace[1679929205] 'agreement among raft nodes before linearized reading' (duration: 123.490181ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:55.467089Z","caller":"traceutil/trace.go:171","msg":"trace[918896258] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"225.382891ms","start":"2026-05-12T17:22:55.241698Z","end":"2026-05-12T17:22:55.46708Z","steps":["trace[918896258] 'process raft request' (duration: 224.817729ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:55.750214Z","caller":"traceutil/trace.go:171","msg":"trace[1074691123] linearizableReadLoop","detail":"{readStateIndex:25724; appliedIndex:25723; }","duration":"263.988397ms","start":"2026-05-12T17:22:55.486183Z","end":"2026-05-12T17:22:55.750172Z","steps":["trace[1074691123] 'read index received' (duration: 223.954891ms)","trace[1074691123] 'applied index is now lower than readState.Index' (duration: 40.031696ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:55.750521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.312853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-05-12T17:22:55.750621Z","caller":"traceutil/trace.go:171","msg":"trace[383053934] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24906; }","duration":"264.468237ms","start":"2026-05-12T17:22:55.486128Z","end":"2026-05-12T17:22:55.750596Z","steps":["trace[383053934] 'agreement among raft nodes before linearized reading' (duration: 264.184551ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:55.750536Z","caller":"traceutil/trace.go:171","msg":"trace[576545534] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"269.126317ms","start":"2026-05-12T17:22:55.481331Z","end":"2026-05-12T17:22:55.750458Z","steps":["trace[576545534] 'process raft request' (duration: 228.818965ms)","trace[576545534] 'compare' (duration: 39.75236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:55.750531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.913413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:22:55.750889Z","caller":"traceutil/trace.go:171","msg":"trace[497694469] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24906; }","duration":"198.347023ms","start":"2026-05-12T17:22:55.552529Z","end":"2026-05-12T17:22:55.750876Z","steps":["trace[497694469] 'agreement among raft nodes before linearized reading' (duration: 197.905293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:55.750978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.897207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:55.751052Z","caller":"traceutil/trace.go:171","msg":"trace[1150266898] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24906; }","duration":"157.00039ms","start":"2026-05-12T17:22:55.594022Z","end":"2026-05-12T17:22:55.751022Z","steps":["trace[1150266898] 'agreement among raft nodes before linearized reading' (duration: 156.912017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:55.751518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.729012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-12T17:22:55.751636Z","caller":"traceutil/trace.go:171","msg":"trace[1889061253] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24906; }","duration":"177.909646ms","start":"2026-05-12T17:22:55.5737Z","end":"2026-05-12T17:22:55.75161Z","steps":["trace[1889061253] 'agreement among raft nodes before linearized reading' (duration: 177.252501ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:56.144583Z","caller":"traceutil/trace.go:171","msg":"trace[1451531526] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"215.817197ms","start":"2026-05-12T17:22:55.928742Z","end":"2026-05-12T17:22:56.144559Z","steps":["trace[1451531526] 'process raft request' (duration: 215.613362ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:56.144837Z","caller":"traceutil/trace.go:171","msg":"trace[858858781] linearizableReadLoop","detail":"{readStateIndex:25728; appliedIndex:25725; }","duration":"142.938647ms","start":"2026-05-12T17:22:56.001842Z","end":"2026-05-12T17:22:56.144781Z","steps":["trace[858858781] 'read index received' (duration: 866.728µs)","trace[858858781] 'applied index is now lower than readState.Index' (duration: 142.063319ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:56.14503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.145543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:22:56.145152Z","caller":"traceutil/trace.go:171","msg":"trace[1141732732] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24910; }","duration":"143.360897ms","start":"2026-05-12T17:22:56.001776Z","end":"2026-05-12T17:22:56.145137Z","steps":["trace[1141732732] 'agreement among raft nodes before linearized reading' (duration: 143.125502ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:56.145559Z","caller":"traceutil/trace.go:171","msg":"trace[1954191888] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"215.267365ms","start":"2026-05-12T17:22:55.929587Z","end":"2026-05-12T17:22:56.144854Z","steps":["trace[1954191888] 'process raft request' (duration: 214.922898ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:56.508069Z","caller":"traceutil/trace.go:171","msg":"trace[47410248] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"248.372103ms","start":"2026-05-12T17:22:56.259658Z","end":"2026-05-12T17:22:56.50803Z","steps":["trace[47410248] 'process raft request' (duration: 226.165868ms)","trace[47410248] 'compare' (duration: 22.027881ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:56.508421Z","caller":"traceutil/trace.go:171","msg":"trace[650536913] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"192.828356ms","start":"2026-05-12T17:22:56.315554Z","end":"2026-05-12T17:22:56.508383Z","steps":["trace[650536913] 'process raft request' (duration: 192.502889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:57.112171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.731313ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T17:22:57.267426Z","caller":"traceutil/trace.go:171","msg":"trace[1876078979] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"221.805835ms","start":"2026-05-12T17:22:57.045586Z","end":"2026-05-12T17:22:57.267392Z","steps":["trace[1876078979] 'process raft request' (duration: 221.641632ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:57.386088Z","caller":"traceutil/trace.go:171","msg":"trace[1022638609] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"298.024625ms","start":"2026-05-12T17:22:57.088006Z","end":"2026-05-12T17:22:57.386031Z","steps":["trace[1022638609] 'process raft request' (duration: 275.054714ms)","trace[1022638609] 'compare' (duration: 22.053511ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:57.386471Z","caller":"traceutil/trace.go:171","msg":"trace[75996999] linearizableReadLoop","detail":"{readStateIndex:25736; appliedIndex:25735; }","duration":"295.917849ms","start":"2026-05-12T17:22:57.090514Z","end":"2026-05-12T17:22:57.386432Z","steps":["trace[75996999] 'read index received' (duration: 177.414605ms)","trace[75996999] 'applied index is now lower than readState.Index' (duration: 118.501844ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:57.386508Z","caller":"traceutil/trace.go:171","msg":"trace[1721220205] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"278.581579ms","start":"2026-05-12T17:22:57.107882Z","end":"2026-05-12T17:22:57.386464Z","steps":["trace[1721220205] 'process raft request' (duration: 277.956156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:57.386729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.189305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:57.386796Z","caller":"traceutil/trace.go:171","msg":"trace[1522631152] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24918; }","duration":"296.322118ms","start":"2026-05-12T17:22:57.090455Z","end":"2026-05-12T17:22:57.386777Z","steps":["trace[1522631152] 'agreement among raft nodes before linearized reading' (duration: 296.189375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:57.387002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.139494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-12T17:22:57.387138Z","caller":"traceutil/trace.go:171","msg":"trace[1456387792] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24918; }","duration":"166.303238ms","start":"2026-05-12T17:22:57.220772Z","end":"2026-05-12T17:22:57.387076Z","steps":["trace[1456387792] 'agreement among raft nodes before linearized reading' (duration: 165.992981ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:57.618571Z","caller":"traceutil/trace.go:171","msg":"trace[1790589542] linearizableReadLoop","detail":"{readStateIndex:25739; appliedIndex:25738; }","duration":"133.799592ms","start":"2026-05-12T17:22:57.484742Z","end":"2026-05-12T17:22:57.618541Z","steps":["trace[1790589542] 'read index received' (duration: 131.896731ms)","trace[1790589542] 'applied index is now lower than readState.Index' (duration: 1.901521ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:57.619077Z","caller":"traceutil/trace.go:171","msg":"trace[1990520450] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"135.213442ms","start":"2026-05-12T17:22:57.483849Z","end":"2026-05-12T17:22:57.619062Z","steps":["trace[1990520450] 'process raft request' (duration: 132.867433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:57.619358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.28247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:57.619409Z","caller":"traceutil/trace.go:171","msg":"trace[1297205205] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24920; }","duration":"147.367411ms","start":"2026-05-12T17:22:57.472027Z","end":"2026-05-12T17:22:57.619395Z","steps":["trace[1297205205] 'agreement among raft nodes before linearized reading' (duration: 147.260819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:57.619609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.557513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:57.619647Z","caller":"traceutil/trace.go:171","msg":"trace[1612914012] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24920; }","duration":"130.639375ms","start":"2026-05-12T17:22:57.488998Z","end":"2026-05-12T17:22:57.619638Z","steps":["trace[1612914012] 'agreement among raft nodes before linearized reading' (duration: 130.579643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:57.95972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.785388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-12T17:22:57.959904Z","caller":"traceutil/trace.go:171","msg":"trace[719498058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24920; }","duration":"202.960352ms","start":"2026-05-12T17:22:57.756887Z","end":"2026-05-12T17:22:57.959847Z","steps":["trace[719498058] 'range keys from in-memory index tree' (duration: 202.575143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:57.960051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.516738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T17:22:57.960173Z","caller":"traceutil/trace.go:171","msg":"trace[282258052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24920; }","duration":"185.702552ms","start":"2026-05-12T17:22:57.774442Z","end":"2026-05-12T17:22:57.960144Z","steps":["trace[282258052] 'range keys from in-memory index tree' (duration: 185.145101ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:57.990092Z","caller":"traceutil/trace.go:171","msg":"trace[16351121] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"186.812497ms","start":"2026-05-12T17:22:57.80326Z","end":"2026-05-12T17:22:57.990073Z","steps":["trace[16351121] 'process raft request' (duration: 186.617132ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:58.120388Z","caller":"traceutil/trace.go:171","msg":"trace[295352616] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"154.327301ms","start":"2026-05-12T17:22:57.96604Z","end":"2026-05-12T17:22:58.120367Z","steps":["trace[295352616] 'process raft request' (duration: 154.28413ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:58.120671Z","caller":"traceutil/trace.go:171","msg":"trace[407458772] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"155.283872ms","start":"2026-05-12T17:22:57.96515Z","end":"2026-05-12T17:22:58.120434Z","steps":["trace[407458772] 'process raft request' (duration: 155.036907ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:58.121754Z","caller":"traceutil/trace.go:171","msg":"trace[147276271] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"111.754401ms","start":"2026-05-12T17:22:58.009978Z","end":"2026-05-12T17:22:58.121733Z","steps":["trace[147276271] 'process raft request' (duration: 111.187749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:58.373132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.979342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:58.373259Z","caller":"traceutil/trace.go:171","msg":"trace[94755578] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"216.234026ms","start":"2026-05-12T17:22:58.157002Z","end":"2026-05-12T17:22:58.373236Z","steps":["trace[94755578] 'process raft request' (duration: 63.025048ms)","trace[94755578] 'compare' (duration: 152.39768ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:58.374041Z","caller":"traceutil/trace.go:171","msg":"trace[111174482] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"216.593333ms","start":"2026-05-12T17:22:58.15743Z","end":"2026-05-12T17:22:58.374023Z","steps":["trace[111174482] 'process raft request' (duration: 216.501491ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:58.376051Z","caller":"traceutil/trace.go:171","msg":"trace[1264853592] linearizableReadLoop","detail":"{readStateIndex:25746; appliedIndex:25746; }","duration":"136.793977ms","start":"2026-05-12T17:22:58.239224Z","end":"2026-05-12T17:22:58.376018Z","steps":["trace[1264853592] 'read index received' (duration: 136.771106ms)","trace[1264853592] 'applied index is now lower than readState.Index' (duration: 20.271µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:58.376263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.045502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18365"} {"level":"info","ts":"2026-05-12T17:22:58.37632Z","caller":"traceutil/trace.go:171","msg":"trace[1889323068] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24927; }","duration":"137.154944ms","start":"2026-05-12T17:22:58.239151Z","end":"2026-05-12T17:22:58.376306Z","steps":["trace[1889323068] 'agreement among raft nodes before linearized reading' (duration: 136.96052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:58.376472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.907432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:22:58.376567Z","caller":"traceutil/trace.go:171","msg":"trace[1265611300] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24927; }","duration":"124.079735ms","start":"2026-05-12T17:22:58.252465Z","end":"2026-05-12T17:22:58.376545Z","steps":["trace[1265611300] 'agreement among raft nodes before linearized reading' (duration: 123.812649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:58.64218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.189476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:58.642306Z","caller":"traceutil/trace.go:171","msg":"trace[217636648] linearizableReadLoop","detail":"{readStateIndex:25747; appliedIndex:25746; }","duration":"261.427923ms","start":"2026-05-12T17:22:58.380861Z","end":"2026-05-12T17:22:58.642289Z","steps":["trace[217636648] 'read index received' (duration: 137.959821ms)","trace[217636648] 'applied index is now lower than readState.Index' (duration: 123.463431ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:58.642545Z","caller":"traceutil/trace.go:171","msg":"trace[136334039] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"261.806911ms","start":"2026-05-12T17:22:58.380727Z","end":"2026-05-12T17:22:58.642534Z","steps":["trace[136334039] 'process raft request' (duration: 138.140455ms)","trace[136334039] 'compare' (duration: 122.727265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:58.64297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.640693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-12T17:22:58.64356Z","caller":"traceutil/trace.go:171","msg":"trace[913747407] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24928; }","duration":"130.334658ms","start":"2026-05-12T17:22:58.513197Z","end":"2026-05-12T17:22:58.643532Z","steps":["trace[913747407] 'agreement among raft nodes before linearized reading' (duration: 129.528661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:58.643259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.374793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18365"} {"level":"info","ts":"2026-05-12T17:22:58.644831Z","caller":"traceutil/trace.go:171","msg":"trace[1391943924] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24928; }","duration":"263.985338ms","start":"2026-05-12T17:22:58.380812Z","end":"2026-05-12T17:22:58.644798Z","steps":["trace[1391943924] 'agreement among raft nodes before linearized reading' (duration: 262.20089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:58.64334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.671568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-12T17:22:58.645067Z","caller":"traceutil/trace.go:171","msg":"trace[1667160131] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24928; }","duration":"106.417716ms","start":"2026-05-12T17:22:58.538617Z","end":"2026-05-12T17:22:58.645035Z","steps":["trace[1667160131] 'agreement among raft nodes before linearized reading' (duration: 104.615237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:58.643378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.301597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:22:58.645203Z","caller":"traceutil/trace.go:171","msg":"trace[1821809551] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:24928; }","duration":"125.148227ms","start":"2026-05-12T17:22:58.520032Z","end":"2026-05-12T17:22:58.645181Z","steps":["trace[1821809551] 'agreement among raft nodes before linearized reading' (duration: 123.295297ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:58.802253Z","caller":"traceutil/trace.go:171","msg":"trace[586173024] linearizableReadLoop","detail":"{readStateIndex:25748; appliedIndex:25747; }","duration":"149.594431ms","start":"2026-05-12T17:22:58.652629Z","end":"2026-05-12T17:22:58.802223Z","steps":["trace[586173024] 'read index received' (duration: 146.196957ms)","trace[586173024] 'applied index is now lower than readState.Index' (duration: 3.396114ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:58.802355Z","caller":"traceutil/trace.go:171","msg":"trace[1987198989] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"152.516863ms","start":"2026-05-12T17:22:58.649803Z","end":"2026-05-12T17:22:58.80232Z","steps":["trace[1987198989] 'process raft request' (duration: 149.09435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:58.802581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.918268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-05-12T17:22:58.802624Z","caller":"traceutil/trace.go:171","msg":"trace[2046395057] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24929; }","duration":"149.993139ms","start":"2026-05-12T17:22:58.652616Z","end":"2026-05-12T17:22:58.802609Z","steps":["trace[2046395057] 'agreement among raft nodes before linearized reading' (duration: 149.789475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:22:59.091711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.581372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:22:59.092097Z","caller":"traceutil/trace.go:171","msg":"trace[1672137131] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"276.502575ms","start":"2026-05-12T17:22:58.815545Z","end":"2026-05-12T17:22:59.092047Z","steps":["trace[1672137131] 'process raft request' (duration: 146.475244ms)","trace[1672137131] 'compare' (duration: 129.223915ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:59.092298Z","caller":"traceutil/trace.go:171","msg":"trace[2018313873] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"274.383609ms","start":"2026-05-12T17:22:58.817892Z","end":"2026-05-12T17:22:59.092276Z","steps":["trace[2018313873] 'process raft request' (duration: 273.993221ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:59.383822Z","caller":"traceutil/trace.go:171","msg":"trace[2057332304] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"109.950412ms","start":"2026-05-12T17:22:59.273809Z","end":"2026-05-12T17:22:59.38376Z","steps":["trace[2057332304] 'process raft request' (duration: 109.748938ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:59.522549Z","caller":"traceutil/trace.go:171","msg":"trace[884244967] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"130.630904ms","start":"2026-05-12T17:22:59.391891Z","end":"2026-05-12T17:22:59.522522Z","steps":["trace[884244967] 'process raft request' (duration: 88.457192ms)","trace[884244967] 'compare' (duration: 41.788994ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:22:59.522541Z","caller":"traceutil/trace.go:171","msg":"trace[1751921599] linearizableReadLoop","detail":"{readStateIndex:25752; appliedIndex:25751; }","duration":"111.456504ms","start":"2026-05-12T17:22:59.411054Z","end":"2026-05-12T17:22:59.52251Z","steps":["trace[1751921599] 'read index received' (duration: 69.261971ms)","trace[1751921599] 'applied index is now lower than readState.Index' (duration: 42.193343ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:22:59.523241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.587027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:22:59.52439Z","caller":"traceutil/trace.go:171","msg":"trace[162539659] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24933; }","duration":"112.318833ms","start":"2026-05-12T17:22:59.410995Z","end":"2026-05-12T17:22:59.523314Z","steps":["trace[162539659] 'agreement among raft nodes before linearized reading' (duration: 111.594128ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:07.294987Z","caller":"traceutil/trace.go:171","msg":"trace[245987078] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"171.641411ms","start":"2026-05-12T17:23:07.123314Z","end":"2026-05-12T17:23:07.294956Z","steps":["trace[245987078] 'process raft request' (duration: 171.417766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:07.832452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.379253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:07.832607Z","caller":"traceutil/trace.go:171","msg":"trace[281591031] linearizableReadLoop","detail":"{readStateIndex:25811; appliedIndex:25810; }","duration":"419.013323ms","start":"2026-05-12T17:23:07.413569Z","end":"2026-05-12T17:23:07.832582Z","steps":["trace[281591031] 'read index received' (duration: 199.553699ms)","trace[281591031] 'applied index is now lower than readState.Index' (duration: 219.458064ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:07.832708Z","caller":"traceutil/trace.go:171","msg":"trace[2136121368] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"709.1657ms","start":"2026-05-12T17:23:07.123452Z","end":"2026-05-12T17:23:07.832617Z","steps":["trace[2136121368] 'process raft request' (duration: 489.514861ms)","trace[2136121368] 'compare' (duration: 219.057346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:07.832798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.236007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:07.832838Z","caller":"traceutil/trace.go:171","msg":"trace[2096851584] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24990; }","duration":"419.318159ms","start":"2026-05-12T17:23:07.413505Z","end":"2026-05-12T17:23:07.832823Z","steps":["trace[2096851584] 'agreement among raft nodes before linearized reading' (duration: 419.234017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:07.832885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.413461Z","time spent":"419.413121ms","remote":"127.0.0.1:54324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:23:07.832904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.123417Z","time spent":"709.392085ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:23:08.36952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.038166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:08.369846Z","caller":"traceutil/trace.go:171","msg":"trace[584762181] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25811; }","duration":"537.11778ms","start":"2026-05-12T17:23:07.832706Z","end":"2026-05-12T17:23:08.369824Z","steps":["trace[584762181] 'read index received' (duration: 245.690986ms)","trace[584762181] 'applied index is now lower than readState.Index' (duration: 291.425444ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:08.370086Z","caller":"traceutil/trace.go:171","msg":"trace[1179439756] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"819.447079ms","start":"2026-05-12T17:23:07.550625Z","end":"2026-05-12T17:23:08.370072Z","steps":["trace[1179439756] 'process raft request' (duration: 819.00176ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:08.369985Z","caller":"traceutil/trace.go:171","msg":"trace[1115785907] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"950.86852ms","start":"2026-05-12T17:23:07.418994Z","end":"2026-05-12T17:23:08.369862Z","steps":["trace[1115785907] 'process raft request' (duration: 659.382094ms)","trace[1115785907] 'compare' (duration: 290.778071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:08.370171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.550597Z","time spent":"819.53444ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:08.370359Z","caller":"traceutil/trace.go:171","msg":"trace[1499146543] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"817.400786ms","start":"2026-05-12T17:23:07.552949Z","end":"2026-05-12T17:23:08.37035Z","steps":["trace[1499146543] 'process raft request' (duration: 816.781493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.370422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.552925Z","time spent":"817.464997ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:23:08.370414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.418974Z","time spent":"951.188927ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:08.370541Z","caller":"traceutil/trace.go:171","msg":"trace[2092405108] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"712.848969ms","start":"2026-05-12T17:23:07.657683Z","end":"2026-05-12T17:23:08.370532Z","steps":["trace[2092405108] 'process raft request' (duration: 712.100413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.370603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.657665Z","time spent":"712.90376ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:23:08.370756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.101013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:08.370792Z","caller":"traceutil/trace.go:171","msg":"trace[560068759] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24994; }","duration":"540.166794ms","start":"2026-05-12T17:23:07.830616Z","end":"2026-05-12T17:23:08.370783Z","steps":["trace[560068759] 'agreement among raft nodes before linearized reading' (duration: 540.107563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.370826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.830598Z","time spent":"540.217346ms","remote":"127.0.0.1:48768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-12T17:23:08.370986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.430095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:08.371019Z","caller":"traceutil/trace.go:171","msg":"trace[844477327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24994; }","duration":"594.517848ms","start":"2026-05-12T17:23:07.776493Z","end":"2026-05-12T17:23:08.371011Z","steps":["trace[844477327] 'agreement among raft nodes before linearized reading' (duration: 594.464077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.371044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.776464Z","time spent":"594.575789ms","remote":"127.0.0.1:48582","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-05-12T17:23:08.371362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.63172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-12T17:23:08.3714Z","caller":"traceutil/trace.go:171","msg":"trace[1100729067] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24994; }","duration":"601.714112ms","start":"2026-05-12T17:23:07.769677Z","end":"2026-05-12T17:23:08.371391Z","steps":["trace[1100729067] 'agreement among raft nodes before linearized reading' (duration: 601.499367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.371429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.769649Z","time spent":"601.773724ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-12T17:23:08.371598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.960076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:08.371633Z","caller":"traceutil/trace.go:171","msg":"trace[942986913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24994; }","duration":"731.040958ms","start":"2026-05-12T17:23:07.640583Z","end":"2026-05-12T17:23:08.371624Z","steps":["trace[942986913] 'agreement among raft nodes before linearized reading' (duration: 730.991057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.371662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:07.640552Z","time spent":"731.104089ms","remote":"127.0.0.1:48576","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-05-12T17:23:08.917075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.40235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:08.917279Z","caller":"traceutil/trace.go:171","msg":"trace[255790838] linearizableReadLoop","detail":"{readStateIndex:25817; appliedIndex:25815; }","duration":"547.378709ms","start":"2026-05-12T17:23:08.369885Z","end":"2026-05-12T17:23:08.917264Z","steps":["trace[255790838] 'read index received' (duration: 322.607091ms)","trace[255790838] 'applied index is now lower than readState.Index' (duration: 224.770738ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:08.917359Z","caller":"traceutil/trace.go:171","msg":"trace[1890526191] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"747.868597ms","start":"2026-05-12T17:23:08.169454Z","end":"2026-05-12T17:23:08.917322Z","steps":["trace[1890526191] 'process raft request' (duration: 747.739475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.917449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.04828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:08.91748Z","caller":"traceutil/trace.go:171","msg":"trace[1976360881] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24996; }","duration":"581.08531ms","start":"2026-05-12T17:23:08.336384Z","end":"2026-05-12T17:23:08.91747Z","steps":["trace[1976360881] 'agreement among raft nodes before linearized reading' (duration: 580.976197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.917521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.336284Z","time spent":"581.226964ms","remote":"127.0.0.1:40220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:23:08.917567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.169423Z","time spent":"748.020302ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:08.917597Z","caller":"traceutil/trace.go:171","msg":"trace[589289005] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"815.859873ms","start":"2026-05-12T17:23:08.101682Z","end":"2026-05-12T17:23:08.917542Z","steps":["trace[589289005] 'process raft request' (duration: 590.8906ms)","trace[589289005] 'compare' (duration: 224.261567ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:08.917736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.520988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-12T17:23:08.917851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.117234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-12T17:23:08.917845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.10165Z","time spent":"816.091488ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:08.917886Z","caller":"traceutil/trace.go:171","msg":"trace[1614343755] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24996; }","duration":"511.197556ms","start":"2026-05-12T17:23:08.406681Z","end":"2026-05-12T17:23:08.917878Z","steps":["trace[1614343755] 'agreement among raft nodes before linearized reading' (duration: 511.068303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.917911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.40665Z","time spent":"511.252887ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-12T17:23:08.918178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.427143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-12T17:23:08.918184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.594825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-12T17:23:08.918222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.020828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:08.918238Z","caller":"traceutil/trace.go:171","msg":"trace[854149663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24996; }","duration":"483.700757ms","start":"2026-05-12T17:23:08.434524Z","end":"2026-05-12T17:23:08.918225Z","steps":["trace[854149663] 'agreement among raft nodes before linearized reading' (duration: 483.580785ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:08.918285Z","caller":"traceutil/trace.go:171","msg":"trace[384636577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24996; }","duration":"142.11633ms","start":"2026-05-12T17:23:08.776155Z","end":"2026-05-12T17:23:08.918271Z","steps":["trace[384636577] 'agreement among raft nodes before linearized reading' (duration: 142.013798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.918294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.434495Z","time spent":"483.789039ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T17:23:08.918275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.13823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:08.918368Z","caller":"traceutil/trace.go:171","msg":"trace[1545024282] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24996; }","duration":"458.296254ms","start":"2026-05-12T17:23:08.460047Z","end":"2026-05-12T17:23:08.918343Z","steps":["trace[1545024282] 'agreement among raft nodes before linearized reading' (duration: 458.11188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.918386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.370655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-12T17:23:08.918424Z","caller":"traceutil/trace.go:171","msg":"trace[1742081024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24996; }","duration":"487.435817ms","start":"2026-05-12T17:23:08.430979Z","end":"2026-05-12T17:23:08.918415Z","steps":["trace[1742081024] 'agreement among raft nodes before linearized reading' (duration: 487.249503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.918426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.46002Z","time spent":"458.395636ms","remote":"127.0.0.1:39456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:23:08.918457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.430965Z","time spent":"487.487218ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T17:23:08.918222Z","caller":"traceutil/trace.go:171","msg":"trace[996140169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24996; }","duration":"225.578296ms","start":"2026-05-12T17:23:08.692626Z","end":"2026-05-12T17:23:08.918204Z","steps":["trace[996140169] 'agreement among raft nodes before linearized reading' (duration: 225.475284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.918533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.61344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T17:23:08.918681Z","caller":"traceutil/trace.go:171","msg":"trace[1703194464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24996; }","duration":"738.819465ms","start":"2026-05-12T17:23:08.179849Z","end":"2026-05-12T17:23:08.918669Z","steps":["trace[1703194464] 'agreement among raft nodes before linearized reading' (duration: 738.281233ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:08.917832Z","caller":"traceutil/trace.go:171","msg":"trace[240370925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24996; }","duration":"738.651192ms","start":"2026-05-12T17:23:08.179158Z","end":"2026-05-12T17:23:08.917809Z","steps":["trace[240370925] 'agreement among raft nodes before linearized reading' (duration: 738.308564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:08.918964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.179825Z","time spent":"738.888396ms","remote":"127.0.0.1:48876","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-05-12T17:23:08.919505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.179145Z","time spent":"740.305996ms","remote":"127.0.0.1:48876","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":"warn","ts":"2026-05-12T17:23:09.554465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.670022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:09.554712Z","caller":"traceutil/trace.go:171","msg":"trace[1730099653] linearizableReadLoop","detail":"{readStateIndex:25823; appliedIndex:25818; }","duration":"564.841953ms","start":"2026-05-12T17:23:08.989856Z","end":"2026-05-12T17:23:09.554698Z","steps":["trace[1730099653] 'read index received' (duration: 285.764873ms)","trace[1730099653] 'applied index is now lower than readState.Index' (duration: 279.07629ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:09.554776Z","caller":"traceutil/trace.go:171","msg":"trace[1488064869] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"630.796474ms","start":"2026-05-12T17:23:08.923969Z","end":"2026-05-12T17:23:09.554766Z","steps":["trace[1488064869] 'process raft request' (duration: 351.743204ms)","trace[1488064869] 'compare' (duration: 278.205082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:09.554831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.923944Z","time spent":"630.857425ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:09.555005Z","caller":"traceutil/trace.go:171","msg":"trace[498692782] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"630.566399ms","start":"2026-05-12T17:23:08.924394Z","end":"2026-05-12T17:23:09.55496Z","steps":["trace[498692782] 'process raft request' (duration: 630.265693ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:09.55505Z","caller":"traceutil/trace.go:171","msg":"trace[131178622] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"630.913106ms","start":"2026-05-12T17:23:08.924048Z","end":"2026-05-12T17:23:09.554961Z","steps":["trace[131178622] 'process raft request' (duration: 630.559208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:09.555263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.924384Z","time spent":"630.749053ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:23:09.555283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.924026Z","time spent":"631.124001ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:09.555659Z","caller":"traceutil/trace.go:171","msg":"trace[965067277] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"631.458737ms","start":"2026-05-12T17:23:08.924181Z","end":"2026-05-12T17:23:09.555639Z","steps":["trace[965067277] 'process raft request' (duration: 630.454576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:09.555759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.924171Z","time spent":"631.5406ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:09.555718Z","caller":"traceutil/trace.go:171","msg":"trace[2095893343] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"631.702483ms","start":"2026-05-12T17:23:08.923969Z","end":"2026-05-12T17:23:09.555672Z","steps":["trace[2095893343] 'process raft request' (duration: 630.586979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:09.555772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.250532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:09.556055Z","caller":"traceutil/trace.go:171","msg":"trace[611265204] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25002; }","duration":"566.257393ms","start":"2026-05-12T17:23:08.989783Z","end":"2026-05-12T17:23:09.55604Z","steps":["trace[611265204] 'agreement among raft nodes before linearized reading' (duration: 565.215021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:09.556103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.989758Z","time spent":"566.330965ms","remote":"127.0.0.1:49078","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-05-12T17:23:09.556134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:08.923943Z","time spent":"631.984138ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:09.556642Z","caller":"traceutil/trace.go:171","msg":"trace[248710025] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"254.636597ms","start":"2026-05-12T17:23:09.301983Z","end":"2026-05-12T17:23:09.556619Z","steps":["trace[248710025] 'process raft request' (duration: 254.481774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:09.569314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.012616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:23:09.569476Z","caller":"traceutil/trace.go:171","msg":"trace[630815858] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25004; }","duration":"170.225982ms","start":"2026-05-12T17:23:09.399226Z","end":"2026-05-12T17:23:09.569452Z","steps":["trace[630815858] 'agreement among raft nodes before linearized reading' (duration: 170.003977ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:09.899398Z","caller":"traceutil/trace.go:171","msg":"trace[1272923046] linearizableReadLoop","detail":"{readStateIndex:25826; appliedIndex:25825; }","duration":"311.978413ms","start":"2026-05-12T17:23:09.587385Z","end":"2026-05-12T17:23:09.899363Z","steps":["trace[1272923046] 'read index received' (duration: 251.485879ms)","trace[1272923046] 'applied index is now lower than readState.Index' (duration: 60.484684ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:09.899718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.29531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-12T17:23:09.899755Z","caller":"traceutil/trace.go:171","msg":"trace[1611186894] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25005; }","duration":"312.373261ms","start":"2026-05-12T17:23:09.587369Z","end":"2026-05-12T17:23:09.899742Z","steps":["trace[1611186894] 'agreement among raft nodes before linearized reading' (duration: 312.116106ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:09.899764Z","caller":"traceutil/trace.go:171","msg":"trace[2089743498] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"316.583993ms","start":"2026-05-12T17:23:09.583127Z","end":"2026-05-12T17:23:09.899711Z","steps":["trace[2089743498] 'process raft request' (duration: 255.68159ms)","trace[2089743498] 'compare' (duration: 59.995504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:09.899833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:09.58736Z","time spent":"312.460313ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-12T17:23:09.899951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:09.583105Z","time spent":"316.765776ms","remote":"127.0.0.1:48768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1560,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T17:23:10.121791Z","caller":"traceutil/trace.go:171","msg":"trace[746069006] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25826; }","duration":"216.710235ms","start":"2026-05-12T17:23:09.905037Z","end":"2026-05-12T17:23:10.121748Z","steps":["trace[746069006] 'read index received' (duration: 147.505945ms)","trace[746069006] 'applied index is now lower than readState.Index' (duration: 69.20238ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:10.121921Z","caller":"traceutil/trace.go:171","msg":"trace[348371862] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"280.848088ms","start":"2026-05-12T17:23:09.841018Z","end":"2026-05-12T17:23:10.121866Z","steps":["trace[348371862] 'process raft request' (duration: 211.660518ms)","trace[348371862] 'compare' (duration: 68.905754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:10.122184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.119264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"warn","ts":"2026-05-12T17:23:10.122225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.221744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"info","ts":"2026-05-12T17:23:10.122296Z","caller":"traceutil/trace.go:171","msg":"trace[1980700904] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25006; }","duration":"215.360487ms","start":"2026-05-12T17:23:09.906916Z","end":"2026-05-12T17:23:10.122276Z","steps":["trace[1980700904] 'agreement among raft nodes before linearized reading' (duration: 215.084712ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:10.122289Z","caller":"traceutil/trace.go:171","msg":"trace[83726465] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25006; }","duration":"217.283988ms","start":"2026-05-12T17:23:09.904976Z","end":"2026-05-12T17:23:10.12226Z","steps":["trace[83726465] 'agreement among raft nodes before linearized reading' (duration: 216.95553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:13.89759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.896206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:13.897698Z","caller":"traceutil/trace.go:171","msg":"trace[1746297242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25096; }","duration":"121.07093ms","start":"2026-05-12T17:23:13.776603Z","end":"2026-05-12T17:23:13.897674Z","steps":["trace[1746297242] 'range keys from in-memory index tree' (duration: 120.741162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:17.998408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.231904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:17.998622Z","caller":"traceutil/trace.go:171","msg":"trace[1151838352] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"257.573ms","start":"2026-05-12T17:23:17.741008Z","end":"2026-05-12T17:23:17.998581Z","steps":["trace[1151838352] 'process raft request' (duration: 257.496119ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:17.998694Z","caller":"traceutil/trace.go:171","msg":"trace[550470567] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"257.57071ms","start":"2026-05-12T17:23:17.741009Z","end":"2026-05-12T17:23:17.99858Z","steps":["trace[550470567] 'process raft request' (duration: 129.078681ms)","trace[550470567] 'compare' (duration: 127.884326ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:17.99891Z","caller":"traceutil/trace.go:171","msg":"trace[630403777] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"257.713402ms","start":"2026-05-12T17:23:17.741162Z","end":"2026-05-12T17:23:17.998875Z","steps":["trace[630403777] 'process raft request' (duration: 257.386945ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:17.998999Z","caller":"traceutil/trace.go:171","msg":"trace[988869737] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"257.735273ms","start":"2026-05-12T17:23:17.741232Z","end":"2026-05-12T17:23:17.998968Z","steps":["trace[988869737] 'process raft request' (duration: 257.58122ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:17.999107Z","caller":"traceutil/trace.go:171","msg":"trace[57453954] linearizableReadLoop","detail":"{readStateIndex:25970; appliedIndex:25964; }","duration":"223.418608ms","start":"2026-05-12T17:23:17.775677Z","end":"2026-05-12T17:23:17.999096Z","steps":["trace[57453954] 'read index received' (duration: 94.424389ms)","trace[57453954] 'applied index is now lower than readState.Index' (duration: 128.992849ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:17.999194Z","caller":"traceutil/trace.go:171","msg":"trace[544161340] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"256.019566ms","start":"2026-05-12T17:23:17.743165Z","end":"2026-05-12T17:23:17.999185Z","steps":["trace[544161340] 'process raft request' (duration: 255.767421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:17.999279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.587472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:17.999321Z","caller":"traceutil/trace.go:171","msg":"trace[300449415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25147; }","duration":"223.685353ms","start":"2026-05-12T17:23:17.775626Z","end":"2026-05-12T17:23:17.999311Z","steps":["trace[300449415] 'agreement among raft nodes before linearized reading' (duration: 223.601122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:17.999322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.310675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:17.999361Z","caller":"traceutil/trace.go:171","msg":"trace[842605516] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25147; }","duration":"157.424007ms","start":"2026-05-12T17:23:17.841927Z","end":"2026-05-12T17:23:17.999351Z","steps":["trace[842605516] 'agreement among raft nodes before linearized reading' (duration: 157.323285ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:17.999449Z","caller":"traceutil/trace.go:171","msg":"trace[2147072899] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"250.56087ms","start":"2026-05-12T17:23:17.74888Z","end":"2026-05-12T17:23:17.999441Z","steps":["trace[2147072899] 'process raft request' (duration: 250.13821ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:18.171234Z","caller":"traceutil/trace.go:171","msg":"trace[2025038793] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"163.716823ms","start":"2026-05-12T17:23:18.007476Z","end":"2026-05-12T17:23:18.171193Z","steps":["trace[2025038793] 'process raft request' (duration: 151.610273ms)","trace[2025038793] 'compare' (duration: 11.64227ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:18.174798Z","caller":"traceutil/trace.go:171","msg":"trace[2142149750] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"166.498221ms","start":"2026-05-12T17:23:18.008265Z","end":"2026-05-12T17:23:18.174763Z","steps":["trace[2142149750] 'process raft request' (duration: 166.143284ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:18.174851Z","caller":"traceutil/trace.go:171","msg":"trace[1614759713] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"163.011837ms","start":"2026-05-12T17:23:18.011795Z","end":"2026-05-12T17:23:18.174807Z","steps":["trace[1614759713] 'process raft request' (duration: 162.858144ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:18.174816Z","caller":"traceutil/trace.go:171","msg":"trace[500402572] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"164.764535ms","start":"2026-05-12T17:23:18.010027Z","end":"2026-05-12T17:23:18.174791Z","steps":["trace[500402572] 'process raft request' (duration: 164.574441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:46.897036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.029467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:46.897283Z","caller":"traceutil/trace.go:171","msg":"trace[1698890306] linearizableReadLoop","detail":"{readStateIndex:26210; appliedIndex:26206; }","duration":"334.899993ms","start":"2026-05-12T17:23:46.562361Z","end":"2026-05-12T17:23:46.897261Z","steps":["trace[1698890306] 'read index received' (duration: 40.668229ms)","trace[1698890306] 'applied index is now lower than readState.Index' (duration: 294.230914ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:46.897566Z","caller":"traceutil/trace.go:171","msg":"trace[684476633] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"376.597295ms","start":"2026-05-12T17:23:46.520954Z","end":"2026-05-12T17:23:46.897551Z","steps":["trace[684476633] 'process raft request' (duration: 262.960225ms)","trace[684476633] 'compare' (duration: 112.65607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:46.897632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:46.520937Z","time spent":"376.659957ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:46.89784Z","caller":"traceutil/trace.go:171","msg":"trace[816113313] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"374.48908ms","start":"2026-05-12T17:23:46.523316Z","end":"2026-05-12T17:23:46.897805Z","steps":["trace[816113313] 'process raft request' (duration: 373.831496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:46.897912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:46.523301Z","time spent":"374.573132ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:46.898089Z","caller":"traceutil/trace.go:171","msg":"trace[1225377448] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"373.973609ms","start":"2026-05-12T17:23:46.524041Z","end":"2026-05-12T17:23:46.898014Z","steps":["trace[1225377448] 'process raft request' (duration: 373.171922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:46.898144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.72353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:46.898238Z","caller":"traceutil/trace.go:171","msg":"trace[873212071] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25381; }","duration":"335.862473ms","start":"2026-05-12T17:23:46.562353Z","end":"2026-05-12T17:23:46.898215Z","steps":["trace[873212071] 'agreement among raft nodes before linearized reading' (duration: 335.611648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:46.898304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:46.562291Z","time spent":"336.004817ms","remote":"127.0.0.1:53984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:23:46.89833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:46.524034Z","time spent":"374.176613ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:23:47.087012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.116798ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T17:23:47.087139Z","caller":"traceutil/trace.go:171","msg":"trace[1040840322] linearizableReadLoop","detail":"{readStateIndex:26211; appliedIndex:26210; }","duration":"189.804131ms","start":"2026-05-12T17:23:46.897314Z","end":"2026-05-12T17:23:47.087118Z","steps":["trace[1040840322] 'read index received' (duration: 6.52455ms)","trace[1040840322] 'applied index is now lower than readState.Index' (duration: 183.277701ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:47.087233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.816759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:47.087267Z","caller":"traceutil/trace.go:171","msg":"trace[1306345096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25381; }","duration":"310.903101ms","start":"2026-05-12T17:23:46.776355Z","end":"2026-05-12T17:23:47.087258Z","steps":["trace[1306345096] 'agreement among raft nodes before linearized reading' (duration: 310.815359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:47.087315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:46.77633Z","time spent":"310.974413ms","remote":"127.0.0.1:48576","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-05-12T17:23:47.088102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.222271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:47.088157Z","caller":"traceutil/trace.go:171","msg":"trace[236150876] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25381; }","duration":"213.475587ms","start":"2026-05-12T17:23:46.874665Z","end":"2026-05-12T17:23:47.088141Z","steps":["trace[236150876] 'agreement among raft nodes before linearized reading' (duration: 213.220421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:47.088356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.974801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:47.088393Z","caller":"traceutil/trace.go:171","msg":"trace[2146657449] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25381; }","duration":"238.066933ms","start":"2026-05-12T17:23:46.850318Z","end":"2026-05-12T17:23:47.088385Z","steps":["trace[2146657449] 'agreement among raft nodes before linearized reading' (duration: 238.004581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:47.244158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.325168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:47.244273Z","caller":"traceutil/trace.go:171","msg":"trace[1064099981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25382; }","duration":"151.489771ms","start":"2026-05-12T17:23:47.092757Z","end":"2026-05-12T17:23:47.244247Z","steps":["trace[1064099981] 'range keys from in-memory index tree' (duration: 151.187674ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:47.396537Z","caller":"traceutil/trace.go:171","msg":"trace[436705219] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"147.345032ms","start":"2026-05-12T17:23:47.249164Z","end":"2026-05-12T17:23:47.396509Z","steps":["trace[436705219] 'process raft request' (duration: 147.163318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:48.211903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.495827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:23:48.212028Z","caller":"traceutil/trace.go:171","msg":"trace[161965773] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25384; }","duration":"199.655671ms","start":"2026-05-12T17:23:48.012341Z","end":"2026-05-12T17:23:48.211997Z","steps":["trace[161965773] 'count revisions from in-memory index tree' (duration: 199.338504ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:48.347918Z","caller":"traceutil/trace.go:171","msg":"trace[2029267960] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"130.654155ms","start":"2026-05-12T17:23:48.217234Z","end":"2026-05-12T17:23:48.347888Z","steps":["trace[2029267960] 'process raft request' (duration: 130.42472ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:48.429371Z","caller":"traceutil/trace.go:171","msg":"trace[1522023028] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"211.606567ms","start":"2026-05-12T17:23:48.217708Z","end":"2026-05-12T17:23:48.429315Z","steps":["trace[1522023028] 'process raft request' (duration: 211.333771ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:48.429405Z","caller":"traceutil/trace.go:171","msg":"trace[1127693294] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"210.484213ms","start":"2026-05-12T17:23:48.218877Z","end":"2026-05-12T17:23:48.429362Z","steps":["trace[1127693294] 'process raft request' (duration: 210.3683ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:48.429664Z","caller":"traceutil/trace.go:171","msg":"trace[1918081620] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"210.82716ms","start":"2026-05-12T17:23:48.21882Z","end":"2026-05-12T17:23:48.429647Z","steps":["trace[1918081620] 'process raft request' (duration: 210.38251ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:48.429717Z","caller":"traceutil/trace.go:171","msg":"trace[1180920451] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"210.534743ms","start":"2026-05-12T17:23:48.219151Z","end":"2026-05-12T17:23:48.429686Z","steps":["trace[1180920451] 'process raft request' (duration: 210.126045ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:48.429852Z","caller":"traceutil/trace.go:171","msg":"trace[716040529] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"130.110663ms","start":"2026-05-12T17:23:48.299692Z","end":"2026-05-12T17:23:48.429803Z","steps":["trace[716040529] 'process raft request' (duration: 129.610802ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:48.715645Z","caller":"traceutil/trace.go:171","msg":"trace[1635253355] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26220; }","duration":"104.796372ms","start":"2026-05-12T17:23:48.610823Z","end":"2026-05-12T17:23:48.715619Z","steps":["trace[1635253355] 'read index received' (duration: 104.619608ms)","trace[1635253355] 'applied index is now lower than readState.Index' (duration: 176.024µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:48.715843Z","caller":"traceutil/trace.go:171","msg":"trace[172068025] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"105.862964ms","start":"2026-05-12T17:23:48.609876Z","end":"2026-05-12T17:23:48.715739Z","steps":["trace[172068025] 'process raft request' (duration: 105.574548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:48.715984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.119169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xjpcx\" ","response":"range_response_count:1 size:18518"} {"level":"info","ts":"2026-05-12T17:23:48.716026Z","caller":"traceutil/trace.go:171","msg":"trace[2065285476] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xjpcx; range_end:; response_count:1; response_revision:25391; }","duration":"105.23159ms","start":"2026-05-12T17:23:48.610782Z","end":"2026-05-12T17:23:48.716014Z","steps":["trace[2065285476] 'agreement among raft nodes before linearized reading' (duration: 104.934404ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:49.152362Z","caller":"traceutil/trace.go:171","msg":"trace[947622415] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"247.647297ms","start":"2026-05-12T17:23:48.904683Z","end":"2026-05-12T17:23:49.15233Z","steps":["trace[947622415] 'process raft request' (duration: 247.469493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:49.32927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.007623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:49.329564Z","caller":"traceutil/trace.go:171","msg":"trace[1086578465] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"424.02858ms","start":"2026-05-12T17:23:48.905496Z","end":"2026-05-12T17:23:49.329525Z","steps":["trace[1086578465] 'process raft request' (duration: 284.67253ms)","trace[1086578465] 'compare' (duration: 138.86902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:49.329695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:48.905432Z","time spent":"424.181923ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:49.329572Z","caller":"traceutil/trace.go:171","msg":"trace[116063108] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"423.852117ms","start":"2026-05-12T17:23:48.905701Z","end":"2026-05-12T17:23:49.329553Z","steps":["trace[116063108] 'process raft request' (duration: 423.663973ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:49.329786Z","caller":"traceutil/trace.go:171","msg":"trace[557470274] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"233.064795ms","start":"2026-05-12T17:23:49.096676Z","end":"2026-05-12T17:23:49.32974Z","steps":["trace[557470274] 'process raft request' (duration: 232.797239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:49.329825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:48.905684Z","time spent":"424.112232ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:49.578809Z","caller":"traceutil/trace.go:171","msg":"trace[1298310163] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"173.443261ms","start":"2026-05-12T17:23:49.405343Z","end":"2026-05-12T17:23:49.578786Z","steps":["trace[1298310163] 'process raft request' (duration: 88.058245ms)","trace[1298310163] 'compare' (duration: 84.929836ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:49.907983Z","caller":"traceutil/trace.go:171","msg":"trace[2072174986] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"285.848144ms","start":"2026-05-12T17:23:49.622097Z","end":"2026-05-12T17:23:49.907945Z","steps":["trace[2072174986] 'process raft request' (duration: 285.552528ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:50.003253Z","caller":"traceutil/trace.go:171","msg":"trace[1012896488] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"374.235636ms","start":"2026-05-12T17:23:49.628966Z","end":"2026-05-12T17:23:50.003202Z","steps":["trace[1012896488] 'process raft request' (duration: 314.459017ms)","trace[1012896488] 'compare' (duration: 58.925591ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:50.003488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:49.628943Z","time spent":"374.42495ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:23:50.615937Z","caller":"traceutil/trace.go:171","msg":"trace[1277079189] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26230; }","duration":"173.282827ms","start":"2026-05-12T17:23:50.442623Z","end":"2026-05-12T17:23:50.615906Z","steps":["trace[1277079189] 'read index received' (duration: 122.908659ms)","trace[1277079189] 'applied index is now lower than readState.Index' (duration: 50.372928ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:50.616058Z","caller":"traceutil/trace.go:171","msg":"trace[2115708225] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"175.441313ms","start":"2026-05-12T17:23:50.440596Z","end":"2026-05-12T17:23:50.616037Z","steps":["trace[2115708225] 'process raft request' (duration: 124.934722ms)","trace[2115708225] 'compare' (duration: 50.187953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:50.616156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.506432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-12T17:23:50.616191Z","caller":"traceutil/trace.go:171","msg":"trace[726618724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25401; }","duration":"173.573013ms","start":"2026-05-12T17:23:50.442606Z","end":"2026-05-12T17:23:50.616179Z","steps":["trace[726618724] 'agreement among raft nodes before linearized reading' (duration: 173.41353ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:50.680591Z","caller":"traceutil/trace.go:171","msg":"trace[360482122] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"235.193601ms","start":"2026-05-12T17:23:50.445371Z","end":"2026-05-12T17:23:50.680565Z","steps":["trace[360482122] 'process raft request' (duration: 235.004117ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:50.681029Z","caller":"traceutil/trace.go:171","msg":"trace[157600361] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"233.821392ms","start":"2026-05-12T17:23:50.447183Z","end":"2026-05-12T17:23:50.681004Z","steps":["trace[157600361] 'process raft request' (duration: 233.72122ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:50.681088Z","caller":"traceutil/trace.go:171","msg":"trace[2017203967] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"234.862994ms","start":"2026-05-12T17:23:50.446206Z","end":"2026-05-12T17:23:50.681069Z","steps":["trace[2017203967] 'process raft request' (duration: 234.309522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:50.835501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.050332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:23:50.835991Z","caller":"traceutil/trace.go:171","msg":"trace[1072996646] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"215.204244ms","start":"2026-05-12T17:23:50.620739Z","end":"2026-05-12T17:23:50.835943Z","steps":["trace[1072996646] 'process raft request' (duration: 113.58967ms)","trace[1072996646] 'compare' (duration: 100.845857ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:50.836282Z","caller":"traceutil/trace.go:171","msg":"trace[1639032074] linearizableReadLoop","detail":"{readStateIndex:26235; appliedIndex:26234; }","duration":"155.863983ms","start":"2026-05-12T17:23:50.680354Z","end":"2026-05-12T17:23:50.836218Z","steps":["trace[1639032074] 'read index received' (duration: 53.992304ms)","trace[1639032074] 'applied index is now lower than readState.Index' (duration: 101.867979ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:50.836353Z","caller":"traceutil/trace.go:171","msg":"trace[1184372847] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"155.963005ms","start":"2026-05-12T17:23:50.680345Z","end":"2026-05-12T17:23:50.836308Z","steps":["trace[1184372847] 'process raft request' (duration: 155.345252ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:50.836469Z","caller":"traceutil/trace.go:171","msg":"trace[1798804747] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"112.957376ms","start":"2026-05-12T17:23:50.723478Z","end":"2026-05-12T17:23:50.836435Z","steps":["trace[1798804747] 'process raft request' (duration: 112.620419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:50.836602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.227541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-12T17:23:50.836635Z","caller":"traceutil/trace.go:171","msg":"trace[432805072] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25407; }","duration":"156.328533ms","start":"2026-05-12T17:23:50.680296Z","end":"2026-05-12T17:23:50.836625Z","steps":["trace[432805072] 'agreement among raft nodes before linearized reading' (duration: 156.117008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:51.150043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.327499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:23:51.150156Z","caller":"traceutil/trace.go:171","msg":"trace[1385241444] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25407; }","duration":"210.540723ms","start":"2026-05-12T17:23:50.939592Z","end":"2026-05-12T17:23:51.150132Z","steps":["trace[1385241444] 'range keys from in-memory index tree' (duration: 210.118175ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:51.43467Z","caller":"traceutil/trace.go:171","msg":"trace[1012780597] linearizableReadLoop","detail":"{readStateIndex:26238; appliedIndex:26237; }","duration":"275.262888ms","start":"2026-05-12T17:23:51.159353Z","end":"2026-05-12T17:23:51.434615Z","steps":["trace[1012780597] 'read index received' (duration: 275.184427ms)","trace[1012780597] 'applied index is now lower than readState.Index' (duration: 76.951µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:51.434713Z","caller":"traceutil/trace.go:171","msg":"trace[560427157] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"276.29202ms","start":"2026-05-12T17:23:51.158378Z","end":"2026-05-12T17:23:51.43467Z","steps":["trace[560427157] 'process raft request' (duration: 276.029565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:51.435179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.780369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18365"} {"level":"info","ts":"2026-05-12T17:23:51.435366Z","caller":"traceutil/trace.go:171","msg":"trace[340420902] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25408; }","duration":"275.997653ms","start":"2026-05-12T17:23:51.159336Z","end":"2026-05-12T17:23:51.435334Z","steps":["trace[340420902] 'agreement among raft nodes before linearized reading' (duration: 275.521413ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:51.539925Z","caller":"traceutil/trace.go:171","msg":"trace[905822990] linearizableReadLoop","detail":"{readStateIndex:26241; appliedIndex:26238; }","duration":"105.154829ms","start":"2026-05-12T17:23:51.434752Z","end":"2026-05-12T17:23:51.539907Z","steps":["trace[905822990] 'read index received' (duration: 41.234603ms)","trace[905822990] 'applied index is now lower than readState.Index' (duration: 63.919026ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:51.540046Z","caller":"traceutil/trace.go:171","msg":"trace[1370929018] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"205.416834ms","start":"2026-05-12T17:23:51.334572Z","end":"2026-05-12T17:23:51.539989Z","steps":["trace[1370929018] 'process raft request' (duration: 141.503958ms)","trace[1370929018] 'compare' (duration: 63.604569ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:51.540168Z","caller":"traceutil/trace.go:171","msg":"trace[69710206] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"204.211597ms","start":"2026-05-12T17:23:51.335942Z","end":"2026-05-12T17:23:51.540153Z","steps":["trace[69710206] 'process raft request' (duration: 203.895181ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:51.54034Z","caller":"traceutil/trace.go:171","msg":"trace[108806375] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"203.071324ms","start":"2026-05-12T17:23:51.337218Z","end":"2026-05-12T17:23:51.540289Z","steps":["trace[108806375] 'process raft request' (duration: 202.650864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:51.540503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.848307ms","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:6"} {"level":"info","ts":"2026-05-12T17:23:51.540599Z","caller":"traceutil/trace.go:171","msg":"trace[624504590] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25411; }","duration":"380.960629ms","start":"2026-05-12T17:23:51.159615Z","end":"2026-05-12T17:23:51.540576Z","steps":["trace[624504590] 'agreement among raft nodes before linearized reading' (duration: 380.748305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:51.540639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:51.1596Z","time spent":"381.03065ms","remote":"127.0.0.1:48884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-05-12T17:23:52.024363Z","caller":"traceutil/trace.go:171","msg":"trace[1472742125] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"175.460813ms","start":"2026-05-12T17:23:51.84887Z","end":"2026-05-12T17:23:52.024331Z","steps":["trace[1472742125] 'process raft request' (duration: 148.19007ms)","trace[1472742125] 'compare' (duration: 26.956176ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:52.539864Z","caller":"traceutil/trace.go:171","msg":"trace[1113815005] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"109.544963ms","start":"2026-05-12T17:23:52.430289Z","end":"2026-05-12T17:23:52.539834Z","steps":["trace[1113815005] 'process raft request' (duration: 109.328289ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:53.590142Z","caller":"traceutil/trace.go:171","msg":"trace[837524466] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"150.255333ms","start":"2026-05-12T17:23:53.439851Z","end":"2026-05-12T17:23:53.590106Z","steps":["trace[837524466] 'process raft request' (duration: 124.266018ms)","trace[837524466] 'compare' (duration: 25.851013ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:53.718115Z","caller":"traceutil/trace.go:171","msg":"trace[2054675160] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"172.978631ms","start":"2026-05-12T17:23:53.5451Z","end":"2026-05-12T17:23:53.718078Z","steps":["trace[2054675160] 'process raft request' (duration: 166.489342ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:53.718368Z","caller":"traceutil/trace.go:171","msg":"trace[353117387] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"171.257493ms","start":"2026-05-12T17:23:53.54709Z","end":"2026-05-12T17:23:53.718347Z","steps":["trace[353117387] 'process raft request' (duration: 170.959757ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:53.71815Z","caller":"traceutil/trace.go:171","msg":"trace[404222110] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"172.162673ms","start":"2026-05-12T17:23:53.545944Z","end":"2026-05-12T17:23:53.718107Z","steps":["trace[404222110] 'process raft request' (duration: 172.069791ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:53.949945Z","caller":"traceutil/trace.go:171","msg":"trace[1768955522] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"226.867733ms","start":"2026-05-12T17:23:53.723046Z","end":"2026-05-12T17:23:53.949913Z","steps":["trace[1768955522] 'process raft request' (duration: 143.925668ms)","trace[1768955522] 'compare' (duration: 82.662539ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:23:57.007616Z","caller":"traceutil/trace.go:171","msg":"trace[2057917783] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"137.534351ms","start":"2026-05-12T17:23:56.870059Z","end":"2026-05-12T17:23:57.007594Z","steps":["trace[2057917783] 'process raft request' (duration: 137.389538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:57.239443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.875258ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T17:23:57.239744Z","caller":"traceutil/trace.go:171","msg":"trace[1843361159] linearizableReadLoop","detail":"{readStateIndex:26280; appliedIndex:26278; }","duration":"352.48262ms","start":"2026-05-12T17:23:56.88722Z","end":"2026-05-12T17:23:57.239703Z","steps":["trace[1843361159] 'read index received' (duration: 120.244272ms)","trace[1843361159] 'applied index is now lower than readState.Index' (duration: 232.234608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:23:57.240184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.909919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-12T17:23:57.24028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.905176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-12T17:23:57.24032Z","caller":"traceutil/trace.go:171","msg":"trace[1283884590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25448; }","duration":"353.104063ms","start":"2026-05-12T17:23:56.887188Z","end":"2026-05-12T17:23:57.240292Z","steps":["trace[1283884590] 'agreement among raft nodes before linearized reading' (duration: 352.678864ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:57.240334Z","caller":"traceutil/trace.go:171","msg":"trace[1324681563] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25449; }","duration":"235.005038ms","start":"2026-05-12T17:23:57.005308Z","end":"2026-05-12T17:23:57.240313Z","steps":["trace[1324681563] 'agreement among raft nodes before linearized reading' (duration: 234.743802ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:57.240238Z","caller":"traceutil/trace.go:171","msg":"trace[251691212] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"186.295734ms","start":"2026-05-12T17:23:57.053899Z","end":"2026-05-12T17:23:57.240195Z","steps":["trace[251691212] 'process raft request' (duration: 185.756293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:23:57.240413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:23:56.887172Z","time spent":"353.212395ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T17:24:18.400979Z","caller":"traceutil/trace.go:171","msg":"trace[1784040509] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"147.325292ms","start":"2026-05-12T17:24:18.253601Z","end":"2026-05-12T17:24:18.400926Z","steps":["trace[1784040509] 'process raft request' (duration: 130.660606ms)","trace[1784040509] 'compare' (duration: 16.487053ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:19.108733Z","caller":"traceutil/trace.go:171","msg":"trace[1304004623] linearizableReadLoop","detail":"{readStateIndex:26453; appliedIndex:26452; }","duration":"210.066574ms","start":"2026-05-12T17:24:18.89864Z","end":"2026-05-12T17:24:19.108707Z","steps":["trace[1304004623] 'read index received' (duration: 209.977542ms)","trace[1304004623] 'applied index is now lower than readState.Index' (duration: 87.972µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:19.108816Z","caller":"traceutil/trace.go:171","msg":"trace[1718069023] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"304.367651ms","start":"2026-05-12T17:24:18.804418Z","end":"2026-05-12T17:24:19.108786Z","steps":["trace[1718069023] 'process raft request' (duration: 304.124686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:19.109022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.804401Z","time spent":"304.539106ms","remote":"127.0.0.1:48876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:24:19.109065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.39325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T17:24:19.109164Z","caller":"traceutil/trace.go:171","msg":"trace[423717895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25617; }","duration":"210.551394ms","start":"2026-05-12T17:24:18.898588Z","end":"2026-05-12T17:24:19.109139Z","steps":["trace[423717895] 'agreement among raft nodes before linearized reading' (duration: 210.237067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:19.132489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.371122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-12T17:24:19.132486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.151789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-12T17:24:19.132685Z","caller":"traceutil/trace.go:171","msg":"trace[1305523380] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25618; }","duration":"190.540846ms","start":"2026-05-12T17:24:18.942043Z","end":"2026-05-12T17:24:19.132584Z","steps":["trace[1305523380] 'agreement among raft nodes before linearized reading' (duration: 190.305961ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:19.132764Z","caller":"traceutil/trace.go:171","msg":"trace[1166635914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25618; }","duration":"213.484347ms","start":"2026-05-12T17:24:18.919243Z","end":"2026-05-12T17:24:19.132727Z","steps":["trace[1166635914] 'agreement among raft nodes before linearized reading' (duration: 212.986646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:19.132807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.973146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-12T17:24:19.132888Z","caller":"traceutil/trace.go:171","msg":"trace[2036358211] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25618; }","duration":"213.110949ms","start":"2026-05-12T17:24:18.919753Z","end":"2026-05-12T17:24:19.132864Z","steps":["trace[2036358211] 'agreement among raft nodes before linearized reading' (duration: 212.69829ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:28.025446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22745} {"level":"info","ts":"2026-05-12T17:24:28.167996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22745,"took":"137.88968ms","hash":4137925124,"current-db-size-bytes":57602048,"current-db-size":"58 MB","current-db-size-in-use-bytes":35123200,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-12T17:24:28.168062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4137925124,"revision":22745,"compact-revision":20020} {"level":"info","ts":"2026-05-12T17:25:03.613639Z","caller":"traceutil/trace.go:171","msg":"trace[96829575] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"123.300378ms","start":"2026-05-12T17:25:03.490302Z","end":"2026-05-12T17:25:03.613603Z","steps":["trace[96829575] 'process raft request' (duration: 123.022422ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:11.739893Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069b4.wal"} {"level":"info","ts":"2026-05-12T17:29:28.032717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25685} {"level":"info","ts":"2026-05-12T17:29:28.188967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25685,"took":"147.785076ms","hash":4139737503,"current-db-size-bytes":58445824,"current-db-size":"58 MB","current-db-size-in-use-bytes":37470208,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-12T17:29:28.189041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4139737503,"revision":25685,"compact-revision":22745} {"level":"info","ts":"2026-05-12T17:30:34.193881Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cf904ec39d3e333","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:30:34.200843Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-12T17:30:34.201038Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-12T17:34:28.039307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28553} {"level":"info","ts":"2026-05-12T17:34:28.179987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28553,"took":"135.542635ms","hash":717627129,"current-db-size-bytes":58445824,"current-db-size":"58 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T17:34:28.180074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":717627129,"revision":28553,"compact-revision":25685} {"level":"info","ts":"2026-05-12T17:39:28.046289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30772} {"level":"info","ts":"2026-05-12T17:39:28.168882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30772,"took":"117.06171ms","hash":240390464,"current-db-size-bytes":58445824,"current-db-size":"58 MB","current-db-size-in-use-bytes":30998528,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T17:39:28.168957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":240390464,"revision":30772,"compact-revision":28553} {"level":"info","ts":"2026-05-12T17:44:28.052602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33038} {"level":"info","ts":"2026-05-12T17:44:28.223061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33038,"took":"163.789817ms","hash":1848660565,"current-db-size-bytes":59490304,"current-db-size":"60 MB","current-db-size-in-use-bytes":31555584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T17:44:28.223133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1848660565,"revision":33038,"compact-revision":30772} {"level":"info","ts":"2026-05-12T17:49:28.084532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35327} {"level":"info","ts":"2026-05-12T17:49:28.23055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35327,"took":"141.145122ms","hash":4166292543,"current-db-size-bytes":60342272,"current-db-size":"60 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T17:49:28.230644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4166292543,"revision":35327,"compact-revision":33038} {"level":"info","ts":"2026-05-12T17:52:05.345452Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cf904ec39d3e333","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:52:05.351028Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-12T17:52:05.351217Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}