{"level":"warn","ts":"2026-05-19T04:02:02.704744Z","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-19T04:02:02.704861Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-19T04:02:02.704935Z","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-19T04:02:02.704952Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-19T04:02:02.704977Z","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-19T04:02:02.705891Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-05-19T04:02:02.705988Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-19T04:02:02.710041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.787735ms"} {"level":"info","ts":"2026-05-19T04:02:02.716033Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-05-19T04:02:02.71621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T04:02:02.716256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-05-19T04:02:02.716279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T04:02:02.716303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-05-19T04:02:02.716371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-05-19T04:02:02.722509Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T04:02:02.724008Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T04:02:02.725527Z","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-19T04:02:02.727792Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T04:02:02.727975Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T04:02:02.728044Z","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-19T04:02:02.728137Z","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-19T04:02:02.72815Z","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-19T04:02:02.729801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-05-19T04:02:02.73002Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-19T04:02:02.733746Z","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-19T04:02:02.734071Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T04:02:02.734139Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T04:02:02.734117Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-19T04:02:02.734241Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-19T04:02:03.417482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T04:02:03.417563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T04:02:03.417589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-05-19T04:02:03.41761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-05-19T04:02:03.417624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-19T04:02:03.417645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-05-19T04:02:03.417663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-19T04:02:03.418634Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:03.419433Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T04:02:03.419427Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T04:02:03.419672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T04:02:03.41969Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:03.419851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:03.419911Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T04:02:03.42009Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T04:02:03.420153Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T04:02:03.423437Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-05-19T04:02:03.42355Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-19T04:02:31.618142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.143204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:02:31.618299Z","caller":"traceutil/trace.go:171","msg":"trace[1763172501] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:435; }","duration":"141.348678ms","start":"2026-05-19T04:02:31.476929Z","end":"2026-05-19T04:02:31.618278Z","steps":["trace[1763172501] 'range keys from in-memory index tree' (duration: 141.050492ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:02:39.854438Z","caller":"traceutil/trace.go:171","msg":"trace[479078375] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"318.702369ms","start":"2026-05-19T04:02:39.535707Z","end":"2026-05-19T04:02:39.854409Z","steps":["trace[479078375] 'process raft request' (duration: 318.016445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:02:39.855112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:02:39.535675Z","time spent":"318.847993ms","remote":"127.0.0.1:33950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:02:40.062168Z","caller":"traceutil/trace.go:171","msg":"trace[816945846] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"145.135675ms","start":"2026-05-19T04:02:39.91701Z","end":"2026-05-19T04:02:40.062145Z","steps":["trace[816945846] 'process raft request' (duration: 97.671171ms)","trace[816945846] 'compare' (duration: 47.174398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:02:40.558159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.513118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T04:02:40.558705Z","caller":"traceutil/trace.go:171","msg":"trace[1159213915] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"464.63137ms","start":"2026-05-19T04:02:40.094018Z","end":"2026-05-19T04:02:40.55865Z","steps":["trace[1159213915] 'process raft request' (duration: 237.349826ms)","trace[1159213915] 'compare' (duration: 225.156961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:02:40.561359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:02:40.093997Z","time spent":"467.177142ms","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:02:59.04729Z","caller":"traceutil/trace.go:171","msg":"trace[1353436603] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"130.83149ms","start":"2026-05-19T04:02:58.916437Z","end":"2026-05-19T04:02:59.047268Z","steps":["trace[1353436603] 'process raft request' (duration: 130.677937ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:04:10.156024Z","caller":"traceutil/trace.go:171","msg":"trace[2011682282] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"228.394519ms","start":"2026-05-19T04:04:09.927596Z","end":"2026-05-19T04:04:10.15599Z","steps":["trace[2011682282] 'process raft request' (duration: 218.547938ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:04:10.156577Z","caller":"traceutil/trace.go:171","msg":"trace[1227560596] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"228.96244ms","start":"2026-05-19T04:04:09.92759Z","end":"2026-05-19T04:04:10.156552Z","steps":["trace[1227560596] 'process raft request' (duration: 228.48045ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:04:55.198782Z","caller":"traceutil/trace.go:171","msg":"trace[1830973278] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"110.333902ms","start":"2026-05-19T04:04:55.088408Z","end":"2026-05-19T04:04:55.198742Z","steps":["trace[1830973278] 'process raft request' (duration: 14.792146ms)","trace[1830973278] 'compare' (duration: 95.386433ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:05:52.782317Z","caller":"traceutil/trace.go:171","msg":"trace[1726723333] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"119.492466ms","start":"2026-05-19T04:05:52.662797Z","end":"2026-05-19T04:05:52.782289Z","steps":["trace[1726723333] 'process raft request' (duration: 119.326563ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:05:52.78357Z","caller":"traceutil/trace.go:171","msg":"trace[395470367] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"120.71663ms","start":"2026-05-19T04:05:52.662836Z","end":"2026-05-19T04:05:52.783552Z","steps":["trace[395470367] 'process raft request' (duration: 120.535266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:05:52.78366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.382833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:05:52.783703Z","caller":"traceutil/trace.go:171","msg":"trace[1390030377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2591; }","duration":"110.466455ms","start":"2026-05-19T04:05:52.673227Z","end":"2026-05-19T04:05:52.783694Z","steps":["trace[1390030377] 'agreement among raft nodes before linearized reading' (duration: 110.370353ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:05:52.783547Z","caller":"traceutil/trace.go:171","msg":"trace[1501087788] linearizableReadLoop","detail":"{readStateIndex:2689; appliedIndex:2686; }","duration":"110.25485ms","start":"2026-05-19T04:05:52.673269Z","end":"2026-05-19T04:05:52.783524Z","steps":["trace[1501087788] 'read index received' (duration: 108.898394ms)","trace[1501087788] 'applied index is now lower than readState.Index' (duration: 1.355626ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:05:52.783855Z","caller":"traceutil/trace.go:171","msg":"trace[999736183] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"116.632568ms","start":"2026-05-19T04:05:52.667217Z","end":"2026-05-19T04:05:52.783849Z","steps":["trace[999736183] 'process raft request' (duration: 116.269141ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:12.258707Z","caller":"traceutil/trace.go:171","msg":"trace[56372673] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"132.284247ms","start":"2026-05-19T04:10:12.126398Z","end":"2026-05-19T04:10:12.258682Z","steps":["trace[56372673] 'process raft request' (duration: 132.126484ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:12.294771Z","caller":"traceutil/trace.go:171","msg":"trace[213312547] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"168.043071ms","start":"2026-05-19T04:10:12.126702Z","end":"2026-05-19T04:10:12.294745Z","steps":["trace[213312547] 'process raft request' (duration: 167.968959ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:12.294802Z","caller":"traceutil/trace.go:171","msg":"trace[983440423] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"168.188564ms","start":"2026-05-19T04:10:12.126585Z","end":"2026-05-19T04:10:12.294773Z","steps":["trace[983440423] 'process raft request' (duration: 167.933898ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:10:12.29495Z","caller":"traceutil/trace.go:171","msg":"trace[253936550] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"119.978594ms","start":"2026-05-19T04:10:12.17494Z","end":"2026-05-19T04:10:12.294919Z","steps":["trace[253936550] 'process raft request' (duration: 119.76842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:10:14.640166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.737905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:10:14.64023Z","caller":"traceutil/trace.go:171","msg":"trace[208399345] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:4945; }","duration":"110.868587ms","start":"2026-05-19T04:10:14.529348Z","end":"2026-05-19T04:10:14.640216Z","steps":["trace[208399345] 'count revisions from in-memory index tree' (duration: 110.573341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:10:31.319678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.230026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:10:31.31978Z","caller":"traceutil/trace.go:171","msg":"trace[87093228] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5297; }","duration":"227.401859ms","start":"2026-05-19T04:10:31.092358Z","end":"2026-05-19T04:10:31.31976Z","steps":["trace[87093228] 'count revisions from in-memory index tree' (duration: 227.126264ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:12:03.452168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-05-19T04:12:03.513593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"60.217775ms","hash":2785320951,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":34144256,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T04:12:03.513656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2785320951,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-05-19T04:16:42.428475Z","caller":"traceutil/trace.go:171","msg":"trace[1306761259] linearizableReadLoop","detail":"{readStateIndex:8445; appliedIndex:8444; }","duration":"181.586099ms","start":"2026-05-19T04:16:42.246864Z","end":"2026-05-19T04:16:42.42845Z","steps":["trace[1306761259] 'read index received' (duration: 181.403975ms)","trace[1306761259] 'applied index is now lower than readState.Index' (duration: 180.584µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:16:42.428531Z","caller":"traceutil/trace.go:171","msg":"trace[369920162] transaction","detail":"{read_only:false; response_revision:8158; number_of_response:1; }","duration":"192.026029ms","start":"2026-05-19T04:16:42.236478Z","end":"2026-05-19T04:16:42.428504Z","steps":["trace[369920162] 'process raft request' (duration: 191.789574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:16:42.4287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.820663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T04:16:42.42876Z","caller":"traceutil/trace.go:171","msg":"trace[1426047036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8158; }","duration":"181.918616ms","start":"2026-05-19T04:16:42.246831Z","end":"2026-05-19T04:16:42.428749Z","steps":["trace[1426047036] 'agreement among raft nodes before linearized reading' (duration: 181.743942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:16:42.601067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.036254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T04:16:42.601265Z","caller":"traceutil/trace.go:171","msg":"trace[955981033] transaction","detail":"{read_only:false; response_revision:8160; number_of_response:1; }","duration":"168.811011ms","start":"2026-05-19T04:16:42.432432Z","end":"2026-05-19T04:16:42.601243Z","steps":["trace[955981033] 'process raft request' (duration: 168.746559ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:16:42.601285Z","caller":"traceutil/trace.go:171","msg":"trace[1398848476] linearizableReadLoop","detail":"{readStateIndex:8446; appliedIndex:8445; }","duration":"172.696059ms","start":"2026-05-19T04:16:42.428555Z","end":"2026-05-19T04:16:42.601251Z","steps":["trace[1398848476] 'read index received' (duration: 11.344399ms)","trace[1398848476] 'applied index is now lower than readState.Index' (duration: 161.34968ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:16:42.601352Z","caller":"traceutil/trace.go:171","msg":"trace[1512888407] transaction","detail":"{read_only:false; response_revision:8159; number_of_response:1; }","duration":"243.654512ms","start":"2026-05-19T04:16:42.357661Z","end":"2026-05-19T04:16:42.601316Z","steps":["trace[1512888407] 'process raft request' (duration: 82.255811ms)","trace[1512888407] 'compare' (duration: 160.905431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:16:42.60154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.362677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T04:16:42.601578Z","caller":"traceutil/trace.go:171","msg":"trace[752873067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8160; }","duration":"346.49502ms","start":"2026-05-19T04:16:42.255071Z","end":"2026-05-19T04:16:42.601566Z","steps":["trace[752873067] 'agreement among raft nodes before linearized reading' (duration: 346.281646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:16:42.601611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:16:42.255052Z","time spent":"346.550562ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T04:16:42.601633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.717817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9gqz7\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-19T04:16:42.601705Z","caller":"traceutil/trace.go:171","msg":"trace[836348726] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9gqz7; range_end:; response_count:1; response_revision:8160; }","duration":"259.789469ms","start":"2026-05-19T04:16:42.341898Z","end":"2026-05-19T04:16:42.601687Z","steps":["trace[836348726] 'agreement among raft nodes before linearized reading' (duration: 259.534973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:16:54.029869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.389358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T04:16:54.030111Z","caller":"traceutil/trace.go:171","msg":"trace[215268006] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"287.699299ms","start":"2026-05-19T04:16:53.742373Z","end":"2026-05-19T04:16:54.030072Z","steps":["trace[215268006] 'process raft request' (duration: 181.993265ms)","trace[215268006] 'compare' (duration: 105.083622ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:16:54.030283Z","caller":"traceutil/trace.go:171","msg":"trace[592069564] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"211.248266ms","start":"2026-05-19T04:16:53.819019Z","end":"2026-05-19T04:16:54.030267Z","steps":["trace[592069564] 'process raft request' (duration: 211.159884ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:16:54.768093Z","caller":"traceutil/trace.go:171","msg":"trace[2108331795] linearizableReadLoop","detail":"{readStateIndex:8514; appliedIndex:8513; }","duration":"108.799927ms","start":"2026-05-19T04:16:54.659264Z","end":"2026-05-19T04:16:54.768064Z","steps":["trace[2108331795] 'read index received' (duration: 104.50914ms)","trace[2108331795] 'applied index is now lower than readState.Index' (duration: 4.289337ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:16:54.768182Z","caller":"traceutil/trace.go:171","msg":"trace[183510986] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"125.646198ms","start":"2026-05-19T04:16:54.642499Z","end":"2026-05-19T04:16:54.768145Z","steps":["trace[183510986] 'process raft request' (duration: 121.33527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:16:54.768316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.032492ms","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-19T04:16:54.768386Z","caller":"traceutil/trace.go:171","msg":"trace[783810818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8225; }","duration":"109.138475ms","start":"2026-05-19T04:16:54.659229Z","end":"2026-05-19T04:16:54.768368Z","steps":["trace[783810818] 'agreement among raft nodes before linearized reading' (duration: 108.960041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:16:54.768364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.184183ms","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-19T04:16:54.769063Z","caller":"traceutil/trace.go:171","msg":"trace[1433067313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8225; }","duration":"102.946978ms","start":"2026-05-19T04:16:54.666102Z","end":"2026-05-19T04:16:54.769049Z","steps":["trace[1433067313] 'agreement among raft nodes before linearized reading' (duration: 102.106531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:03.459406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6133} {"level":"info","ts":"2026-05-19T04:17:03.553355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6133,"took":"91.684771ms","hash":2266874000,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":22892544,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-19T04:17:03.553428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2266874000,"revision":6133,"compact-revision":2995} {"level":"info","ts":"2026-05-19T04:17:24.188208Z","caller":"traceutil/trace.go:171","msg":"trace[367264889] linearizableReadLoop","detail":"{readStateIndex:8733; appliedIndex:8732; }","duration":"167.200367ms","start":"2026-05-19T04:17:24.020976Z","end":"2026-05-19T04:17:24.188177Z","steps":["trace[367264889] 'read index received' (duration: 85.245372ms)","trace[367264889] 'applied index is now lower than readState.Index' (duration: 81.953815ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:17:24.188357Z","caller":"traceutil/trace.go:171","msg":"trace[832275568] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"348.64569ms","start":"2026-05-19T04:17:23.839693Z","end":"2026-05-19T04:17:24.188338Z","steps":["trace[832275568] 'process raft request' (duration: 266.510512ms)","trace[832275568] 'compare' (duration: 81.72408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:17:24.188439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.443902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-19T04:17:24.188473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:23.839669Z","time spent":"348.730532ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:17:24.188525Z","caller":"traceutil/trace.go:171","msg":"trace[753080448] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8435; }","duration":"167.600564ms","start":"2026-05-19T04:17:24.020903Z","end":"2026-05-19T04:17:24.188503Z","steps":["trace[753080448] 'agreement among raft nodes before linearized reading' (duration: 167.472342ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:24.252418Z","caller":"traceutil/trace.go:171","msg":"trace[1608408043] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"111.644065ms","start":"2026-05-19T04:17:24.14075Z","end":"2026-05-19T04:17:24.252394Z","steps":["trace[1608408043] 'process raft request' (duration: 111.447341ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:26.397438Z","caller":"traceutil/trace.go:171","msg":"trace[2013076013] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"203.692744ms","start":"2026-05-19T04:17:26.193718Z","end":"2026-05-19T04:17:26.397411Z","steps":["trace[2013076013] 'process raft request' (duration: 203.4799ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:26.490732Z","caller":"traceutil/trace.go:171","msg":"trace[938179596] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"233.610967ms","start":"2026-05-19T04:17:26.257101Z","end":"2026-05-19T04:17:26.490712Z","steps":["trace[938179596] 'process raft request' (duration: 233.445904ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:26.490925Z","caller":"traceutil/trace.go:171","msg":"trace[252755071] linearizableReadLoop","detail":"{readStateIndex:8746; appliedIndex:8743; }","duration":"230.73336ms","start":"2026-05-19T04:17:26.260165Z","end":"2026-05-19T04:17:26.490898Z","steps":["trace[252755071] 'read index received' (duration: 136.973357ms)","trace[252755071] 'applied index is now lower than readState.Index' (duration: 93.757803ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:17:26.49102Z","caller":"traceutil/trace.go:171","msg":"trace[2037941315] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"231.874193ms","start":"2026-05-19T04:17:26.259127Z","end":"2026-05-19T04:17:26.491001Z","steps":["trace[2037941315] 'process raft request' (duration: 231.534666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:26.491179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.988925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T04:17:26.491215Z","caller":"traceutil/trace.go:171","msg":"trace[620053352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8448; }","duration":"231.102087ms","start":"2026-05-19T04:17:26.260101Z","end":"2026-05-19T04:17:26.491203Z","steps":["trace[620053352] 'agreement among raft nodes before linearized reading' (duration: 230.929663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:36.073793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.118973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9qtwb\" ","response":"range_response_count:1 size:4668"} {"level":"info","ts":"2026-05-19T04:17:36.073969Z","caller":"traceutil/trace.go:171","msg":"trace[1411917104] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9qtwb; range_end:; response_count:1; response_revision:8537; }","duration":"103.330797ms","start":"2026-05-19T04:17:35.970617Z","end":"2026-05-19T04:17:36.073948Z","steps":["trace[1411917104] 'range keys from in-memory index tree' (duration: 102.973699ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:36.076564Z","caller":"traceutil/trace.go:171","msg":"trace[2010878339] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"104.975621ms","start":"2026-05-19T04:17:35.971564Z","end":"2026-05-19T04:17:36.07654Z","steps":["trace[2010878339] 'process raft request' (duration: 104.813188ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:36.84493Z","caller":"traceutil/trace.go:171","msg":"trace[666629652] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"104.848087ms","start":"2026-05-19T04:17:36.740041Z","end":"2026-05-19T04:17:36.844889Z","steps":["trace[666629652] 'process raft request' (duration: 104.621693ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:37.225044Z","caller":"traceutil/trace.go:171","msg":"trace[1254574906] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"289.240872ms","start":"2026-05-19T04:17:36.935776Z","end":"2026-05-19T04:17:37.225017Z","steps":["trace[1254574906] 'process raft request' (duration: 289.051878ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:37.520495Z","caller":"traceutil/trace.go:171","msg":"trace[1110695472] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"572.014561ms","start":"2026-05-19T04:17:36.94845Z","end":"2026-05-19T04:17:37.520464Z","steps":["trace[1110695472] 'process raft request' (duration: 571.770226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.520668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:36.948434Z","time spent":"572.145864ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:17:37.520861Z","caller":"traceutil/trace.go:171","msg":"trace[6110876] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"566.644583ms","start":"2026-05-19T04:17:36.954169Z","end":"2026-05-19T04:17:37.520814Z","steps":["trace[6110876] 'process raft request' (duration: 566.241115ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:37.520925Z","caller":"traceutil/trace.go:171","msg":"trace[1289894323] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"446.210931ms","start":"2026-05-19T04:17:37.074697Z","end":"2026-05-19T04:17:37.520908Z","steps":["trace[1289894323] 'process raft request' (duration: 445.955796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.520996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:37.074649Z","time spent":"446.312333ms","remote":"127.0.0.1:33936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T04:17:37.520911Z","caller":"traceutil/trace.go:171","msg":"trace[1546768061] linearizableReadLoop","detail":"{readStateIndex:8855; appliedIndex:8852; }","duration":"539.137037ms","start":"2026-05-19T04:17:36.981757Z","end":"2026-05-19T04:17:37.520895Z","steps":["trace[1546768061] 'read index received' (duration: 243.14919ms)","trace[1546768061] 'applied index is now lower than readState.Index' (duration: 295.984826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:17:37.521093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.336571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T04:17:37.521093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:36.954155Z","time spent":"566.815116ms","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:17:37.521127Z","caller":"traceutil/trace.go:171","msg":"trace[481890275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8552; }","duration":"539.412712ms","start":"2026-05-19T04:17:36.981707Z","end":"2026-05-19T04:17:37.52112Z","steps":["trace[481890275] 'agreement among raft nodes before linearized reading' (duration: 539.230078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.521149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:36.981665Z","time spent":"539.479553ms","remote":"127.0.0.1:34152","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-19T04:17:37.521371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.53583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T04:17:37.521396Z","caller":"traceutil/trace.go:171","msg":"trace[1986526689] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8552; }","duration":"200.609801ms","start":"2026-05-19T04:17:37.320776Z","end":"2026-05-19T04:17:37.521386Z","steps":["trace[1986526689] 'agreement among raft nodes before linearized reading' (duration: 200.56915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.521394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.879994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"warn","ts":"2026-05-19T04:17:37.521394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.61871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T04:17:37.521465Z","caller":"traceutil/trace.go:171","msg":"trace[955299093] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8552; }","duration":"287.023826ms","start":"2026-05-19T04:17:37.23442Z","end":"2026-05-19T04:17:37.521444Z","steps":["trace[955299093] 'agreement among raft nodes before linearized reading' (duration: 286.76194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:17:37.52154Z","caller":"traceutil/trace.go:171","msg":"trace[1299735418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8552; }","duration":"503.822804ms","start":"2026-05-19T04:17:37.017666Z","end":"2026-05-19T04:17:37.521488Z","steps":["trace[1299735418] 'agreement among raft nodes before linearized reading' (duration: 503.509097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.521601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:37.01763Z","time spent":"503.961237ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T04:17:37.52176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.019932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9gqz7\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-19T04:17:37.522557Z","caller":"traceutil/trace.go:171","msg":"trace[816514474] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9gqz7; range_end:; response_count:1; response_revision:8552; }","duration":"466.875879ms","start":"2026-05-19T04:17:37.055659Z","end":"2026-05-19T04:17:37.522535Z","steps":["trace[816514474] 'agreement among raft nodes before linearized reading' (duration: 465.812938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.521435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.183721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9qtwb\" ","response":"range_response_count:1 size:4653"} {"level":"warn","ts":"2026-05-19T04:17:37.521604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.758727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-19T04:17:37.5227Z","caller":"traceutil/trace.go:171","msg":"trace[690825791] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8552; }","duration":"264.90706ms","start":"2026-05-19T04:17:37.257781Z","end":"2026-05-19T04:17:37.522689Z","steps":["trace[690825791] 'agreement among raft nodes before linearized reading' (duration: 263.703036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.522825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:37.055632Z","time spent":"467.136174ms","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6468,"request content":"key:\"/registry/pods/openstack/keepalived-9gqz7\" "} {"level":"info","ts":"2026-05-19T04:17:37.522865Z","caller":"traceutil/trace.go:171","msg":"trace[2140007399] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9qtwb; range_end:; response_count:1; response_revision:8552; }","duration":"447.668941ms","start":"2026-05-19T04:17:37.075165Z","end":"2026-05-19T04:17:37.522834Z","steps":["trace[2140007399] 'agreement among raft nodes before linearized reading' (duration: 446.161781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:17:37.523409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:17:37.075133Z","time spent":"447.809683ms","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4676,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9qtwb\" "} {"level":"warn","ts":"2026-05-19T04:17:38.052624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.4295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T04:17:38.052802Z","caller":"traceutil/trace.go:171","msg":"trace[316939339] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"151.484789ms","start":"2026-05-19T04:17:37.901295Z","end":"2026-05-19T04:17:38.05278Z","steps":["trace[316939339] 'process raft request' (duration: 41.793414ms)","trace[316939339] 'compare' (duration: 109.227346ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:18:27.09234Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002453.wal"} {"level":"info","ts":"2026-05-19T04:18:41.941659Z","caller":"traceutil/trace.go:171","msg":"trace[873098342] transaction","detail":"{read_only:false; response_revision:9229; number_of_response:1; }","duration":"100.208155ms","start":"2026-05-19T04:18:41.841426Z","end":"2026-05-19T04:18:41.941634Z","steps":["trace[873098342] 'process raft request' (duration: 99.994189ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:19:44.47701Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T04:19:44.484408Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T04:19:44.484496Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T04:22:03.465915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8276} {"level":"info","ts":"2026-05-19T04:22:03.541932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8276,"took":"74.25429ms","hash":2776048849,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":25321472,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-19T04:22:03.541998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2776048849,"revision":8276,"compact-revision":6133} {"level":"info","ts":"2026-05-19T04:22:19.22293Z","caller":"traceutil/trace.go:171","msg":"trace[207359897] transaction","detail":"{read_only:false; response_revision:11045; number_of_response:1; }","duration":"121.290459ms","start":"2026-05-19T04:22:19.101605Z","end":"2026-05-19T04:22:19.222895Z","steps":["trace[207359897] 'process raft request' (duration: 120.82327ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:22:41.750372Z","caller":"traceutil/trace.go:171","msg":"trace[1759191770] linearizableReadLoop","detail":"{readStateIndex:11551; appliedIndex:11550; }","duration":"138.367466ms","start":"2026-05-19T04:22:41.611976Z","end":"2026-05-19T04:22:41.750343Z","steps":["trace[1759191770] 'read index received' (duration: 129.291412ms)","trace[1759191770] 'applied index is now lower than readState.Index' (duration: 9.074054ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:22:41.750416Z","caller":"traceutil/trace.go:171","msg":"trace[1150016657] transaction","detail":"{read_only:false; response_revision:11173; number_of_response:1; }","duration":"136.393836ms","start":"2026-05-19T04:22:41.614Z","end":"2026-05-19T04:22:41.750394Z","steps":["trace[1150016657] 'process raft request' (duration: 136.336424ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:22:41.750416Z","caller":"traceutil/trace.go:171","msg":"trace[1706613988] transaction","detail":"{read_only:false; response_revision:11171; number_of_response:1; }","duration":"159.550102ms","start":"2026-05-19T04:22:41.590808Z","end":"2026-05-19T04:22:41.750358Z","steps":["trace[1706613988] 'process raft request' (duration: 150.467019ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:22:41.750681Z","caller":"traceutil/trace.go:171","msg":"trace[1347640706] transaction","detail":"{read_only:false; response_revision:11172; number_of_response:1; }","duration":"137.306773ms","start":"2026-05-19T04:22:41.613324Z","end":"2026-05-19T04:22:41.750631Z","steps":["trace[1347640706] 'process raft request' (duration: 136.959636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:22:41.772367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.36818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9gqz7\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-19T04:22:41.776159Z","caller":"traceutil/trace.go:171","msg":"trace[1623647265] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9gqz7; range_end:; response_count:1; response_revision:11173; }","duration":"164.192426ms","start":"2026-05-19T04:22:41.611902Z","end":"2026-05-19T04:22:41.776094Z","steps":["trace[1623647265] 'agreement among raft nodes before linearized reading' (duration: 138.566739ms)","trace[1623647265] 'range keys from in-memory index tree' (duration: 20.923352ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:27:03.47497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10771} {"level":"info","ts":"2026-05-19T04:27:03.552162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10771,"took":"74.567766ms","hash":133996398,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":27361280,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-19T04:27:03.552244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":133996398,"revision":10771,"compact-revision":8276} {"level":"info","ts":"2026-05-19T04:29:10.914317Z","caller":"traceutil/trace.go:171","msg":"trace[2069408389] linearizableReadLoop","detail":"{readStateIndex:14255; appliedIndex:14254; }","duration":"104.065778ms","start":"2026-05-19T04:29:10.810226Z","end":"2026-05-19T04:29:10.914292Z","steps":["trace[2069408389] 'read index received' (duration: 103.875604ms)","trace[2069408389] 'applied index is now lower than readState.Index' (duration: 189.404µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:29:10.914522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.287443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9gqz7\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-05-19T04:29:10.914555Z","caller":"traceutil/trace.go:171","msg":"trace[778237767] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9gqz7; range_end:; response_count:1; response_revision:13782; }","duration":"104.369984ms","start":"2026-05-19T04:29:10.810175Z","end":"2026-05-19T04:29:10.914545Z","steps":["trace[778237767] 'agreement among raft nodes before linearized reading' (duration: 104.217601ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:29:10.914656Z","caller":"traceutil/trace.go:171","msg":"trace[160823746] transaction","detail":"{read_only:false; response_revision:13782; number_of_response:1; }","duration":"112.56084ms","start":"2026-05-19T04:29:10.802064Z","end":"2026-05-19T04:29:10.914625Z","steps":["trace[160823746] 'process raft request' (duration: 112.078301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:29:11.957969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.211109ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T04:29:20.415032Z","caller":"traceutil/trace.go:171","msg":"trace[1425973536] linearizableReadLoop","detail":"{readStateIndex:14424; appliedIndex:14423; }","duration":"183.10549ms","start":"2026-05-19T04:29:20.2319Z","end":"2026-05-19T04:29:20.415006Z","steps":["trace[1425973536] 'read index received' (duration: 149.408648ms)","trace[1425973536] 'applied index is now lower than readState.Index' (duration: 33.695702ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:29:20.415122Z","caller":"traceutil/trace.go:171","msg":"trace[1599092530] transaction","detail":"{read_only:false; response_revision:13948; number_of_response:1; }","duration":"230.191565ms","start":"2026-05-19T04:29:20.184891Z","end":"2026-05-19T04:29:20.415082Z","steps":["trace[1599092530] 'process raft request' (duration: 196.476842ms)","trace[1599092530] 'compare' (duration: 33.378245ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:29:20.416045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.424716ms","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-19T04:29:20.416158Z","caller":"traceutil/trace.go:171","msg":"trace[1548302442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13948; }","duration":"184.266413ms","start":"2026-05-19T04:29:20.231862Z","end":"2026-05-19T04:29:20.416128Z","steps":["trace[1548302442] 'agreement among raft nodes before linearized reading' (duration: 183.238333ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:29:20.601485Z","caller":"traceutil/trace.go:171","msg":"trace[184006157] transaction","detail":"{read_only:false; response_revision:13950; number_of_response:1; }","duration":"115.508069ms","start":"2026-05-19T04:29:20.485959Z","end":"2026-05-19T04:29:20.601467Z","steps":["trace[184006157] 'process raft request' (duration: 115.473058ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:29:20.601796Z","caller":"traceutil/trace.go:171","msg":"trace[1816020426] linearizableReadLoop","detail":"{readStateIndex:14425; appliedIndex:14424; }","duration":"180.340303ms","start":"2026-05-19T04:29:20.421425Z","end":"2026-05-19T04:29:20.601765Z","steps":["trace[1816020426] 'read index received' (duration: 135.395413ms)","trace[1816020426] 'applied index is now lower than readState.Index' (duration: 44.94176ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:29:20.601863Z","caller":"traceutil/trace.go:171","msg":"trace[887881255] transaction","detail":"{read_only:false; response_revision:13949; number_of_response:1; }","duration":"181.827924ms","start":"2026-05-19T04:29:20.420026Z","end":"2026-05-19T04:29:20.601854Z","steps":["trace[887881255] 'process raft request' (duration: 136.69707ms)","trace[887881255] 'compare' (duration: 44.464141ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:29:20.601949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.509716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T04:29:20.601997Z","caller":"traceutil/trace.go:171","msg":"trace[131944978] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:13950; }","duration":"180.613148ms","start":"2026-05-19T04:29:20.421368Z","end":"2026-05-19T04:29:20.601981Z","steps":["trace[131944978] 'agreement among raft nodes before linearized reading' (duration: 180.497246ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:30:59.209822Z","caller":"traceutil/trace.go:171","msg":"trace[1505833146] transaction","detail":"{read_only:false; response_revision:15029; number_of_response:1; }","duration":"145.361916ms","start":"2026-05-19T04:30:59.064431Z","end":"2026-05-19T04:30:59.209793Z","steps":["trace[1505833146] 'process raft request' (duration: 145.129471ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:30:59.688401Z","caller":"traceutil/trace.go:171","msg":"trace[179282129] transaction","detail":"{read_only:false; response_revision:15030; number_of_response:1; }","duration":"115.4841ms","start":"2026-05-19T04:30:59.57289Z","end":"2026-05-19T04:30:59.688374Z","steps":["trace[179282129] 'process raft request' (duration: 115.181994ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:30:59.700512Z","caller":"traceutil/trace.go:171","msg":"trace[45450068] transaction","detail":"{read_only:false; response_revision:15031; number_of_response:1; }","duration":"124.620644ms","start":"2026-05-19T04:30:59.575875Z","end":"2026-05-19T04:30:59.700496Z","steps":["trace[45450068] 'process raft request' (duration: 124.485941ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:30:59.70075Z","caller":"traceutil/trace.go:171","msg":"trace[652397494] transaction","detail":"{read_only:false; response_revision:15032; number_of_response:1; }","duration":"122.863029ms","start":"2026-05-19T04:30:59.577872Z","end":"2026-05-19T04:30:59.700735Z","steps":["trace[652397494] 'process raft request' (duration: 122.582273ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:32:03.480629Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12966} {"level":"info","ts":"2026-05-19T04:32:03.553349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12966,"took":"70.79415ms","hash":4009020247,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":30904320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T04:32:03.553401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4009020247,"revision":12966,"compact-revision":10771} {"level":"warn","ts":"2026-05-19T04:36:37.747201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.770998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:36:37.747646Z","caller":"traceutil/trace.go:171","msg":"trace[1832379297] transaction","detail":"{read_only:false; response_revision:17630; number_of_response:1; }","duration":"219.863607ms","start":"2026-05-19T04:36:37.52774Z","end":"2026-05-19T04:36:37.747603Z","steps":["trace[1832379297] 'process raft request' (duration: 35.574888ms)","trace[1832379297] 'compare' (duration: 183.38248ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:36:37.748137Z","caller":"traceutil/trace.go:171","msg":"trace[281110468] transaction","detail":"{read_only:false; response_revision:17631; number_of_response:1; }","duration":"219.955079ms","start":"2026-05-19T04:36:37.528156Z","end":"2026-05-19T04:36:37.748111Z","steps":["trace[281110468] 'process raft request' (duration: 219.786216ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:36:37.748903Z","caller":"traceutil/trace.go:171","msg":"trace[194852924] transaction","detail":"{read_only:false; response_revision:17632; number_of_response:1; }","duration":"218.228384ms","start":"2026-05-19T04:36:37.530643Z","end":"2026-05-19T04:36:37.748872Z","steps":["trace[194852924] 'process raft request' (duration: 217.410838ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:36:37.74916Z","caller":"traceutil/trace.go:171","msg":"trace[753168715] linearizableReadLoop","detail":"{readStateIndex:18226; appliedIndex:18222; }","duration":"106.093591ms","start":"2026-05-19T04:36:37.643033Z","end":"2026-05-19T04:36:37.749126Z","steps":["trace[753168715] 'read index received' (duration: 70.275598ms)","trace[753168715] 'applied index is now lower than readState.Index' (duration: 35.816863ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:36:37.749281Z","caller":"traceutil/trace.go:171","msg":"trace[1954386865] transaction","detail":"{read_only:false; response_revision:17633; number_of_response:1; }","duration":"118.678145ms","start":"2026-05-19T04:36:37.630581Z","end":"2026-05-19T04:36:37.749259Z","steps":["trace[1954386865] 'process raft request' (duration: 118.230816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:37.749463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.410607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fhdbz\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-05-19T04:36:37.74954Z","caller":"traceutil/trace.go:171","msg":"trace[553812953] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fhdbz; range_end:; response_count:1; response_revision:17633; }","duration":"106.508569ms","start":"2026-05-19T04:36:37.642993Z","end":"2026-05-19T04:36:37.749501Z","steps":["trace[553812953] 'agreement among raft nodes before linearized reading' (duration: 106.299055ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:36:43.573863Z","caller":"traceutil/trace.go:171","msg":"trace[366522849] transaction","detail":"{read_only:false; response_revision:17662; number_of_response:1; }","duration":"118.098332ms","start":"2026-05-19T04:36:43.455738Z","end":"2026-05-19T04:36:43.573836Z","steps":["trace[366522849] 'process raft request' (duration: 117.921399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:36:43.697206Z","caller":"traceutil/trace.go:171","msg":"trace[738559072] transaction","detail":"{read_only:false; response_revision:17663; number_of_response:1; }","duration":"118.96536ms","start":"2026-05-19T04:36:43.578205Z","end":"2026-05-19T04:36:43.69717Z","steps":["trace[738559072] 'process raft request' (duration: 62.613363ms)","trace[738559072] 'compare' (duration: 56.050031ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:36:44.055485Z","caller":"traceutil/trace.go:171","msg":"trace[414899420] linearizableReadLoop","detail":"{readStateIndex:18258; appliedIndex:18257; }","duration":"282.464011ms","start":"2026-05-19T04:36:43.772992Z","end":"2026-05-19T04:36:44.055456Z","steps":["trace[414899420] 'read index received' (duration: 282.231206ms)","trace[414899420] 'applied index is now lower than readState.Index' (duration: 231.844µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:36:44.055545Z","caller":"traceutil/trace.go:171","msg":"trace[1738286407] transaction","detail":"{read_only:false; response_revision:17664; number_of_response:1; }","duration":"288.092572ms","start":"2026-05-19T04:36:43.767411Z","end":"2026-05-19T04:36:44.055504Z","steps":["trace[1738286407] 'process raft request' (duration: 287.867318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:44.055674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.693974ms","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-19T04:36:44.055709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.554074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:36:44.055753Z","caller":"traceutil/trace.go:171","msg":"trace[278992326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17664; }","duration":"282.818747ms","start":"2026-05-19T04:36:43.772915Z","end":"2026-05-19T04:36:44.055734Z","steps":["trace[278992326] 'agreement among raft nodes before linearized reading' (duration: 282.630513ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:36:44.055769Z","caller":"traceutil/trace.go:171","msg":"trace[199635669] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:17664; }","duration":"186.661126ms","start":"2026-05-19T04:36:43.869091Z","end":"2026-05-19T04:36:44.055752Z","steps":["trace[199635669] 'agreement among raft nodes before linearized reading' (duration: 186.549404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:44.05578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.846945ms","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-19T04:36:44.055828Z","caller":"traceutil/trace.go:171","msg":"trace[1082478666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17664; }","duration":"269.923167ms","start":"2026-05-19T04:36:43.785891Z","end":"2026-05-19T04:36:44.055815Z","steps":["trace[1082478666] 'agreement among raft nodes before linearized reading' (duration: 269.726653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:44.055856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.434961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fhdbz\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-05-19T04:36:44.055937Z","caller":"traceutil/trace.go:171","msg":"trace[311692602] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fhdbz; range_end:; response_count:1; response_revision:17664; }","duration":"239.565054ms","start":"2026-05-19T04:36:43.816352Z","end":"2026-05-19T04:36:44.055917Z","steps":["trace[311692602] 'agreement among raft nodes before linearized reading' (duration: 239.328519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:44.443931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.859628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:36:44.444066Z","caller":"traceutil/trace.go:171","msg":"trace[435253827] transaction","detail":"{read_only:false; response_revision:17665; number_of_response:1; }","duration":"384.222272ms","start":"2026-05-19T04:36:44.059817Z","end":"2026-05-19T04:36:44.444039Z","steps":["trace[435253827] 'process raft request' (duration: 209.15617ms)","trace[435253827] 'compare' (duration: 174.723456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:36:44.444165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:36:44.059792Z","time spent":"384.324915ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:36:44.460998Z","caller":"traceutil/trace.go:171","msg":"trace[1751698078] linearizableReadLoop","detail":"{readStateIndex:18260; appliedIndex:18258; }","duration":"400.332508ms","start":"2026-05-19T04:36:44.060635Z","end":"2026-05-19T04:36:44.460968Z","steps":["trace[1751698078] 'read index received' (duration: 208.354995ms)","trace[1751698078] 'applied index is now lower than readState.Index' (duration: 191.976413ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:36:44.461089Z","caller":"traceutil/trace.go:171","msg":"trace[1006331974] transaction","detail":"{read_only:false; response_revision:17666; number_of_response:1; }","duration":"400.589433ms","start":"2026-05-19T04:36:44.060469Z","end":"2026-05-19T04:36:44.461059Z","steps":["trace[1006331974] 'process raft request' (duration: 400.355578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:44.461204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:36:44.060448Z","time spent":"400.697275ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:36:44.461222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.300893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:36:44.461262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.041166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:36:44.461292Z","caller":"traceutil/trace.go:171","msg":"trace[1779938006] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:17666; }","duration":"267.421986ms","start":"2026-05-19T04:36:44.193854Z","end":"2026-05-19T04:36:44.461276Z","steps":["trace[1779938006] 'agreement among raft nodes before linearized reading' (duration: 267.294163ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:36:44.46131Z","caller":"traceutil/trace.go:171","msg":"trace[1787129345] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17666; }","duration":"144.123217ms","start":"2026-05-19T04:36:44.317172Z","end":"2026-05-19T04:36:44.461295Z","steps":["trace[1787129345] 'agreement among raft nodes before linearized reading' (duration: 143.973894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:44.46181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.153514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:946462"} {"level":"info","ts":"2026-05-19T04:36:44.461843Z","caller":"traceutil/trace.go:171","msg":"trace[363371718] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:17666; }","duration":"401.223595ms","start":"2026-05-19T04:36:44.060613Z","end":"2026-05-19T04:36:44.461836Z","steps":["trace[363371718] 'agreement among raft nodes before linearized reading' (duration: 400.48369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:36:44.46187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:36:44.060604Z","time spent":"401.257787ms","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":96,"response size":946485,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-19T04:37:03.488237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15456} {"level":"info","ts":"2026-05-19T04:37:03.586134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15456,"took":"94.132789ms","hash":2162525307,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":33116160,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-19T04:37:03.586206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2162525307,"revision":15456,"compact-revision":12966} {"level":"info","ts":"2026-05-19T04:40:13.51918Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T04:40:13.523024Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T04:40:13.523086Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T04:42:00.577662Z","caller":"traceutil/trace.go:171","msg":"trace[1572053261] transaction","detail":"{read_only:false; response_revision:20102; number_of_response:1; }","duration":"155.982326ms","start":"2026-05-19T04:42:00.421645Z","end":"2026-05-19T04:42:00.577627Z","steps":["trace[1572053261] 'process raft request' (duration: 155.721791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:42:00.830008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.419216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:42:00.830114Z","caller":"traceutil/trace.go:171","msg":"trace[1625904982] linearizableReadLoop","detail":"{readStateIndex:20777; appliedIndex:20776; }","duration":"248.8732ms","start":"2026-05-19T04:42:00.581222Z","end":"2026-05-19T04:42:00.830095Z","steps":["trace[1625904982] 'read index received' (duration: 146.28708ms)","trace[1625904982] 'applied index is now lower than readState.Index' (duration: 102.58498ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:42:00.830172Z","caller":"traceutil/trace.go:171","msg":"trace[195591625] transaction","detail":"{read_only:false; response_revision:20103; number_of_response:1; }","duration":"264.25753ms","start":"2026-05-19T04:42:00.565874Z","end":"2026-05-19T04:42:00.830132Z","steps":["trace[195591625] 'process raft request' (duration: 161.630329ms)","trace[195591625] 'compare' (duration: 102.277784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:42:00.830265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.486197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:42:00.830298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.070904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:42:00.830337Z","caller":"traceutil/trace.go:171","msg":"trace[2015969162] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20103; }","duration":"249.135586ms","start":"2026-05-19T04:42:00.581192Z","end":"2026-05-19T04:42:00.830328Z","steps":["trace[2015969162] 'agreement among raft nodes before linearized reading' (duration: 248.995083ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:42:00.830341Z","caller":"traceutil/trace.go:171","msg":"trace[2078125285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20103; }","duration":"156.583169ms","start":"2026-05-19T04:42:00.673741Z","end":"2026-05-19T04:42:00.830324Z","steps":["trace[2078125285] 'agreement among raft nodes before linearized reading' (duration: 156.461926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:42:01.843811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.805672ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T04:42:01.843908Z","caller":"traceutil/trace.go:171","msg":"trace[1212792966] linearizableReadLoop","detail":"{readStateIndex:20780; appliedIndex:20779; }","duration":"168.786385ms","start":"2026-05-19T04:42:01.675105Z","end":"2026-05-19T04:42:01.843892Z","steps":["trace[1212792966] 'read index received' (duration: 24.910513ms)","trace[1212792966] 'applied index is now lower than readState.Index' (duration: 143.874552ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:42:01.844545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.443788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:42:01.844592Z","caller":"traceutil/trace.go:171","msg":"trace[444484351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20105; }","duration":"169.497179ms","start":"2026-05-19T04:42:01.675084Z","end":"2026-05-19T04:42:01.844581Z","steps":["trace[444484351] 'agreement among raft nodes before linearized reading' (duration: 169.401217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:42:01.844808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.57829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:42:01.844848Z","caller":"traceutil/trace.go:171","msg":"trace[1192574529] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:20105; }","duration":"164.656402ms","start":"2026-05-19T04:42:01.680181Z","end":"2026-05-19T04:42:01.844837Z","steps":["trace[1192574529] 'agreement among raft nodes before linearized reading' (duration: 164.597541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:42:01.845113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.119746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:42:01.845151Z","caller":"traceutil/trace.go:171","msg":"trace[625658788] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20105; }","duration":"135.190167ms","start":"2026-05-19T04:42:01.709952Z","end":"2026-05-19T04:42:01.845143Z","steps":["trace[625658788] 'agreement among raft nodes before linearized reading' (duration: 135.081605ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:42:03.493256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18015} {"level":"info","ts":"2026-05-19T04:42:03.615007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18015,"took":"116.326066ms","hash":3718022330,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":30216192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T04:42:03.615075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3718022330,"revision":18015,"compact-revision":15456} {"level":"info","ts":"2026-05-19T04:45:46.286785Z","caller":"traceutil/trace.go:171","msg":"trace[2003213415] transaction","detail":"{read_only:false; response_revision:22499; number_of_response:1; }","duration":"312.676587ms","start":"2026-05-19T04:45:45.974074Z","end":"2026-05-19T04:45:46.286751Z","steps":["trace[2003213415] 'process raft request' (duration: 312.545175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:45:46.286932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:45:45.974053Z","time spent":"312.81426ms","remote":"127.0.0.1:33936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1013,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T04:45:46.385863Z","caller":"traceutil/trace.go:171","msg":"trace[1110924346] transaction","detail":"{read_only:false; response_revision:22500; number_of_response:1; }","duration":"354.237335ms","start":"2026-05-19T04:45:46.031599Z","end":"2026-05-19T04:45:46.385836Z","steps":["trace[1110924346] 'process raft request' (duration: 332.787113ms)","trace[1110924346] 'compare' (duration: 21.158386ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:45:46.386018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:45:46.031568Z","time spent":"354.371948ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:45:46.386067Z","caller":"traceutil/trace.go:171","msg":"trace[398855689] transaction","detail":"{read_only:false; response_revision:22501; number_of_response:1; }","duration":"351.569971ms","start":"2026-05-19T04:45:46.034474Z","end":"2026-05-19T04:45:46.386044Z","steps":["trace[398855689] 'process raft request' (duration: 351.281505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:45:46.386149Z","caller":"traceutil/trace.go:171","msg":"trace[988561410] transaction","detail":"{read_only:false; response_revision:22502; number_of_response:1; }","duration":"235.690524ms","start":"2026-05-19T04:45:46.150411Z","end":"2026-05-19T04:45:46.386102Z","steps":["trace[988561410] 'process raft request' (duration: 235.596362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:45:46.386213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:45:46.034456Z","time spent":"351.677453ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:47:03.498796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20115} {"level":"info","ts":"2026-05-19T04:47:03.601913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20115,"took":"99.523037ms","hash":3268961421,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":41472000,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-19T04:47:03.602091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3268961421,"revision":20115,"compact-revision":18015} {"level":"warn","ts":"2026-05-19T04:49:11.864253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.007886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T04:49:11.864367Z","caller":"traceutil/trace.go:171","msg":"trace[1704704293] linearizableReadLoop","detail":"{readStateIndex:25350; appliedIndex:25349; }","duration":"207.558864ms","start":"2026-05-19T04:49:11.65679Z","end":"2026-05-19T04:49:11.864349Z","steps":["trace[1704704293] 'read index received' (duration: 46.448235ms)","trace[1704704293] 'applied index is now lower than readState.Index' (duration: 161.109179ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:11.864558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.767068ms","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-19T04:49:11.8646Z","caller":"traceutil/trace.go:171","msg":"trace[944282506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24551; }","duration":"207.84179ms","start":"2026-05-19T04:49:11.656746Z","end":"2026-05-19T04:49:11.864587Z","steps":["trace[944282506] 'agreement among raft nodes before linearized reading' (duration: 207.690797ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:12.008913Z","caller":"traceutil/trace.go:171","msg":"trace[1639758231] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"326.525972ms","start":"2026-05-19T04:49:11.682369Z","end":"2026-05-19T04:49:12.008895Z","steps":["trace[1639758231] 'process raft request' (duration: 326.481721ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:12.008941Z","caller":"traceutil/trace.go:171","msg":"trace[1872354770] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"327.690775ms","start":"2026-05-19T04:49:11.681226Z","end":"2026-05-19T04:49:12.008917Z","steps":["trace[1872354770] 'process raft request' (duration: 327.499001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:12.009042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:11.682347Z","time spent":"326.619773ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:12.009072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:11.681209Z","time spent":"327.779397ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:12.009264Z","caller":"traceutil/trace.go:171","msg":"trace[1448036491] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"325.902019ms","start":"2026-05-19T04:49:11.68335Z","end":"2026-05-19T04:49:12.009252Z","steps":["trace[1448036491] 'process raft request' (duration: 325.527201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:12.009326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:11.683339Z","time spent":"325.95739ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:12.00933Z","caller":"traceutil/trace.go:171","msg":"trace[1310538140] linearizableReadLoop","detail":"{readStateIndex:25354; appliedIndex:25350; }","duration":"144.90297ms","start":"2026-05-19T04:49:11.864392Z","end":"2026-05-19T04:49:12.009295Z","steps":["trace[1310538140] 'read index received' (duration: 144.263487ms)","trace[1310538140] 'applied index is now lower than readState.Index' (duration: 638.253µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:12.009453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.521864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:12.009494Z","caller":"traceutil/trace.go:171","msg":"trace[1656496168] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24555; }","duration":"270.561774ms","start":"2026-05-19T04:49:11.738921Z","end":"2026-05-19T04:49:12.009483Z","steps":["trace[1656496168] 'agreement among raft nodes before linearized reading' (duration: 270.477443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:12.009549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.470469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:49:12.00962Z","caller":"traceutil/trace.go:171","msg":"trace[1539490595] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24555; }","duration":"308.623952ms","start":"2026-05-19T04:49:11.700974Z","end":"2026-05-19T04:49:12.009598Z","steps":["trace[1539490595] 'agreement among raft nodes before linearized reading' (duration: 308.466648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:12.00968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:11.700956Z","time spent":"308.707824ms","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-19T04:49:12.009725Z","caller":"traceutil/trace.go:171","msg":"trace[37701087] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"324.580233ms","start":"2026-05-19T04:49:11.685135Z","end":"2026-05-19T04:49:12.009715Z","steps":["trace[37701087] 'process raft request' (duration: 324.027511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:12.009788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:11.685115Z","time spent":"324.642724ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:12.009985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.176356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:12.010035Z","caller":"traceutil/trace.go:171","msg":"trace[698867458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24555; }","duration":"336.262118ms","start":"2026-05-19T04:49:11.67376Z","end":"2026-05-19T04:49:12.010022Z","steps":["trace[698867458] 'agreement among raft nodes before linearized reading' (duration: 336.190677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:12.010081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:11.673738Z","time spent":"336.326709ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T04:49:12.138281Z","caller":"traceutil/trace.go:171","msg":"trace[2099309182] linearizableReadLoop","detail":"{readStateIndex:25355; appliedIndex:25354; }","duration":"128.844847ms","start":"2026-05-19T04:49:12.009413Z","end":"2026-05-19T04:49:12.138258Z","steps":["trace[2099309182] 'read index received' (duration: 103.596937ms)","trace[2099309182] 'applied index is now lower than readState.Index' (duration: 25.2471ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:12.138397Z","caller":"traceutil/trace.go:171","msg":"trace[1705355216] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"265.982331ms","start":"2026-05-19T04:49:11.872373Z","end":"2026-05-19T04:49:12.138356Z","steps":["trace[1705355216] 'process raft request' (duration: 240.675771ms)","trace[1705355216] 'compare' (duration: 25.105675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:12.138458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.298747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:12.138491Z","caller":"traceutil/trace.go:171","msg":"trace[467364015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24556; }","duration":"127.379419ms","start":"2026-05-19T04:49:12.011102Z","end":"2026-05-19T04:49:12.138481Z","steps":["trace[467364015] 'agreement among raft nodes before linearized reading' (duration: 127.300087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:12.138471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.17985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:12.138554Z","caller":"traceutil/trace.go:171","msg":"trace[367212561] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24556; }","duration":"227.332763ms","start":"2026-05-19T04:49:11.911203Z","end":"2026-05-19T04:49:12.138536Z","steps":["trace[367212561] 'agreement among raft nodes before linearized reading' (duration: 227.15927ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:16.256253Z","caller":"traceutil/trace.go:171","msg":"trace[34708511] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"103.013025ms","start":"2026-05-19T04:49:16.1532Z","end":"2026-05-19T04:49:16.256213Z","steps":["trace[34708511] 'process raft request' (duration: 102.709929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:16.423105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.11711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:16.423216Z","caller":"traceutil/trace.go:171","msg":"trace[1288901579] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"163.640369ms","start":"2026-05-19T04:49:16.259555Z","end":"2026-05-19T04:49:16.423196Z","steps":["trace[1288901579] 'process raft request' (duration: 47.346875ms)","trace[1288901579] 'compare' (duration: 115.999128ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:16.495165Z","caller":"traceutil/trace.go:171","msg":"trace[1069219044] linearizableReadLoop","detail":"{readStateIndex:25393; appliedIndex:25391; }","duration":"234.354734ms","start":"2026-05-19T04:49:16.260785Z","end":"2026-05-19T04:49:16.49514Z","steps":["trace[1069219044] 'read index received' (duration: 46.12652ms)","trace[1069219044] 'applied index is now lower than readState.Index' (duration: 188.226804ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:16.495236Z","caller":"traceutil/trace.go:171","msg":"trace[878272140] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"234.6208ms","start":"2026-05-19T04:49:16.260557Z","end":"2026-05-19T04:49:16.495178Z","steps":["trace[878272140] 'process raft request' (duration: 234.401736ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:16.495256Z","caller":"traceutil/trace.go:171","msg":"trace[1684930195] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"233.807104ms","start":"2026-05-19T04:49:16.261409Z","end":"2026-05-19T04:49:16.495216Z","steps":["trace[1684930195] 'process raft request' (duration: 233.682961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:16.495401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.555748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:16.49568Z","caller":"traceutil/trace.go:171","msg":"trace[197063068] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:24595; }","duration":"234.880015ms","start":"2026-05-19T04:49:16.260781Z","end":"2026-05-19T04:49:16.495661Z","steps":["trace[197063068] 'agreement among raft nodes before linearized reading' (duration: 234.515367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:16.696581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.877644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:16.696734Z","caller":"traceutil/trace.go:171","msg":"trace[1218560928] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"225.503587ms","start":"2026-05-19T04:49:16.471215Z","end":"2026-05-19T04:49:16.696718Z","steps":["trace[1218560928] 'process raft request' (duration: 121.410138ms)","trace[1218560928] 'compare' (duration: 103.64531ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:16.696834Z","caller":"traceutil/trace.go:171","msg":"trace[1936021146] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"179.505559ms","start":"2026-05-19T04:49:16.517323Z","end":"2026-05-19T04:49:16.696829Z","steps":["trace[1936021146] 'process raft request' (duration: 179.335715ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:16.730319Z","caller":"traceutil/trace.go:171","msg":"trace[1232542229] linearizableReadLoop","detail":"{readStateIndex:25397; appliedIndex:25394; }","duration":"131.367329ms","start":"2026-05-19T04:49:16.598926Z","end":"2026-05-19T04:49:16.730293Z","steps":["trace[1232542229] 'read index received' (duration: 76.958681ms)","trace[1232542229] 'applied index is now lower than readState.Index' (duration: 54.407437ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:16.730645Z","caller":"traceutil/trace.go:171","msg":"trace[1047795657] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"132.439939ms","start":"2026-05-19T04:49:16.598193Z","end":"2026-05-19T04:49:16.730632Z","steps":["trace[1047795657] 'process raft request' (duration: 131.95824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:16.73093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.984551ms","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-19T04:49:16.730977Z","caller":"traceutil/trace.go:171","msg":"trace[369163096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24598; }","duration":"132.067762ms","start":"2026-05-19T04:49:16.598897Z","end":"2026-05-19T04:49:16.730965Z","steps":["trace[369163096] 'agreement among raft nodes before linearized reading' (duration: 131.927209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:16.731166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.022649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-19T04:49:16.731202Z","caller":"traceutil/trace.go:171","msg":"trace[39382986] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:24598; }","duration":"114.07787ms","start":"2026-05-19T04:49:16.617116Z","end":"2026-05-19T04:49:16.731194Z","steps":["trace[39382986] 'agreement among raft nodes before linearized reading' (duration: 113.989309ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:16.909304Z","caller":"traceutil/trace.go:171","msg":"trace[1826412637] linearizableReadLoop","detail":"{readStateIndex:25398; appliedIndex:25397; }","duration":"175.702832ms","start":"2026-05-19T04:49:16.733502Z","end":"2026-05-19T04:49:16.909205Z","steps":["trace[1826412637] 'read index received' (duration: 130.51876ms)","trace[1826412637] 'applied index is now lower than readState.Index' (duration: 45.177792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:16.909416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.887166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:16.909979Z","caller":"traceutil/trace.go:171","msg":"trace[1730440206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24598; }","duration":"176.458997ms","start":"2026-05-19T04:49:16.733477Z","end":"2026-05-19T04:49:16.909936Z","steps":["trace[1730440206] 'agreement among raft nodes before linearized reading' (duration: 175.838844ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:16.917666Z","caller":"traceutil/trace.go:171","msg":"trace[1236589687] transaction","detail":"{read_only:false; response_revision:24599; number_of_response:1; }","duration":"181.957548ms","start":"2026-05-19T04:49:16.735689Z","end":"2026-05-19T04:49:16.917647Z","steps":["trace[1236589687] 'process raft request' (duration: 172.689861ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:17.144919Z","caller":"traceutil/trace.go:171","msg":"trace[1547727872] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"234.545628ms","start":"2026-05-19T04:49:16.910335Z","end":"2026-05-19T04:49:17.144881Z","steps":["trace[1547727872] 'process raft request' (duration: 226.100478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:17.913582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.314683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:17.913652Z","caller":"traceutil/trace.go:171","msg":"trace[423471385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24603; }","duration":"235.479917ms","start":"2026-05-19T04:49:17.678156Z","end":"2026-05-19T04:49:17.913636Z","steps":["trace[423471385] 'range keys from in-memory index tree' (duration: 235.219952ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:18.410097Z","caller":"traceutil/trace.go:171","msg":"trace[1656523929] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"375.04394ms","start":"2026-05-19T04:49:18.034998Z","end":"2026-05-19T04:49:18.410042Z","steps":["trace[1656523929] 'process raft request' (duration: 374.237304ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:18.410137Z","caller":"traceutil/trace.go:171","msg":"trace[580167095] linearizableReadLoop","detail":"{readStateIndex:25407; appliedIndex:25404; }","duration":"328.491041ms","start":"2026-05-19T04:49:18.081577Z","end":"2026-05-19T04:49:18.410068Z","steps":["trace[580167095] 'read index received' (duration: 327.116934ms)","trace[580167095] 'applied index is now lower than readState.Index' (duration: 1.372487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:18.410337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.03497Z","time spent":"375.252235ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:18.410412Z","caller":"traceutil/trace.go:171","msg":"trace[1040718879] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"375.284875ms","start":"2026-05-19T04:49:18.035111Z","end":"2026-05-19T04:49:18.410396Z","steps":["trace[1040718879] 'process raft request' (duration: 374.753014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.410529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.94385ms","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":"warn","ts":"2026-05-19T04:49:18.410591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.03509Z","time spent":"375.395067ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:18.4106Z","caller":"traceutil/trace.go:171","msg":"trace[1331863375] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24607; }","duration":"329.080755ms","start":"2026-05-19T04:49:18.081497Z","end":"2026-05-19T04:49:18.410578Z","steps":["trace[1331863375] 'agreement among raft nodes before linearized reading' (duration: 328.89406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.410649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.081477Z","time spent":"329.160636ms","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1885,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-05-19T04:49:18.410694Z","caller":"traceutil/trace.go:171","msg":"trace[717499202] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"373.332227ms","start":"2026-05-19T04:49:18.037348Z","end":"2026-05-19T04:49:18.41068Z","steps":["trace[717499202] 'process raft request' (duration: 372.672273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.410769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.03733Z","time spent":"373.400788ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:18.410963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.388489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:18.411Z","caller":"traceutil/trace.go:171","msg":"trace[1991317505] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24607; }","duration":"322.469551ms","start":"2026-05-19T04:49:18.088518Z","end":"2026-05-19T04:49:18.410988Z","steps":["trace[1991317505] 'agreement among raft nodes before linearized reading' (duration: 322.387109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.411031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.088498Z","time spent":"322.524991ms","remote":"127.0.0.1:54246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-19T04:49:18.411062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.560495ms","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-19T04:49:18.411109Z","caller":"traceutil/trace.go:171","msg":"trace[414280581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24607; }","duration":"148.643797ms","start":"2026-05-19T04:49:18.262454Z","end":"2026-05-19T04:49:18.411098Z","steps":["trace[414280581] 'agreement among raft nodes before linearized reading' (duration: 148.504784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.789704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.454204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:18.789961Z","caller":"traceutil/trace.go:171","msg":"trace[1543610610] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"286.543546ms","start":"2026-05-19T04:49:18.503355Z","end":"2026-05-19T04:49:18.789899Z","steps":["trace[1543610610] 'process raft request' (duration: 128.702815ms)","trace[1543610610] 'compare' (duration: 156.848912ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:18.866101Z","caller":"traceutil/trace.go:171","msg":"trace[1247985852] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"361.602009ms","start":"2026-05-19T04:49:18.504472Z","end":"2026-05-19T04:49:18.866075Z","steps":["trace[1247985852] 'process raft request' (duration: 360.398715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:18.86644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.504457Z","time spent":"361.790863ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:18.868852Z","caller":"traceutil/trace.go:171","msg":"trace[2108659249] linearizableReadLoop","detail":"{readStateIndex:25411; appliedIndex:25411; }","duration":"227.903174ms","start":"2026-05-19T04:49:18.64092Z","end":"2026-05-19T04:49:18.868823Z","steps":["trace[2108659249] 'read index received' (duration: 227.895154ms)","trace[2108659249] 'applied index is now lower than readState.Index' (duration: 6.31µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:18.869151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.21493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-05-19T04:49:18.869198Z","caller":"traceutil/trace.go:171","msg":"trace[2121049124] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24611; }","duration":"228.300101ms","start":"2026-05-19T04:49:18.640882Z","end":"2026-05-19T04:49:18.869182Z","steps":["trace[2121049124] 'agreement among raft nodes before linearized reading' (duration: 228.061647ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:19.178705Z","caller":"traceutil/trace.go:171","msg":"trace[1518664016] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"475.042497ms","start":"2026-05-19T04:49:18.703638Z","end":"2026-05-19T04:49:19.17868Z","steps":["trace[1518664016] 'process raft request' (duration: 457.160976ms)","trace[1518664016] 'compare' (duration: 17.714177ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:19.178933Z","caller":"traceutil/trace.go:171","msg":"trace[587792650] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"472.654888ms","start":"2026-05-19T04:49:18.706258Z","end":"2026-05-19T04:49:19.178913Z","steps":["trace[587792650] 'process raft request' (duration: 472.379403ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:19.179027Z","caller":"traceutil/trace.go:171","msg":"trace[1960798960] linearizableReadLoop","detail":"{readStateIndex:25413; appliedIndex:25411; }","duration":"310.088991ms","start":"2026-05-19T04:49:18.868919Z","end":"2026-05-19T04:49:19.179008Z","steps":["trace[1960798960] 'read index received' (duration: 291.896064ms)","trace[1960798960] 'applied index is now lower than readState.Index' (duration: 18.192247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:19.179061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.706231Z","time spent":"472.76055ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:19.179161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.474291ms","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-19T04:49:19.179193Z","caller":"traceutil/trace.go:171","msg":"trace[380033210] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24614; }","duration":"386.568113ms","start":"2026-05-19T04:49:18.792614Z","end":"2026-05-19T04:49:19.179183Z","steps":["trace[380033210] 'agreement among raft nodes before linearized reading' (duration: 386.497251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.179222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.792592Z","time spent":"386.622503ms","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T04:49:19.179368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.847677ms","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-19T04:49:19.17941Z","caller":"traceutil/trace.go:171","msg":"trace[1686724709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24614; }","duration":"254.935189ms","start":"2026-05-19T04:49:18.924465Z","end":"2026-05-19T04:49:19.1794Z","steps":["trace[1686724709] 'agreement among raft nodes before linearized reading' (duration: 254.793346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.1795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.625295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:19.179581Z","caller":"traceutil/trace.go:171","msg":"trace[570405334] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:24614; }","duration":"206.714227ms","start":"2026-05-19T04:49:18.972847Z","end":"2026-05-19T04:49:19.179561Z","steps":["trace[570405334] 'agreement among raft nodes before linearized reading' (duration: 206.592225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.182829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:18.703612Z","time spent":"475.354162ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:19.606239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.41795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:19.606359Z","caller":"traceutil/trace.go:171","msg":"trace[770527991] linearizableReadLoop","detail":"{readStateIndex:25415; appliedIndex:25414; }","duration":"423.267833ms","start":"2026-05-19T04:49:19.18307Z","end":"2026-05-19T04:49:19.606338Z","steps":["trace[770527991] 'read index received' (duration: 221.686439ms)","trace[770527991] 'applied index is now lower than readState.Index' (duration: 201.579924ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:19.606601Z","caller":"traceutil/trace.go:171","msg":"trace[1790980686] transaction","detail":"{read_only:false; response_revision:24615; number_of_response:1; }","duration":"423.857033ms","start":"2026-05-19T04:49:19.182725Z","end":"2026-05-19T04:49:19.606582Z","steps":["trace[1790980686] 'process raft request' (duration: 222.007565ms)","trace[1790980686] 'compare' (duration: 201.305128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:19.606684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:19.1827Z","time spent":"423.939606ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:19.607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.920786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:49:19.607047Z","caller":"traceutil/trace.go:171","msg":"trace[397272956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24615; }","duration":"423.989407ms","start":"2026-05-19T04:49:19.183042Z","end":"2026-05-19T04:49:19.607031Z","steps":["trace[397272956] 'agreement among raft nodes before linearized reading' (duration: 423.837434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.60709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:19.183032Z","time spent":"424.044858ms","remote":"127.0.0.1:34054","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-19T04:49:19.60735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.615747ms","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-19T04:49:19.607436Z","caller":"traceutil/trace.go:171","msg":"trace[348118463] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24617; }","duration":"156.75509ms","start":"2026-05-19T04:49:19.450661Z","end":"2026-05-19T04:49:19.607417Z","steps":["trace[348118463] 'agreement among raft nodes before linearized reading' (duration: 156.601867ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:19.607672Z","caller":"traceutil/trace.go:171","msg":"trace[545206613] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"331.840559ms","start":"2026-05-19T04:49:19.275792Z","end":"2026-05-19T04:49:19.607633Z","steps":["trace[545206613] 'process raft request' (duration: 331.198096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.607859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:19.275771Z","time spent":"332.027993ms","remote":"127.0.0.1:60850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:19.607663Z","caller":"traceutil/trace.go:171","msg":"trace[1790060615] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"389.221196ms","start":"2026-05-19T04:49:19.218398Z","end":"2026-05-19T04:49:19.60762Z","steps":["trace[1790060615] 'process raft request' (duration: 388.40998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:19.608202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:19.218357Z","time spent":"389.786697ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:20.585281Z","caller":"traceutil/trace.go:171","msg":"trace[1674255416] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"168.462766ms","start":"2026-05-19T04:49:20.416795Z","end":"2026-05-19T04:49:20.585258Z","steps":["trace[1674255416] 'process raft request' (duration: 168.303333ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:20.755298Z","caller":"traceutil/trace.go:171","msg":"trace[2026702744] linearizableReadLoop","detail":"{readStateIndex:25422; appliedIndex:25419; }","duration":"320.784716ms","start":"2026-05-19T04:49:20.434494Z","end":"2026-05-19T04:49:20.755279Z","steps":["trace[2026702744] 'read index received' (duration: 150.673687ms)","trace[2026702744] 'applied index is now lower than readState.Index' (duration: 170.110289ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:20.755535Z","caller":"traceutil/trace.go:171","msg":"trace[1606236182] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"333.533723ms","start":"2026-05-19T04:49:20.421991Z","end":"2026-05-19T04:49:20.755524Z","steps":["trace[1606236182] 'process raft request' (duration: 333.058133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:20.755631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:20.421973Z","time spent":"333.593334ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:20.755783Z","caller":"traceutil/trace.go:171","msg":"trace[1660656378] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"333.457972ms","start":"2026-05-19T04:49:20.422317Z","end":"2026-05-19T04:49:20.755775Z","steps":["trace[1660656378] 'process raft request' (duration: 332.930741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:20.755835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:20.422291Z","time spent":"333.519543ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:20.756014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.595313ms","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-19T04:49:20.75605Z","caller":"traceutil/trace.go:171","msg":"trace[1890087425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24622; }","duration":"321.679785ms","start":"2026-05-19T04:49:20.434361Z","end":"2026-05-19T04:49:20.756041Z","steps":["trace[1890087425] 'agreement among raft nodes before linearized reading' (duration: 321.564152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:20.756082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:20.43434Z","time spent":"321.734685ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T04:49:20.756248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.627148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T04:49:20.756278Z","caller":"traceutil/trace.go:171","msg":"trace[2133993795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24622; }","duration":"286.678178ms","start":"2026-05-19T04:49:20.469592Z","end":"2026-05-19T04:49:20.756271Z","steps":["trace[2133993795] 'agreement among raft nodes before linearized reading' (duration: 286.599647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.041308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.922244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:21.041528Z","caller":"traceutil/trace.go:171","msg":"trace[112649472] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"274.886461ms","start":"2026-05-19T04:49:20.766607Z","end":"2026-05-19T04:49:21.041493Z","steps":["trace[112649472] 'process raft request' (duration: 112.691151ms)","trace[112649472] 'compare' (duration: 161.756871ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:21.041548Z","caller":"traceutil/trace.go:171","msg":"trace[1224931818] linearizableReadLoop","detail":"{readStateIndex:25424; appliedIndex:25422; }","duration":"247.297386ms","start":"2026-05-19T04:49:20.79421Z","end":"2026-05-19T04:49:21.041508Z","steps":["trace[1224931818] 'read index received' (duration: 85.097146ms)","trace[1224931818] 'applied index is now lower than readState.Index' (duration: 162.19936ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:21.041633Z","caller":"traceutil/trace.go:171","msg":"trace[2027925794] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"272.78211ms","start":"2026-05-19T04:49:20.768802Z","end":"2026-05-19T04:49:21.041584Z","steps":["trace[2027925794] 'process raft request' (duration: 272.607856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.041744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.522757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T04:49:21.041783Z","caller":"traceutil/trace.go:171","msg":"trace[1057049225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24624; }","duration":"168.604078ms","start":"2026-05-19T04:49:20.873168Z","end":"2026-05-19T04:49:21.041773Z","steps":["trace[1057049225] 'agreement among raft nodes before linearized reading' (duration: 168.464516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.041766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.537111ms","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-19T04:49:21.041874Z","caller":"traceutil/trace.go:171","msg":"trace[1711845862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24624; }","duration":"247.697054ms","start":"2026-05-19T04:49:20.794166Z","end":"2026-05-19T04:49:21.041863Z","steps":["trace[1711845862] 'agreement among raft nodes before linearized reading' (duration: 247.443349ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:21.227357Z","caller":"traceutil/trace.go:171","msg":"trace[960291638] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"177.387316ms","start":"2026-05-19T04:49:21.049935Z","end":"2026-05-19T04:49:21.227322Z","steps":["trace[960291638] 'process raft request' (duration: 154.017765ms)","trace[960291638] 'compare' (duration: 23.235818ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:21.50308Z","caller":"traceutil/trace.go:171","msg":"trace[1598701252] linearizableReadLoop","detail":"{readStateIndex:25429; appliedIndex:25425; }","duration":"314.463448ms","start":"2026-05-19T04:49:21.188596Z","end":"2026-05-19T04:49:21.503059Z","steps":["trace[1598701252] 'read index received' (duration: 15.3662ms)","trace[1598701252] 'applied index is now lower than readState.Index' (duration: 299.096098ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:21.503462Z","caller":"traceutil/trace.go:171","msg":"trace[317655160] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"319.595024ms","start":"2026-05-19T04:49:21.183852Z","end":"2026-05-19T04:49:21.503447Z","steps":["trace[317655160] 'process raft request' (duration: 311.052132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.503801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:21.18381Z","time spent":"319.704015ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:21.504039Z","caller":"traceutil/trace.go:171","msg":"trace[354571492] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"316.863027ms","start":"2026-05-19T04:49:21.187157Z","end":"2026-05-19T04:49:21.50402Z","steps":["trace[354571492] 'process raft request' (duration: 315.772845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.504167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:21.187128Z","time spent":"316.96666ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:21.504345Z","caller":"traceutil/trace.go:171","msg":"trace[315019775] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"317.061901ms","start":"2026-05-19T04:49:21.187268Z","end":"2026-05-19T04:49:21.50433Z","steps":["trace[315019775] 'process raft request' (duration: 315.718564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.504427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:21.18726Z","time spent":"317.125103ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:21.504635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.037941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:21.505469Z","caller":"traceutil/trace.go:171","msg":"trace[602473007] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24629; }","duration":"316.128442ms","start":"2026-05-19T04:49:21.188549Z","end":"2026-05-19T04:49:21.504677Z","steps":["trace[602473007] 'agreement among raft nodes before linearized reading' (duration: 316.02829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.505604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:21.188531Z","time spent":"317.039381ms","remote":"127.0.0.1:44268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"warn","ts":"2026-05-19T04:49:21.869898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.480291ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T04:49:21.870667Z","caller":"traceutil/trace.go:171","msg":"trace[816291683] linearizableReadLoop","detail":"{readStateIndex:25432; appliedIndex:25430; }","duration":"114.887386ms","start":"2026-05-19T04:49:21.75576Z","end":"2026-05-19T04:49:21.870648Z","steps":["trace[816291683] 'read index received' (duration: 63.772155ms)","trace[816291683] 'applied index is now lower than readState.Index' (duration: 51.113911ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:21.870746Z","caller":"traceutil/trace.go:171","msg":"trace[2009174430] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"199.160444ms","start":"2026-05-19T04:49:21.671575Z","end":"2026-05-19T04:49:21.870735Z","steps":["trace[2009174430] 'process raft request' (duration: 198.48604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:21.906317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.538073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-19T04:49:21.906395Z","caller":"traceutil/trace.go:171","msg":"trace[1996795076] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24631; }","duration":"150.607425ms","start":"2026-05-19T04:49:21.755752Z","end":"2026-05-19T04:49:21.90636Z","steps":["trace[1996795076] 'agreement among raft nodes before linearized reading' (duration: 114.963237ms)","trace[1996795076] 'range keys from in-memory index tree' (duration: 35.490665ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:22.012421Z","caller":"traceutil/trace.go:171","msg":"trace[1750927572] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"102.694449ms","start":"2026-05-19T04:49:21.909701Z","end":"2026-05-19T04:49:22.012396Z","steps":["trace[1750927572] 'process raft request' (duration: 102.529106ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:22.686429Z","caller":"traceutil/trace.go:171","msg":"trace[1267844013] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"107.969017ms","start":"2026-05-19T04:49:22.578433Z","end":"2026-05-19T04:49:22.686402Z","steps":["trace[1267844013] 'process raft request' (duration: 107.783204ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:22.734408Z","caller":"traceutil/trace.go:171","msg":"trace[251319021] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"137.307038ms","start":"2026-05-19T04:49:22.597079Z","end":"2026-05-19T04:49:22.734386Z","steps":["trace[251319021] 'process raft request' (duration: 136.938801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:23.041982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.073726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:23.042757Z","caller":"traceutil/trace.go:171","msg":"trace[504653642] linearizableReadLoop","detail":"{readStateIndex:25438; appliedIndex:25436; }","duration":"146.750538ms","start":"2026-05-19T04:49:22.895994Z","end":"2026-05-19T04:49:23.042745Z","steps":["trace[504653642] 'read index received' (duration: 113.055649ms)","trace[504653642] 'applied index is now lower than readState.Index' (duration: 33.694169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:23.042868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.86663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:23.042937Z","caller":"traceutil/trace.go:171","msg":"trace[897116425] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:24637; }","duration":"146.964542ms","start":"2026-05-19T04:49:22.895964Z","end":"2026-05-19T04:49:23.042928Z","steps":["trace[897116425] 'agreement among raft nodes before linearized reading' (duration: 146.8575ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:23.043189Z","caller":"traceutil/trace.go:171","msg":"trace[2008232880] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"281.179888ms","start":"2026-05-19T04:49:22.761998Z","end":"2026-05-19T04:49:23.043178Z","steps":["trace[2008232880] 'process raft request' (duration: 126.909438ms)","trace[2008232880] 'compare' (duration: 152.364072ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:23.043758Z","caller":"traceutil/trace.go:171","msg":"trace[1042529780] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"271.618385ms","start":"2026-05-19T04:49:22.772131Z","end":"2026-05-19T04:49:23.04375Z","steps":["trace[1042529780] 'process raft request' (duration: 270.567194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:31.164961Z","caller":"traceutil/trace.go:171","msg":"trace[878043482] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"402.88937ms","start":"2026-05-19T04:49:30.762048Z","end":"2026-05-19T04:49:31.164937Z","steps":["trace[878043482] 'process raft request' (duration: 402.743168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:31.165095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:30.762026Z","time spent":"403.009523ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:31.179206Z","caller":"traceutil/trace.go:171","msg":"trace[1335526616] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"110.398026ms","start":"2026-05-19T04:49:31.068794Z","end":"2026-05-19T04:49:31.179192Z","steps":["trace[1335526616] 'process raft request' (duration: 110.200251ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:31.17936Z","caller":"traceutil/trace.go:171","msg":"trace[1182986435] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"108.034299ms","start":"2026-05-19T04:49:31.071317Z","end":"2026-05-19T04:49:31.179352Z","steps":["trace[1182986435] 'process raft request' (duration: 107.744552ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:31.323838Z","caller":"traceutil/trace.go:171","msg":"trace[1209093371] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"139.844499ms","start":"2026-05-19T04:49:31.183976Z","end":"2026-05-19T04:49:31.32382Z","steps":["trace[1209093371] 'process raft request' (duration: 139.773908ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:31.323845Z","caller":"traceutil/trace.go:171","msg":"trace[873924496] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"140.256457ms","start":"2026-05-19T04:49:31.183565Z","end":"2026-05-19T04:49:31.323822Z","steps":["trace[873924496] 'process raft request' (duration: 140.013763ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:31.711768Z","caller":"traceutil/trace.go:171","msg":"trace[98798212] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"170.048799ms","start":"2026-05-19T04:49:31.541693Z","end":"2026-05-19T04:49:31.711742Z","steps":["trace[98798212] 'process raft request' (duration: 169.950607ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:31.712239Z","caller":"traceutil/trace.go:171","msg":"trace[908356596] transaction","detail":"{read_only:false; response_revision:24700; number_of_response:1; }","duration":"176.391536ms","start":"2026-05-19T04:49:31.535823Z","end":"2026-05-19T04:49:31.712215Z","steps":["trace[908356596] 'process raft request' (duration: 175.732633ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:31.712254Z","caller":"traceutil/trace.go:171","msg":"trace[634996529] transaction","detail":"{read_only:false; response_revision:24699; number_of_response:1; }","duration":"176.462207ms","start":"2026-05-19T04:49:31.535752Z","end":"2026-05-19T04:49:31.712214Z","steps":["trace[634996529] 'process raft request' (duration: 173.954207ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:32.138158Z","caller":"traceutil/trace.go:171","msg":"trace[439015700] linearizableReadLoop","detail":"{readStateIndex:25505; appliedIndex:25504; }","duration":"215.095256ms","start":"2026-05-19T04:49:31.923039Z","end":"2026-05-19T04:49:32.138135Z","steps":["trace[439015700] 'read index received' (duration: 214.888582ms)","trace[439015700] 'applied index is now lower than readState.Index' (duration: 205.504µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:32.138378Z","caller":"traceutil/trace.go:171","msg":"trace[137535733] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"392.057813ms","start":"2026-05-19T04:49:31.746295Z","end":"2026-05-19T04:49:32.138353Z","steps":["trace[137535733] 'process raft request' (duration: 391.681836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:32.138494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:31.74627Z","time spent":"392.166195ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:32.138645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.597906ms","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-19T04:49:32.138696Z","caller":"traceutil/trace.go:171","msg":"trace[761053170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24703; }","duration":"215.700228ms","start":"2026-05-19T04:49:31.922983Z","end":"2026-05-19T04:49:32.138683Z","steps":["trace[761053170] 'agreement among raft nodes before linearized reading' (duration: 215.245429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:38.123925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.163539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:49:38.123992Z","caller":"traceutil/trace.go:171","msg":"trace[1436482198] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24806; }","duration":"159.335382ms","start":"2026-05-19T04:49:37.964642Z","end":"2026-05-19T04:49:38.123978Z","steps":["trace[1436482198] 'count revisions from in-memory index tree' (duration: 159.063836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:38.124278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.42425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c00a4f1-dde9-4678-8e71-bf87fa1afb40\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-19T04:49:38.124299Z","caller":"traceutil/trace.go:171","msg":"trace[1734959219] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c00a4f1-dde9-4678-8e71-bf87fa1afb40; range_end:; response_count:1; response_revision:24806; }","duration":"201.483982ms","start":"2026-05-19T04:49:37.92281Z","end":"2026-05-19T04:49:38.124294Z","steps":["trace[1734959219] 'range keys from in-memory index tree' (duration: 201.244637ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:38.301613Z","caller":"traceutil/trace.go:171","msg":"trace[1479907269] linearizableReadLoop","detail":"{readStateIndex:25611; appliedIndex:25610; }","duration":"138.769867ms","start":"2026-05-19T04:49:38.16282Z","end":"2026-05-19T04:49:38.30159Z","steps":["trace[1479907269] 'read index received' (duration: 138.528222ms)","trace[1479907269] 'applied index is now lower than readState.Index' (duration: 240.685µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:38.301748Z","caller":"traceutil/trace.go:171","msg":"trace[1667442085] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"168.738491ms","start":"2026-05-19T04:49:38.132956Z","end":"2026-05-19T04:49:38.301694Z","steps":["trace[1667442085] 'process raft request' (duration: 168.461016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:38.30186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.009312ms","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-19T04:49:38.301921Z","caller":"traceutil/trace.go:171","msg":"trace[98440123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24807; }","duration":"139.094164ms","start":"2026-05-19T04:49:38.162814Z","end":"2026-05-19T04:49:38.301908Z","steps":["trace[98440123] 'agreement among raft nodes before linearized reading' (duration: 138.882029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:38.644111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.593763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:38.644203Z","caller":"traceutil/trace.go:171","msg":"trace[1357769165] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25612; }","duration":"184.623731ms","start":"2026-05-19T04:49:38.459566Z","end":"2026-05-19T04:49:38.64419Z","steps":["trace[1357769165] 'read index received' (duration: 49.780653ms)","trace[1357769165] 'applied index is now lower than readState.Index' (duration: 134.841998ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:38.644302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.371549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:38.644317Z","caller":"traceutil/trace.go:171","msg":"trace[2024736025] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24809; }","duration":"137.389189ms","start":"2026-05-19T04:49:38.506922Z","end":"2026-05-19T04:49:38.644311Z","steps":["trace[2024736025] 'agreement among raft nodes before linearized reading' (duration: 137.340718ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:38.644317Z","caller":"traceutil/trace.go:171","msg":"trace[2139424260] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"327.730626ms","start":"2026-05-19T04:49:38.316531Z","end":"2026-05-19T04:49:38.644262Z","steps":["trace[2139424260] 'process raft request' (duration: 192.911509ms)","trace[2139424260] 'compare' (duration: 134.369169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:38.644359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.750463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:38.644477Z","caller":"traceutil/trace.go:171","msg":"trace[793102215] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:24809; }","duration":"184.915927ms","start":"2026-05-19T04:49:38.459513Z","end":"2026-05-19T04:49:38.644429Z","steps":["trace[793102215] 'agreement among raft nodes before linearized reading' (duration: 184.728603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:38.644532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:38.316504Z","time spent":"327.91939ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:38.902651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.974484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-19T04:49:38.902741Z","caller":"traceutil/trace.go:171","msg":"trace[1258567021] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24809; }","duration":"150.137267ms","start":"2026-05-19T04:49:38.752584Z","end":"2026-05-19T04:49:38.902721Z","steps":["trace[1258567021] 'count revisions from in-memory index tree' (duration: 149.823041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:39.11077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.404037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"warn","ts":"2026-05-19T04:49:39.110813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.397835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b0de3624b3e0d9\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-05-19T04:49:39.110862Z","caller":"traceutil/trace.go:171","msg":"trace[1356778136] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b0de3624b3e0d9; range_end:; response_count:1; response_revision:24810; }","duration":"106.479397ms","start":"2026-05-19T04:49:39.004367Z","end":"2026-05-19T04:49:39.110847Z","steps":["trace[1356778136] 'range keys from in-memory index tree' (duration: 106.045928ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:39.110863Z","caller":"traceutil/trace.go:171","msg":"trace[1224898350] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24810; }","duration":"181.525989ms","start":"2026-05-19T04:49:38.929316Z","end":"2026-05-19T04:49:39.110842Z","steps":["trace[1224898350] 'agreement among raft nodes before linearized reading' (duration: 71.942761ms)","trace[1224898350] 'range keys from in-memory index tree' (duration: 109.250382ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:41.35006Z","caller":"traceutil/trace.go:171","msg":"trace[82604693] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"137.178245ms","start":"2026-05-19T04:49:41.212851Z","end":"2026-05-19T04:49:41.350029Z","steps":["trace[82604693] 'process raft request' (duration: 136.992181ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:41.530617Z","caller":"traceutil/trace.go:171","msg":"trace[1342045956] linearizableReadLoop","detail":"{readStateIndex:25634; appliedIndex:25631; }","duration":"243.732022ms","start":"2026-05-19T04:49:41.286864Z","end":"2026-05-19T04:49:41.530596Z","steps":["trace[1342045956] 'read index received' (duration: 62.969478ms)","trace[1342045956] 'applied index is now lower than readState.Index' (duration: 180.761674ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:41.53075Z","caller":"traceutil/trace.go:171","msg":"trace[464612878] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"315.232875ms","start":"2026-05-19T04:49:41.215492Z","end":"2026-05-19T04:49:41.530725Z","steps":["trace[464612878] 'process raft request' (duration: 258.394018ms)","trace[464612878] 'compare' (duration: 56.412459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:41.530849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:41.215477Z","time spent":"315.300106ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:41.53098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.68283ms","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-19T04:49:41.531058Z","caller":"traceutil/trace.go:171","msg":"trace[848051713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24830; }","duration":"165.798572ms","start":"2026-05-19T04:49:41.365239Z","end":"2026-05-19T04:49:41.531037Z","steps":["trace[848051713] 'agreement among raft nodes before linearized reading' (duration: 165.614679ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:41.531081Z","caller":"traceutil/trace.go:171","msg":"trace[264182040] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"310.53612ms","start":"2026-05-19T04:49:41.220291Z","end":"2026-05-19T04:49:41.530827Z","steps":["trace[264182040] 'process raft request' (duration: 310.220454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:41.531251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:41.220277Z","time spent":"310.908717ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:41.53091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.019599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T04:49:41.53133Z","caller":"traceutil/trace.go:171","msg":"trace[1123538765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24830; }","duration":"244.483508ms","start":"2026-05-19T04:49:41.286829Z","end":"2026-05-19T04:49:41.531312Z","steps":["trace[1123538765] 'agreement among raft nodes before linearized reading' (duration: 243.964557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:41.531392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.119669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T04:49:41.531429Z","caller":"traceutil/trace.go:171","msg":"trace[2112779022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24830; }","duration":"164.17562ms","start":"2026-05-19T04:49:41.367244Z","end":"2026-05-19T04:49:41.53142Z","steps":["trace[2112779022] 'agreement among raft nodes before linearized reading' (duration: 164.089848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:41.531594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.193992ms","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-19T04:49:41.531681Z","caller":"traceutil/trace.go:171","msg":"trace[1902587308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24830; }","duration":"108.307824ms","start":"2026-05-19T04:49:41.423355Z","end":"2026-05-19T04:49:41.531663Z","steps":["trace[1902587308] 'agreement among raft nodes before linearized reading' (duration: 107.852335ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:45.313683Z","caller":"traceutil/trace.go:171","msg":"trace[1165748417] linearizableReadLoop","detail":"{readStateIndex:25679; appliedIndex:25678; }","duration":"125.301616ms","start":"2026-05-19T04:49:45.188356Z","end":"2026-05-19T04:49:45.313658Z","steps":["trace[1165748417] 'read index received' (duration: 125.186234ms)","trace[1165748417] 'applied index is now lower than readState.Index' (duration: 114.532µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:45.313934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.555801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-19T04:49:45.313996Z","caller":"traceutil/trace.go:171","msg":"trace[2145157944] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24873; }","duration":"125.676754ms","start":"2026-05-19T04:49:45.188303Z","end":"2026-05-19T04:49:45.31398Z","steps":["trace[2145157944] 'agreement among raft nodes before linearized reading' (duration: 125.451269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:45.313989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.387163ms","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-19T04:49:45.314075Z","caller":"traceutil/trace.go:171","msg":"trace[329582514] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24873; }","duration":"102.510576ms","start":"2026-05-19T04:49:45.211543Z","end":"2026-05-19T04:49:45.314054Z","steps":["trace[329582514] 'agreement among raft nodes before linearized reading' (duration: 102.349453ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:45.313725Z","caller":"traceutil/trace.go:171","msg":"trace[1079012425] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"147.397792ms","start":"2026-05-19T04:49:45.166304Z","end":"2026-05-19T04:49:45.313702Z","steps":["trace[1079012425] 'process raft request' (duration: 147.210728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:45.56808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.762507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:45.568158Z","caller":"traceutil/trace.go:171","msg":"trace[247250828] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"245.378957ms","start":"2026-05-19T04:49:45.322765Z","end":"2026-05-19T04:49:45.568144Z","steps":["trace[247250828] 'process raft request' (duration: 114.488968ms)","trace[247250828] 'compare' (duration: 130.684865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:45.817084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.250943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:49:45.817358Z","caller":"traceutil/trace.go:171","msg":"trace[1055037005] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"494.56201ms","start":"2026-05-19T04:49:45.322751Z","end":"2026-05-19T04:49:45.817313Z","steps":["trace[1055037005] 'process raft request' (duration: 379.98293ms)","trace[1055037005] 'compare' (duration: 114.106571ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:45.817407Z","caller":"traceutil/trace.go:171","msg":"trace[2070768903] linearizableReadLoop","detail":"{readStateIndex:25683; appliedIndex:25679; }","duration":"494.023038ms","start":"2026-05-19T04:49:45.323366Z","end":"2026-05-19T04:49:45.817389Z","steps":["trace[2070768903] 'read index received' (duration: 113.904576ms)","trace[2070768903] 'applied index is now lower than readState.Index' (duration: 380.117562ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:49:45.817451Z","caller":"traceutil/trace.go:171","msg":"trace[1488356349] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"494.59779ms","start":"2026-05-19T04:49:45.32283Z","end":"2026-05-19T04:49:45.817428Z","steps":["trace[1488356349] 'process raft request' (duration: 494.364146ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:45.817478Z","caller":"traceutil/trace.go:171","msg":"trace[180836013] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"456.40743ms","start":"2026-05-19T04:49:45.361059Z","end":"2026-05-19T04:49:45.817467Z","steps":["trace[180836013] 'process raft request' (duration: 456.274887ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:45.817483Z","caller":"traceutil/trace.go:171","msg":"trace[488235792] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"494.257864ms","start":"2026-05-19T04:49:45.323172Z","end":"2026-05-19T04:49:45.81743Z","steps":["trace[488235792] 'process raft request' (duration: 494.124972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:45.817538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.322722Z","time spent":"494.703202ms","remote":"127.0.0.1:34036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:45.817587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.321761Z","time spent":"495.767363ms","remote":"127.0.0.1:34164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:45.817602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.361042Z","time spent":"456.523073ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:45.817691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.323149Z","time spent":"494.455528ms","remote":"127.0.0.1:34164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:45.817847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.507988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T04:49:45.817958Z","caller":"traceutil/trace.go:171","msg":"trace[1075253862] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24878; }","duration":"494.65663ms","start":"2026-05-19T04:49:45.323279Z","end":"2026-05-19T04:49:45.817936Z","steps":["trace[1075253862] 'agreement among raft nodes before linearized reading' (duration: 494.202611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:45.818031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.323269Z","time spent":"494.748853ms","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-19T04:49:45.857085Z","caller":"traceutil/trace.go:171","msg":"trace[1057050539] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"310.948438ms","start":"2026-05-19T04:49:45.546113Z","end":"2026-05-19T04:49:45.857061Z","steps":["trace[1057050539] 'process raft request' (duration: 310.900257ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:45.857193Z","caller":"traceutil/trace.go:171","msg":"trace[14062206] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"311.105261ms","start":"2026-05-19T04:49:45.546065Z","end":"2026-05-19T04:49:45.857171Z","steps":["trace[14062206] 'process raft request' (duration: 310.813425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:45.857221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.546091Z","time spent":"311.05904ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T04:49:45.857292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.546044Z","time spent":"311.201473ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:49:46.038598Z","caller":"traceutil/trace.go:171","msg":"trace[742940965] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"284.594927ms","start":"2026-05-19T04:49:45.753974Z","end":"2026-05-19T04:49:46.038569Z","steps":["trace[742940965] 'process raft request' (duration: 284.332832ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:46.038643Z","caller":"traceutil/trace.go:171","msg":"trace[316731309] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"274.31293ms","start":"2026-05-19T04:49:45.764301Z","end":"2026-05-19T04:49:46.038614Z","steps":["trace[316731309] 'process raft request' (duration: 274.229178ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:46.038744Z","caller":"traceutil/trace.go:171","msg":"trace[2118586922] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"278.147436ms","start":"2026-05-19T04:49:45.760474Z","end":"2026-05-19T04:49:46.038622Z","steps":["trace[2118586922] 'process raft request' (duration: 278.015263ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:46.03897Z","caller":"traceutil/trace.go:171","msg":"trace[1905249938] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"271.572825ms","start":"2026-05-19T04:49:45.767384Z","end":"2026-05-19T04:49:46.038957Z","steps":["trace[1905249938] 'process raft request' (duration: 271.182117ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:46.038993Z","caller":"traceutil/trace.go:171","msg":"trace[1578820614] linearizableReadLoop","detail":"{readStateIndex:25690; appliedIndex:25686; }","duration":"221.518236ms","start":"2026-05-19T04:49:45.817438Z","end":"2026-05-19T04:49:46.038957Z","steps":["trace[1578820614] 'read index received' (duration: 39.932035ms)","trace[1578820614] 'applied index is now lower than readState.Index' (duration: 181.583921ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:49:46.039222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.268822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:49:46.039285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.667991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-19T04:49:46.039287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.877764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:46.039333Z","caller":"traceutil/trace.go:171","msg":"trace[1987278182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24884; }","duration":"365.394815ms","start":"2026-05-19T04:49:45.673895Z","end":"2026-05-19T04:49:46.03929Z","steps":["trace[1987278182] 'agreement among raft nodes before linearized reading' (duration: 365.249602ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:49:46.039374Z","caller":"traceutil/trace.go:171","msg":"trace[5057499] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24884; }","duration":"426.986427ms","start":"2026-05-19T04:49:45.612369Z","end":"2026-05-19T04:49:46.039355Z","steps":["trace[5057499] 'agreement among raft nodes before linearized reading' (duration: 426.818893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:46.039385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.516853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:49:46.039421Z","caller":"traceutil/trace.go:171","msg":"trace[1181445447] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24884; }","duration":"520.578714ms","start":"2026-05-19T04:49:45.518832Z","end":"2026-05-19T04:49:46.03941Z","steps":["trace[1181445447] 'agreement among raft nodes before linearized reading' (duration: 520.517992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:46.039383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.673881Z","time spent":"365.492517ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T04:49:46.039422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.068358ms","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-19T04:49:46.039465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.518817Z","time spent":"520.638336ms","remote":"127.0.0.1:46244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-05-19T04:49:46.039498Z","caller":"traceutil/trace.go:171","msg":"trace[1375845364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24884; }","duration":"489.19575ms","start":"2026-05-19T04:49:45.550287Z","end":"2026-05-19T04:49:46.039483Z","steps":["trace[1375845364] 'agreement among raft nodes before linearized reading' (duration: 488.969606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:46.039385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.853744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-19T04:49:46.039572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.601939ms","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-19T04:49:46.039612Z","caller":"traceutil/trace.go:171","msg":"trace[129192631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24884; }","duration":"487.66041ms","start":"2026-05-19T04:49:45.551938Z","end":"2026-05-19T04:49:46.039599Z","steps":["trace[129192631] 'agreement among raft nodes before linearized reading' (duration: 487.520058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:46.039645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.551931Z","time spent":"487.708251ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T04:49:46.039426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.612348Z","time spent":"427.066318ms","remote":"127.0.0.1:54144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-05-19T04:49:46.03958Z","caller":"traceutil/trace.go:171","msg":"trace[1567592255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24884; }","duration":"485.18121ms","start":"2026-05-19T04:49:45.554386Z","end":"2026-05-19T04:49:46.039567Z","steps":["trace[1567592255] 'agreement among raft nodes before linearized reading' (duration: 484.71085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:46.039837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.554366Z","time spent":"485.445526ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T04:49:46.039534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.550278Z","time spent":"489.247272ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T04:49:46.039326Z","caller":"traceutil/trace.go:171","msg":"trace[1931043114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24884; }","duration":"486.737672ms","start":"2026-05-19T04:49:45.552575Z","end":"2026-05-19T04:49:46.039312Z","steps":["trace[1931043114] 'agreement among raft nodes before linearized reading' (duration: 486.524128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:49:46.039968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:49:45.552565Z","time spent":"487.382374ms","remote":"127.0.0.1:34152","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-19T04:49:46.04023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.200378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18746"} {"level":"info","ts":"2026-05-19T04:49:46.040339Z","caller":"traceutil/trace.go:171","msg":"trace[1882444968] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24885; }","duration":"220.385402ms","start":"2026-05-19T04:49:45.81993Z","end":"2026-05-19T04:49:46.040315Z","steps":["trace[1882444968] 'agreement among raft nodes before linearized reading' (duration: 220.096966ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:00.686401Z","caller":"traceutil/trace.go:171","msg":"trace[411493378] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"106.623849ms","start":"2026-05-19T04:50:00.579744Z","end":"2026-05-19T04:50:00.686367Z","steps":["trace[411493378] 'process raft request' (duration: 100.132577ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:00.968679Z","caller":"traceutil/trace.go:171","msg":"trace[1891340042] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"246.922577ms","start":"2026-05-19T04:50:00.721719Z","end":"2026-05-19T04:50:00.968642Z","steps":["trace[1891340042] 'process raft request' (duration: 208.799189ms)","trace[1891340042] 'compare' (duration: 37.776021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:50:01.991377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.658722ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T04:50:01.991486Z","caller":"traceutil/trace.go:171","msg":"trace[170511557] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25828; }","duration":"231.477777ms","start":"2026-05-19T04:50:01.759984Z","end":"2026-05-19T04:50:01.991462Z","steps":["trace[170511557] 'read index received' (duration: 51.731µs)","trace[170511557] 'applied index is now lower than readState.Index' (duration: 231.424016ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:50:01.991739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.736691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-19T04:50:01.991789Z","caller":"traceutil/trace.go:171","msg":"trace[1402975595] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25018; }","duration":"231.845304ms","start":"2026-05-19T04:50:01.759932Z","end":"2026-05-19T04:50:01.991777Z","steps":["trace[1402975595] 'agreement among raft nodes before linearized reading' (duration: 231.595899ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.006093Z","caller":"traceutil/trace.go:171","msg":"trace[314887370] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"134.248277ms","start":"2026-05-19T04:50:01.87182Z","end":"2026-05-19T04:50:02.006068Z","steps":["trace[314887370] 'process raft request' (duration: 133.88375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.252865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.811189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:50:02.253013Z","caller":"traceutil/trace.go:171","msg":"trace[1758131101] linearizableReadLoop","detail":"{readStateIndex:25832; appliedIndex:25829; }","duration":"261.48398ms","start":"2026-05-19T04:50:01.991514Z","end":"2026-05-19T04:50:02.252998Z","steps":["trace[1758131101] 'read index received' (duration: 14.332509ms)","trace[1758131101] 'applied index is now lower than readState.Index' (duration: 247.150551ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:02.253039Z","caller":"traceutil/trace.go:171","msg":"trace[203855675] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"336.827849ms","start":"2026-05-19T04:50:01.916187Z","end":"2026-05-19T04:50:02.253015Z","steps":["trace[203855675] 'process raft request' (duration: 336.758798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.253121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.070273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:50:02.253148Z","caller":"traceutil/trace.go:171","msg":"trace[85222152] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25021; }","duration":"263.132584ms","start":"2026-05-19T04:50:01.990008Z","end":"2026-05-19T04:50:02.25314Z","steps":["trace[85222152] 'agreement among raft nodes before linearized reading' (duration: 263.064073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.253152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:50:01.91616Z","time spent":"336.930151ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:50:02.253144Z","caller":"traceutil/trace.go:171","msg":"trace[590291321] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"336.963491ms","start":"2026-05-19T04:50:01.916152Z","end":"2026-05-19T04:50:02.253116Z","steps":["trace[590291321] 'process raft request' (duration: 192.774915ms)","trace[590291321] 'compare' (duration: 143.634565ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:50:02.253246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T04:50:01.916123Z","time spent":"337.071104ms","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T04:50:02.256009Z","caller":"traceutil/trace.go:171","msg":"trace[1984263116] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"165.398273ms","start":"2026-05-19T04:50:02.090596Z","end":"2026-05-19T04:50:02.255994Z","steps":["trace[1984263116] 'process raft request' (duration: 165.041976ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.256058Z","caller":"traceutil/trace.go:171","msg":"trace[337929296] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"160.032085ms","start":"2026-05-19T04:50:02.096007Z","end":"2026-05-19T04:50:02.256039Z","steps":["trace[337929296] 'process raft request' (duration: 159.7597ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.25643Z","caller":"traceutil/trace.go:171","msg":"trace[1519076803] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"158.779271ms","start":"2026-05-19T04:50:02.097635Z","end":"2026-05-19T04:50:02.256414Z","steps":["trace[1519076803] 'process raft request' (duration: 158.350662ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.256405Z","caller":"traceutil/trace.go:171","msg":"trace[481865456] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"154.667937ms","start":"2026-05-19T04:50:02.101714Z","end":"2026-05-19T04:50:02.256382Z","steps":["trace[481865456] 'process raft request' (duration: 154.536354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.265839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.574355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:50:02.265868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.917199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-19T04:50:02.265881Z","caller":"traceutil/trace.go:171","msg":"trace[1048637481] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25026; }","duration":"210.663276ms","start":"2026-05-19T04:50:02.055205Z","end":"2026-05-19T04:50:02.265868Z","steps":["trace[1048637481] 'agreement among raft nodes before linearized reading' (duration: 210.566934ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.2659Z","caller":"traceutil/trace.go:171","msg":"trace[709685183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25026; }","duration":"136.971992ms","start":"2026-05-19T04:50:02.128919Z","end":"2026-05-19T04:50:02.265891Z","steps":["trace[709685183] 'agreement among raft nodes before linearized reading' (duration: 136.869049ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.265878Z","caller":"traceutil/trace.go:171","msg":"trace[2036393802] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"101.393894ms","start":"2026-05-19T04:50:02.164457Z","end":"2026-05-19T04:50:02.265851Z","steps":["trace[2036393802] 'process raft request' (duration: 101.245781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.265944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.187227ms","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-19T04:50:02.265983Z","caller":"traceutil/trace.go:171","msg":"trace[1115061694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25026; }","duration":"141.254468ms","start":"2026-05-19T04:50:02.12472Z","end":"2026-05-19T04:50:02.265975Z","steps":["trace[1115061694] 'agreement among raft nodes before linearized reading' (duration: 141.169247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.266068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.699767ms","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-19T04:50:02.266118Z","caller":"traceutil/trace.go:171","msg":"trace[156106969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25026; }","duration":"144.779229ms","start":"2026-05-19T04:50:02.121329Z","end":"2026-05-19T04:50:02.266108Z","steps":["trace[156106969] 'agreement among raft nodes before linearized reading' (duration: 144.651606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.266115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.137166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T04:50:02.266183Z","caller":"traceutil/trace.go:171","msg":"trace[960023998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25026; }","duration":"141.230947ms","start":"2026-05-19T04:50:02.124937Z","end":"2026-05-19T04:50:02.266168Z","steps":["trace[960023998] 'agreement among raft nodes before linearized reading' (duration: 141.033263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:02.831475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.858919ms","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-19T04:50:02.831541Z","caller":"traceutil/trace.go:171","msg":"trace[39805074] 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:25032; }","duration":"141.975662ms","start":"2026-05-19T04:50:02.689551Z","end":"2026-05-19T04:50:02.831527Z","steps":["trace[39805074] 'count revisions from in-memory index tree' (duration: 141.775038ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.950059Z","caller":"traceutil/trace.go:171","msg":"trace[1868496976] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"189.636133ms","start":"2026-05-19T04:50:02.760389Z","end":"2026-05-19T04:50:02.950025Z","steps":["trace[1868496976] 'process raft request' (duration: 189.516641ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:02.996929Z","caller":"traceutil/trace.go:171","msg":"trace[495418453] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"156.408342ms","start":"2026-05-19T04:50:02.840498Z","end":"2026-05-19T04:50:02.996906Z","steps":["trace[495418453] 'process raft request' (duration: 156.222149ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.197386Z","caller":"traceutil/trace.go:171","msg":"trace[1901131618] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"164.73691ms","start":"2026-05-19T04:50:03.032624Z","end":"2026-05-19T04:50:03.197361Z","steps":["trace[1901131618] 'process raft request' (duration: 164.640929ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.197494Z","caller":"traceutil/trace.go:171","msg":"trace[1764060540] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"200.601114ms","start":"2026-05-19T04:50:02.996867Z","end":"2026-05-19T04:50:03.197468Z","steps":["trace[1764060540] 'read index received' (duration: 101.355713ms)","trace[1764060540] 'applied index is now lower than readState.Index' (duration: 99.243921ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:03.197645Z","caller":"traceutil/trace.go:171","msg":"trace[788580161] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"221.041126ms","start":"2026-05-19T04:50:02.976589Z","end":"2026-05-19T04:50:03.19763Z","steps":["trace[788580161] 'process raft request' (duration: 121.619882ms)","trace[788580161] 'compare' (duration: 98.70787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:50:03.19796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.593331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1148"} {"level":"warn","ts":"2026-05-19T04:50:03.197992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.634752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:676"} {"level":"warn","ts":"2026-05-19T04:50:03.197988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.642482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:894"} {"level":"warn","ts":"2026-05-19T04:50:03.198005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.699004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21476"} {"level":"info","ts":"2026-05-19T04:50:03.19803Z","caller":"traceutil/trace.go:171","msg":"trace[1253465263] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:25036; }","duration":"249.738563ms","start":"2026-05-19T04:50:02.94828Z","end":"2026-05-19T04:50:03.198019Z","steps":["trace[1253465263] 'agreement among raft nodes before linearized reading' (duration: 249.58277ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.198043Z","caller":"traceutil/trace.go:171","msg":"trace[767043031] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:25036; }","duration":"249.746494ms","start":"2026-05-19T04:50:02.948282Z","end":"2026-05-19T04:50:03.198029Z","steps":["trace[767043031] 'agreement among raft nodes before linearized reading' (duration: 249.5688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:03.198027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.734334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7489"} {"level":"info","ts":"2026-05-19T04:50:03.198058Z","caller":"traceutil/trace.go:171","msg":"trace[1810062368] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:25036; }","duration":"249.773805ms","start":"2026-05-19T04:50:02.948273Z","end":"2026-05-19T04:50:03.198047Z","steps":["trace[1810062368] 'agreement among raft nodes before linearized reading' (duration: 249.582921ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.198094Z","caller":"traceutil/trace.go:171","msg":"trace[1691419305] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:25036; }","duration":"249.817796ms","start":"2026-05-19T04:50:02.94826Z","end":"2026-05-19T04:50:03.198078Z","steps":["trace[1691419305] 'agreement among raft nodes before linearized reading' (duration: 249.584051ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.197999Z","caller":"traceutil/trace.go:171","msg":"trace[449092555] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:25036; }","duration":"249.671913ms","start":"2026-05-19T04:50:02.948312Z","end":"2026-05-19T04:50:03.197984Z","steps":["trace[449092555] 'agreement among raft nodes before linearized reading' (duration: 249.494349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:03.198113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.835496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5054"} {"level":"info","ts":"2026-05-19T04:50:03.198184Z","caller":"traceutil/trace.go:171","msg":"trace[1502385149] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:25036; }","duration":"249.918947ms","start":"2026-05-19T04:50:02.948247Z","end":"2026-05-19T04:50:03.198166Z","steps":["trace[1502385149] 'agreement among raft nodes before linearized reading' (duration: 249.676483ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.353893Z","caller":"traceutil/trace.go:171","msg":"trace[1189185558] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"114.501968ms","start":"2026-05-19T04:50:03.239352Z","end":"2026-05-19T04:50:03.353854Z","steps":["trace[1189185558] 'process raft request' (duration: 114.226933ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.521396Z","caller":"traceutil/trace.go:171","msg":"trace[462080606] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"158.151599ms","start":"2026-05-19T04:50:03.363215Z","end":"2026-05-19T04:50:03.521367Z","steps":["trace[462080606] 'process raft request' (duration: 157.904464ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.521528Z","caller":"traceutil/trace.go:171","msg":"trace[920273430] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"152.127198ms","start":"2026-05-19T04:50:03.369358Z","end":"2026-05-19T04:50:03.521485Z","steps":["trace[920273430] 'process raft request' (duration: 151.975265ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.521607Z","caller":"traceutil/trace.go:171","msg":"trace[916009131] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"158.337952ms","start":"2026-05-19T04:50:03.363231Z","end":"2026-05-19T04:50:03.521569Z","steps":["trace[916009131] 'process raft request' (duration: 158.053837ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.704087Z","caller":"traceutil/trace.go:171","msg":"trace[761365351] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"166.735221ms","start":"2026-05-19T04:50:03.537314Z","end":"2026-05-19T04:50:03.70405Z","steps":["trace[761365351] 'process raft request' (duration: 121.604762ms)","trace[761365351] 'compare' (duration: 44.939355ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:03.737811Z","caller":"traceutil/trace.go:171","msg":"trace[1893915542] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"196.079972ms","start":"2026-05-19T04:50:03.541706Z","end":"2026-05-19T04:50:03.737786Z","steps":["trace[1893915542] 'process raft request' (duration: 195.922799ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:03.966554Z","caller":"traceutil/trace.go:171","msg":"trace[90670080] linearizableReadLoop","detail":"{readStateIndex:25856; appliedIndex:25855; }","duration":"178.510999ms","start":"2026-05-19T04:50:03.78802Z","end":"2026-05-19T04:50:03.966531Z","steps":["trace[90670080] 'read index received' (duration: 178.290043ms)","trace[90670080] 'applied index is now lower than readState.Index' (duration: 217.846µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:03.966608Z","caller":"traceutil/trace.go:171","msg":"trace[768224558] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"184.03379ms","start":"2026-05-19T04:50:03.782553Z","end":"2026-05-19T04:50:03.966587Z","steps":["trace[768224558] 'process raft request' (duration: 183.814635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:03.96677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.717713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-05-19T04:50:03.966899Z","caller":"traceutil/trace.go:171","msg":"trace[730555091] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25045; }","duration":"178.877916ms","start":"2026-05-19T04:50:03.788007Z","end":"2026-05-19T04:50:03.966885Z","steps":["trace[730555091] 'agreement among raft nodes before linearized reading' (duration: 178.61606ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:10.801226Z","caller":"traceutil/trace.go:171","msg":"trace[749328823] linearizableReadLoop","detail":"{readStateIndex:25925; appliedIndex:25924; }","duration":"128.814727ms","start":"2026-05-19T04:50:10.672381Z","end":"2026-05-19T04:50:10.801196Z","steps":["trace[749328823] 'read index received' (duration: 112.964188ms)","trace[749328823] 'applied index is now lower than readState.Index' (duration: 15.849019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:50:10.801423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.018331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:50:10.801485Z","caller":"traceutil/trace.go:171","msg":"trace[235288191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25113; }","duration":"129.122502ms","start":"2026-05-19T04:50:10.672347Z","end":"2026-05-19T04:50:10.801469Z","steps":["trace[235288191] 'agreement among raft nodes before linearized reading' (duration: 128.971359ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:10.801472Z","caller":"traceutil/trace.go:171","msg":"trace[192636479] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"146.913841ms","start":"2026-05-19T04:50:10.654533Z","end":"2026-05-19T04:50:10.801447Z","steps":["trace[192636479] 'process raft request' (duration: 130.768856ms)","trace[192636479] 'compare' (duration: 15.343889ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:14.844135Z","caller":"traceutil/trace.go:171","msg":"trace[866081937] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"105.970556ms","start":"2026-05-19T04:50:14.738146Z","end":"2026-05-19T04:50:14.844117Z","steps":["trace[866081937] 'process raft request' (duration: 105.837272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.26464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.733971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:50:20.26471Z","caller":"traceutil/trace.go:171","msg":"trace[1747844342] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25185; }","duration":"297.837755ms","start":"2026-05-19T04:50:19.966856Z","end":"2026-05-19T04:50:20.264694Z","steps":["trace[1747844342] 'count revisions from in-memory index tree' (duration: 297.615989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.264886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.132399ms","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-19T04:50:20.264924Z","caller":"traceutil/trace.go:171","msg":"trace[1792413600] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25185; }","duration":"288.169829ms","start":"2026-05-19T04:50:19.976743Z","end":"2026-05-19T04:50:20.264913Z","steps":["trace[1792413600] 'count revisions from in-memory index tree' (duration: 288.057097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.265301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.405998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T04:50:20.265342Z","caller":"traceutil/trace.go:171","msg":"trace[474659600] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25185; }","duration":"244.48462ms","start":"2026-05-19T04:50:20.020847Z","end":"2026-05-19T04:50:20.265332Z","steps":["trace[474659600] 'count revisions from in-memory index tree' (duration: 244.334896ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.267231Z","caller":"traceutil/trace.go:171","msg":"trace[593753831] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"167.604808ms","start":"2026-05-19T04:50:20.099611Z","end":"2026-05-19T04:50:20.267216Z","steps":["trace[593753831] 'process raft request' (duration: 128.660524ms)","trace[593753831] 'compare' (duration: 37.75984ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T04:50:20.592404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.307681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T04:50:20.59251Z","caller":"traceutil/trace.go:171","msg":"trace[981525927] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"245.858236ms","start":"2026-05-19T04:50:20.346634Z","end":"2026-05-19T04:50:20.592492Z","steps":["trace[981525927] 'process raft request' (duration: 81.384491ms)","trace[981525927] 'compare' (duration: 163.979225ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:20.603565Z","caller":"traceutil/trace.go:171","msg":"trace[523345487] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"230.637698ms","start":"2026-05-19T04:50:20.372901Z","end":"2026-05-19T04:50:20.603538Z","steps":["trace[523345487] 'process raft request' (duration: 230.554037ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.603596Z","caller":"traceutil/trace.go:171","msg":"trace[1077497896] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"230.966606ms","start":"2026-05-19T04:50:20.372582Z","end":"2026-05-19T04:50:20.603548Z","steps":["trace[1077497896] 'process raft request' (duration: 230.827033ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.603841Z","caller":"traceutil/trace.go:171","msg":"trace[758476214] linearizableReadLoop","detail":"{readStateIndex:26003; appliedIndex:26000; }","duration":"231.010737ms","start":"2026-05-19T04:50:20.372811Z","end":"2026-05-19T04:50:20.603822Z","steps":["trace[758476214] 'read index received' (duration: 55.177282ms)","trace[758476214] 'applied index is now lower than readState.Index' (duration: 175.832285ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:20.603863Z","caller":"traceutil/trace.go:171","msg":"trace[961723663] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"230.720242ms","start":"2026-05-19T04:50:20.373129Z","end":"2026-05-19T04:50:20.603849Z","steps":["trace[961723663] 'process raft request' (duration: 230.603829ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.603843Z","caller":"traceutil/trace.go:171","msg":"trace[1938719720] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"247.789486ms","start":"2026-05-19T04:50:20.356026Z","end":"2026-05-19T04:50:20.603815Z","steps":["trace[1938719720] 'process raft request' (duration: 247.190583ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.603893Z","caller":"traceutil/trace.go:171","msg":"trace[1560789964] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"230.766682ms","start":"2026-05-19T04:50:20.37307Z","end":"2026-05-19T04:50:20.603836Z","steps":["trace[1560789964] 'process raft request' (duration: 230.421635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.604115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.71413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T04:50:20.604126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.947625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T04:50:20.604161Z","caller":"traceutil/trace.go:171","msg":"trace[2029788090] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25192; }","duration":"225.775552ms","start":"2026-05-19T04:50:20.378372Z","end":"2026-05-19T04:50:20.604148Z","steps":["trace[2029788090] 'agreement among raft nodes before linearized reading' (duration: 225.68283ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.60417Z","caller":"traceutil/trace.go:171","msg":"trace[1424049553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25192; }","duration":"231.017468ms","start":"2026-05-19T04:50:20.373138Z","end":"2026-05-19T04:50:20.604156Z","steps":["trace[1424049553] 'agreement among raft nodes before linearized reading' (duration: 230.816844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.604205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.557507ms","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-19T04:50:20.604266Z","caller":"traceutil/trace.go:171","msg":"trace[1458410909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25192; }","duration":"228.619659ms","start":"2026-05-19T04:50:20.375631Z","end":"2026-05-19T04:50:20.60425Z","steps":["trace[1458410909] 'agreement among raft nodes before linearized reading' (duration: 228.456415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.604307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.836573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T04:50:20.604347Z","caller":"traceutil/trace.go:171","msg":"trace[633149077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25192; }","duration":"229.876123ms","start":"2026-05-19T04:50:20.374462Z","end":"2026-05-19T04:50:20.604338Z","steps":["trace[633149077] 'agreement among raft nodes before linearized reading' (duration: 229.782061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.604345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.517107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-05-19T04:50:20.6044Z","caller":"traceutil/trace.go:171","msg":"trace[1501540259] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25192; }","duration":"231.592228ms","start":"2026-05-19T04:50:20.372794Z","end":"2026-05-19T04:50:20.604386Z","steps":["trace[1501540259] 'agreement among raft nodes before linearized reading' (duration: 231.136689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.604425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.607517ms","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-19T04:50:20.604462Z","caller":"traceutil/trace.go:171","msg":"trace[362097796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25192; }","duration":"220.659319ms","start":"2026-05-19T04:50:20.383792Z","end":"2026-05-19T04:50:20.604451Z","steps":["trace[362097796] 'agreement among raft nodes before linearized reading' (duration: 220.556326ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.824018Z","caller":"traceutil/trace.go:171","msg":"trace[1323070060] linearizableReadLoop","detail":"{readStateIndex:26010; appliedIndex:26007; }","duration":"211.483053ms","start":"2026-05-19T04:50:20.612511Z","end":"2026-05-19T04:50:20.823994Z","steps":["trace[1323070060] 'read index received' (duration: 62.280825ms)","trace[1323070060] 'applied index is now lower than readState.Index' (duration: 149.201318ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:20.824036Z","caller":"traceutil/trace.go:171","msg":"trace[1582996555] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"215.236359ms","start":"2026-05-19T04:50:20.608763Z","end":"2026-05-19T04:50:20.823999Z","steps":["trace[1582996555] 'process raft request' (duration: 178.10265ms)","trace[1582996555] 'compare' (duration: 36.821022ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:50:20.824102Z","caller":"traceutil/trace.go:171","msg":"trace[1599122092] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"215.031945ms","start":"2026-05-19T04:50:20.609043Z","end":"2026-05-19T04:50:20.824075Z","steps":["trace[1599122092] 'process raft request' (duration: 214.843301ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.824138Z","caller":"traceutil/trace.go:171","msg":"trace[1276431864] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"214.649526ms","start":"2026-05-19T04:50:20.609478Z","end":"2026-05-19T04:50:20.824127Z","steps":["trace[1276431864] 'process raft request' (duration: 214.473113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T04:50:20.824284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.766978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"warn","ts":"2026-05-19T04:50:20.824266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.257499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T04:50:20.824326Z","caller":"traceutil/trace.go:171","msg":"trace[386494289] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25196; }","duration":"211.86607ms","start":"2026-05-19T04:50:20.61245Z","end":"2026-05-19T04:50:20.824316Z","steps":["trace[386494289] 'agreement among raft nodes before linearized reading' (duration: 211.706537ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:20.824344Z","caller":"traceutil/trace.go:171","msg":"trace[1642470393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25196; }","duration":"150.37269ms","start":"2026-05-19T04:50:20.673953Z","end":"2026-05-19T04:50:20.824325Z","steps":["trace[1642470393] 'agreement among raft nodes before linearized reading' (duration: 150.252018ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:50:21.019694Z","caller":"traceutil/trace.go:171","msg":"trace[1562912227] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"151.146537ms","start":"2026-05-19T04:50:20.868517Z","end":"2026-05-19T04:50:21.019663Z","steps":["trace[1562912227] 'process raft request' (duration: 122.307386ms)","trace[1562912227] 'compare' (duration: 28.444943ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T04:52:03.505673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23336} {"level":"info","ts":"2026-05-19T04:52:03.634144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23336,"took":"124.753325ms","hash":824148658,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":36626432,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-19T04:52:03.634213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":824148658,"revision":23336,"compact-revision":20115} {"level":"info","ts":"2026-05-19T04:52:19.05389Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a44.wal"} {"level":"info","ts":"2026-05-19T04:53:27.787442Z","caller":"traceutil/trace.go:171","msg":"trace[1195575480] transaction","detail":"{read_only:false; response_revision:27073; number_of_response:1; }","duration":"139.650395ms","start":"2026-05-19T04:53:27.647752Z","end":"2026-05-19T04:53:27.787403Z","steps":["trace[1195575480] 'process raft request' (duration: 139.377199ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T04:57:03.517559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26226} {"level":"info","ts":"2026-05-19T04:57:03.66513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26226,"took":"143.120565ms","hash":3278585249,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":33296384,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-19T04:57:03.665203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3278585249,"revision":26226,"compact-revision":23336} {"level":"info","ts":"2026-05-19T04:57:39.242225Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T04:57:39.246829Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T04:57:39.246901Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T05:02:03.52423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28818} {"level":"info","ts":"2026-05-19T05:02:03.656971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28818,"took":"128.394195ms","hash":894373558,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T05:02:03.657066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":894373558,"revision":28818,"compact-revision":26226} {"level":"info","ts":"2026-05-19T05:07:03.531574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31106} {"level":"info","ts":"2026-05-19T05:07:03.656279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31106,"took":"120.38337ms","hash":69465941,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T05:07:03.656327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":69465941,"revision":31106,"compact-revision":28818} {"level":"info","ts":"2026-05-19T05:12:03.544581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33433} {"level":"info","ts":"2026-05-19T05:12:03.699424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33433,"took":"150.201339ms","hash":3672069371,"current-db-size-bytes":57569280,"current-db-size":"58 MB","current-db-size-in-use-bytes":32112640,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T05:12:03.699554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672069371,"revision":33433,"compact-revision":31106} {"level":"info","ts":"2026-05-19T05:17:03.550171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35699} {"level":"info","ts":"2026-05-19T05:17:03.69309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35699,"took":"138.115546ms","hash":133291803,"current-db-size-bytes":59727872,"current-db-size":"60 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T05:17:03.693166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":133291803,"revision":35699,"compact-revision":33433} {"level":"info","ts":"2026-05-19T05:18:55.50395Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T05:18:55.508005Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-19T05:18:55.50808Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-19T05:22:03.555183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37928} {"level":"info","ts":"2026-05-19T05:22:03.669062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37928,"took":"109.553472ms","hash":1350158893,"current-db-size-bytes":59727872,"current-db-size":"60 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T05:22:03.669122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1350158893,"revision":37928,"compact-revision":35699}