{"level":"warn","ts":"2026-07-02T03:24:45.903367Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T03:24:45.903493Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T03:24:45.903572Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T03:24:45.903583Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-02T03:24:45.903604Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T03:24:45.904321Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-07-02T03:24:45.904413Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T03:24:45.906537Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.917135ms"} {"level":"info","ts":"2026-07-02T03:24:45.910864Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-07-02T03:24:45.910959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T03:24:45.910987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-07-02T03:24:45.911004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T03:24:45.911019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-07-02T03:24:45.911064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-07-02T03:24:45.916894Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T03:24:45.918276Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T03:24:45.919579Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T03:24:45.922759Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T03:24:45.923033Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T03:24:45.923181Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T03:24:45.923256Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T03:24:45.92327Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T03:24:45.930099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-07-02T03:24:45.930521Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-02T03:24:45.932975Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T03:24:45.933134Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-02T03:24:45.933209Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-02T03:24:45.933345Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T03:24:45.933412Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T03:24:46.011877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T03:24:46.011966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T03:24:46.011993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-07-02T03:24:46.012018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-07-02T03:24:46.01203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-02T03:24:46.012049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-07-02T03:24:46.012064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-02T03:24:46.013147Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:24:46.013983Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T03:24:46.014242Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T03:24:46.014301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T03:24:46.014568Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:24:46.014747Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:24:46.014806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T03:24:46.017792Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-07-02T03:24:46.017908Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T03:24:46.017983Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T03:24:46.018086Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T03:25:23.161499Z","caller":"traceutil/trace.go:171","msg":"trace[1218734204] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"114.941025ms","start":"2026-07-02T03:25:23.046526Z","end":"2026-07-02T03:25:23.161467Z","steps":["trace[1218734204] 'process raft request' (duration: 85.431999ms)","trace[1218734204] 'compare' (duration: 29.301372ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:25:23.162213Z","caller":"traceutil/trace.go:171","msg":"trace[1544743702] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"110.695466ms","start":"2026-07-02T03:25:23.05146Z","end":"2026-07-02T03:25:23.162155Z","steps":["trace[1544743702] 'process raft request' (duration: 110.229227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:29.50615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.542353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-02T03:25:29.506301Z","caller":"traceutil/trace.go:171","msg":"trace[1052201356] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:632; }","duration":"246.733798ms","start":"2026-07-02T03:25:29.259541Z","end":"2026-07-02T03:25:29.506274Z","steps":["trace[1052201356] 'range keys from in-memory index tree' (duration: 246.438722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:29.506299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.937376ms","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-07-02T03:25:29.506444Z","caller":"traceutil/trace.go:171","msg":"trace[582848619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"149.16678ms","start":"2026-07-02T03:25:29.357245Z","end":"2026-07-02T03:25:29.506412Z","steps":["trace[582848619] 'range keys from in-memory index tree' (duration: 148.613709ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:25:29.591796Z","caller":"traceutil/trace.go:171","msg":"trace[57482808] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"198.989048ms","start":"2026-07-02T03:25:29.392754Z","end":"2026-07-02T03:25:29.591743Z","steps":["trace[57482808] 'process raft request' (duration: 197.279015ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:25:30.062029Z","caller":"traceutil/trace.go:171","msg":"trace[187469810] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"254.25686ms","start":"2026-07-02T03:25:29.807736Z","end":"2026-07-02T03:25:30.061993Z","steps":["trace[187469810] 'read index received' (duration: 254.074275ms)","trace[187469810] 'applied index is now lower than readState.Index' (duration: 181.285µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:25:30.062114Z","caller":"traceutil/trace.go:171","msg":"trace[2069361147] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"404.165963ms","start":"2026-07-02T03:25:29.657895Z","end":"2026-07-02T03:25:30.062061Z","steps":["trace[2069361147] 'process raft request' (duration: 403.894617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:30.062509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.748358ms","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-07-02T03:25:30.06257Z","caller":"traceutil/trace.go:171","msg":"trace[349726136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"254.874651ms","start":"2026-07-02T03:25:29.807679Z","end":"2026-07-02T03:25:30.062553Z","steps":["trace[349726136] 'agreement among raft nodes before linearized reading' (duration: 254.454023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:25:30.062816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T03:25:29.657849Z","time spent":"404.380407ms","remote":"127.0.0.1:56236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T03:27:02.423043Z","caller":"traceutil/trace.go:171","msg":"trace[777967473] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"258.040676ms","start":"2026-07-02T03:27:02.164963Z","end":"2026-07-02T03:27:02.423004Z","steps":["trace[777967473] 'process raft request' (duration: 251.027503ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:27:02.835175Z","caller":"traceutil/trace.go:171","msg":"trace[1120978376] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"180.410013ms","start":"2026-07-02T03:27:02.654729Z","end":"2026-07-02T03:27:02.835139Z","steps":["trace[1120978376] 'process raft request' (duration: 180.291711ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:27:02.835291Z","caller":"traceutil/trace.go:171","msg":"trace[52753015] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"202.882719ms","start":"2026-07-02T03:27:02.632366Z","end":"2026-07-02T03:27:02.835249Z","steps":["trace[52753015] 'process raft request' (duration: 149.408275ms)","trace[52753015] 'compare' (duration: 52.863902ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:27:53.223435Z","caller":"traceutil/trace.go:171","msg":"trace[1460170359] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2290; }","duration":"127.57769ms","start":"2026-07-02T03:27:53.095814Z","end":"2026-07-02T03:27:53.223391Z","steps":["trace[1460170359] 'read index received' (duration: 126.849026ms)","trace[1460170359] 'applied index is now lower than readState.Index' (duration: 726.744µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:27:53.223543Z","caller":"traceutil/trace.go:171","msg":"trace[1646682806] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"148.746142ms","start":"2026-07-02T03:27:53.074742Z","end":"2026-07-02T03:27:53.223488Z","steps":["trace[1646682806] 'process raft request' (duration: 147.976947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:27:53.223757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.904527ms","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-07-02T03:27:53.223822Z","caller":"traceutil/trace.go:171","msg":"trace[244086665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2205; }","duration":"128.017209ms","start":"2026-07-02T03:27:53.095786Z","end":"2026-07-02T03:27:53.223803Z","steps":["trace[244086665] 'agreement among raft nodes before linearized reading' (duration: 127.773454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:27:53.223839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.968874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-02T03:27:53.223898Z","caller":"traceutil/trace.go:171","msg":"trace[1193572111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2205; }","duration":"121.087047ms","start":"2026-07-02T03:27:53.102795Z","end":"2026-07-02T03:27:53.223882Z","steps":["trace[1193572111] 'agreement among raft nodes before linearized reading' (duration: 120.823602ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:29:07.014304Z","caller":"traceutil/trace.go:171","msg":"trace[562026521] linearizableReadLoop","detail":"{readStateIndex:2911; appliedIndex:2910; }","duration":"135.422575ms","start":"2026-07-02T03:29:06.878851Z","end":"2026-07-02T03:29:07.014274Z","steps":["trace[562026521] 'read index received' (duration: 63.094089ms)","trace[562026521] 'applied index is now lower than readState.Index' (duration: 72.327096ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:29:07.014495Z","caller":"traceutil/trace.go:171","msg":"trace[1104971260] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"219.153917ms","start":"2026-07-02T03:29:06.795287Z","end":"2026-07-02T03:29:07.014441Z","steps":["trace[1104971260] 'process raft request' (duration: 146.774651ms)","trace[1104971260] 'compare' (duration: 71.882846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:29:07.014567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.70116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-02T03:29:07.014629Z","caller":"traceutil/trace.go:171","msg":"trace[1803961342] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2803; }","duration":"135.820172ms","start":"2026-07-02T03:29:06.878776Z","end":"2026-07-02T03:29:07.014596Z","steps":["trace[1803961342] 'agreement among raft nodes before linearized reading' (duration: 135.630968ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:31:47.732094Z","caller":"traceutil/trace.go:171","msg":"trace[621498209] transaction","detail":"{read_only:false; response_revision:3849; number_of_response:1; }","duration":"102.168815ms","start":"2026-07-02T03:31:47.629868Z","end":"2026-07-02T03:31:47.732037Z","steps":["trace[621498209] 'process raft request' (duration: 101.968952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:33:12.345048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.992389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:33:12.345165Z","caller":"traceutil/trace.go:171","msg":"trace[1342304353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4909; }","duration":"198.191613ms","start":"2026-07-02T03:33:12.146947Z","end":"2026-07-02T03:33:12.345138Z","steps":["trace[1342304353] 'range keys from in-memory index tree' (duration: 197.877277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:33:12.345142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.55144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-02T03:33:12.345285Z","caller":"traceutil/trace.go:171","msg":"trace[1237419931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4909; }","duration":"187.767685ms","start":"2026-07-02T03:33:12.157485Z","end":"2026-07-02T03:33:12.345252Z","steps":["trace[1237419931] 'range keys from in-memory index tree' (duration: 187.230885ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:33:12.347178Z","caller":"traceutil/trace.go:171","msg":"trace[859445325] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"145.794756ms","start":"2026-07-02T03:33:12.201361Z","end":"2026-07-02T03:33:12.347156Z","steps":["trace[859445325] 'process raft request' (duration: 145.635913ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:33:29.753784Z","caller":"traceutil/trace.go:171","msg":"trace[459231811] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"168.885455ms","start":"2026-07-02T03:33:29.58446Z","end":"2026-07-02T03:33:29.753345Z","steps":["trace[459231811] 'process raft request' (duration: 168.685501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:33:37.77034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.499111ms","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-07-02T03:33:37.770481Z","caller":"traceutil/trace.go:171","msg":"trace[635903455] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5494; }","duration":"154.722136ms","start":"2026-07-02T03:33:37.615727Z","end":"2026-07-02T03:33:37.770449Z","steps":["trace[635903455] 'count revisions from in-memory index tree' (duration: 154.386659ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:33:38.147154Z","caller":"traceutil/trace.go:171","msg":"trace[1324725327] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"117.68315ms","start":"2026-07-02T03:33:38.029436Z","end":"2026-07-02T03:33:38.147119Z","steps":["trace[1324725327] 'process raft request' (duration: 117.437035ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:33:38.184622Z","caller":"traceutil/trace.go:171","msg":"trace[1848990784] linearizableReadLoop","detail":"{readStateIndex:5702; appliedIndex:5699; }","duration":"142.979372ms","start":"2026-07-02T03:33:38.041599Z","end":"2026-07-02T03:33:38.184578Z","steps":["trace[1848990784] 'read index received' (duration: 105.381846ms)","trace[1848990784] 'applied index is now lower than readState.Index' (duration: 37.595716ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:33:38.184723Z","caller":"traceutil/trace.go:171","msg":"trace[2108867805] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"152.112335ms","start":"2026-07-02T03:33:38.032559Z","end":"2026-07-02T03:33:38.184671Z","steps":["trace[2108867805] 'process raft request' (duration: 151.951153ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:33:38.184798Z","caller":"traceutil/trace.go:171","msg":"trace[1695119082] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"154.769416ms","start":"2026-07-02T03:33:38.030003Z","end":"2026-07-02T03:33:38.184772Z","steps":["trace[1695119082] 'process raft request' (duration: 154.319768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:33:38.184928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.269928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:33:38.185087Z","caller":"traceutil/trace.go:171","msg":"trace[1842355595] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5500; }","duration":"143.469791ms","start":"2026-07-02T03:33:38.041581Z","end":"2026-07-02T03:33:38.185051Z","steps":["trace[1842355595] 'agreement among raft nodes before linearized reading' (duration: 143.187165ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:34:46.193052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3019} {"level":"info","ts":"2026-07-02T03:34:46.278451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3019,"took":"83.294608ms","hash":1937826534,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T03:34:46.278536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1937826534,"revision":3019,"compact-revision":-1} {"level":"info","ts":"2026-07-02T03:39:46.199102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6094} {"level":"info","ts":"2026-07-02T03:39:46.302147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6094,"took":"99.825719ms","hash":3606456836,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":21819392,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-02T03:39:46.302236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3606456836,"revision":6094,"compact-revision":3019} {"level":"info","ts":"2026-07-02T03:39:50.684817Z","caller":"traceutil/trace.go:171","msg":"trace[874909308] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"139.486141ms","start":"2026-07-02T03:39:50.545288Z","end":"2026-07-02T03:39:50.684774Z","steps":["trace[874909308] 'process raft request' (duration: 139.289787ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:01.835214Z","caller":"traceutil/trace.go:171","msg":"trace[765683676] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"121.27417ms","start":"2026-07-02T03:40:01.713872Z","end":"2026-07-02T03:40:01.835146Z","steps":["trace[765683676] 'process raft request' (duration: 117.58221ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:02.498375Z","caller":"traceutil/trace.go:171","msg":"trace[1437872408] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"265.435662ms","start":"2026-07-02T03:40:02.232902Z","end":"2026-07-02T03:40:02.498338Z","steps":["trace[1437872408] 'process raft request' (duration: 265.267809ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:31.819689Z","caller":"traceutil/trace.go:171","msg":"trace[1621001745] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8908; }","duration":"191.796146ms","start":"2026-07-02T03:40:31.627857Z","end":"2026-07-02T03:40:31.819653Z","steps":["trace[1621001745] 'read index received' (duration: 191.595473ms)","trace[1621001745] 'applied index is now lower than readState.Index' (duration: 199.233µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:40:31.819782Z","caller":"traceutil/trace.go:171","msg":"trace[2097699035] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"260.522757ms","start":"2026-07-02T03:40:31.559214Z","end":"2026-07-02T03:40:31.819737Z","steps":["trace[2097699035] 'process raft request' (duration: 260.255002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:40:31.820171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.481536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T03:40:31.820232Z","caller":"traceutil/trace.go:171","msg":"trace[663977668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8606; }","duration":"184.585668ms","start":"2026-07-02T03:40:31.63563Z","end":"2026-07-02T03:40:31.820216Z","steps":["trace[663977668] 'agreement among raft nodes before linearized reading' (duration: 184.352314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:40:31.820335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.450668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-02T03:40:31.820495Z","caller":"traceutil/trace.go:171","msg":"trace[1025155267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8606; }","duration":"192.679103ms","start":"2026-07-02T03:40:31.627759Z","end":"2026-07-02T03:40:31.820438Z","steps":["trace[1025155267] 'agreement among raft nodes before linearized reading' (duration: 192.060982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:40:31.821844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.368629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6d4bb478cb-z5m5w.18be5bee56ed0cff\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-02T03:40:31.821958Z","caller":"traceutil/trace.go:171","msg":"trace[473327497] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6d4bb478cb-z5m5w.18be5bee56ed0cff; range_end:; response_count:1; response_revision:8606; }","duration":"114.787037ms","start":"2026-07-02T03:40:31.707142Z","end":"2026-07-02T03:40:31.821929Z","steps":["trace[473327497] 'agreement among raft nodes before linearized reading' (duration: 113.065874ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:32.052174Z","caller":"traceutil/trace.go:171","msg":"trace[259852596] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"227.32192ms","start":"2026-07-02T03:40:31.824815Z","end":"2026-07-02T03:40:32.052137Z","steps":["trace[259852596] 'process raft request' (duration: 227.073196ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:32.053209Z","caller":"traceutil/trace.go:171","msg":"trace[162997993] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"227.642995ms","start":"2026-07-02T03:40:31.825533Z","end":"2026-07-02T03:40:32.053176Z","steps":["trace[162997993] 'process raft request' (duration: 227.38942ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:32.053574Z","caller":"traceutil/trace.go:171","msg":"trace[762370643] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"227.712157ms","start":"2026-07-02T03:40:31.825828Z","end":"2026-07-02T03:40:32.05354Z","steps":["trace[762370643] 'process raft request' (duration: 227.255008ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:32.054265Z","caller":"traceutil/trace.go:171","msg":"trace[832336123] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"228.310338ms","start":"2026-07-02T03:40:31.825934Z","end":"2026-07-02T03:40:32.054245Z","steps":["trace[832336123] 'process raft request' (duration: 227.385671ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:32.054462Z","caller":"traceutil/trace.go:171","msg":"trace[484208755] linearizableReadLoop","detail":"{readStateIndex:8913; appliedIndex:8909; }","duration":"198.346502ms","start":"2026-07-02T03:40:31.856097Z","end":"2026-07-02T03:40:32.054443Z","steps":["trace[484208755] 'read index received' (duration: 195.780863ms)","trace[484208755] 'applied index is now lower than readState.Index' (duration: 2.564079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:40:32.054686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.526686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:40:32.054771Z","caller":"traceutil/trace.go:171","msg":"trace[347915906] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8611; }","duration":"198.668659ms","start":"2026-07-02T03:40:31.856052Z","end":"2026-07-02T03:40:32.054721Z","steps":["trace[347915906] 'agreement among raft nodes before linearized reading' (duration: 198.531926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:40:32.055366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.016891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T03:40:32.055457Z","caller":"traceutil/trace.go:171","msg":"trace[1067384693] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8611; }","duration":"114.140703ms","start":"2026-07-02T03:40:31.941304Z","end":"2026-07-02T03:40:32.055445Z","steps":["trace[1067384693] 'agreement among raft nodes before linearized reading' (duration: 113.98399ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:40:35.255702Z","caller":"traceutil/trace.go:171","msg":"trace[364716721] linearizableReadLoop","detail":"{readStateIndex:8928; appliedIndex:8927; }","duration":"168.687063ms","start":"2026-07-02T03:40:35.08698Z","end":"2026-07-02T03:40:35.255667Z","steps":["trace[364716721] 'read index received' (duration: 168.383897ms)","trace[364716721] 'applied index is now lower than readState.Index' (duration: 301.506µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:40:35.255749Z","caller":"traceutil/trace.go:171","msg":"trace[443762637] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"308.396098ms","start":"2026-07-02T03:40:34.947298Z","end":"2026-07-02T03:40:35.255694Z","steps":["trace[443762637] 'process raft request' (duration: 308.099172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:40:35.255995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.998308ms","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-07-02T03:40:35.256036Z","caller":"traceutil/trace.go:171","msg":"trace[726948718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8624; }","duration":"169.108771ms","start":"2026-07-02T03:40:35.086915Z","end":"2026-07-02T03:40:35.256024Z","steps":["trace[726948718] 'agreement among raft nodes before linearized reading' (duration: 168.927007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:40:35.256158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T03:40:34.94728Z","time spent":"308.56587ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T03:40:35.256161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.292381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:40:35.256264Z","caller":"traceutil/trace.go:171","msg":"trace[271177636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8624; }","duration":"108.453455ms","start":"2026-07-02T03:40:35.147782Z","end":"2026-07-02T03:40:35.256235Z","steps":["trace[271177636] 'agreement among raft nodes before linearized reading' (duration: 108.288132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:40:44.876949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.623521ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T03:40:44.87748Z","caller":"traceutil/trace.go:171","msg":"trace[1859125089] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"150.969622ms","start":"2026-07-02T03:40:44.726484Z","end":"2026-07-02T03:40:44.877454Z","steps":["trace[1859125089] 'process raft request' (duration: 150.663506ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:41:02.168507Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f0.wal"} {"level":"warn","ts":"2026-07-02T03:42:00.94134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.60279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:42:00.941478Z","caller":"traceutil/trace.go:171","msg":"trace[1763219180] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9467; }","duration":"199.777793ms","start":"2026-07-02T03:42:00.741677Z","end":"2026-07-02T03:42:00.941454Z","steps":["trace[1763219180] 'range keys from in-memory index tree' (duration: 199.537548ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:42:27.884547Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T03:42:27.889912Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-02T03:42:27.889993Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-02T03:44:46.205954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8183} {"level":"info","ts":"2026-07-02T03:44:46.268948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8183,"took":"60.288233ms","hash":967291688,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":29638656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T03:44:46.269017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":967291688,"revision":8183,"compact-revision":6094} {"level":"info","ts":"2026-07-02T03:45:51.168353Z","caller":"traceutil/trace.go:171","msg":"trace[952964242] linearizableReadLoop","detail":"{readStateIndex:11732; appliedIndex:11730; }","duration":"162.296622ms","start":"2026-07-02T03:45:51.006026Z","end":"2026-07-02T03:45:51.168323Z","steps":["trace[952964242] 'read index received' (duration: 2.189132ms)","trace[952964242] 'applied index is now lower than readState.Index' (duration: 160.10608ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T03:45:51.168529Z","caller":"traceutil/trace.go:171","msg":"trace[284147384] transaction","detail":"{read_only:false; response_revision:11348; number_of_response:1; }","duration":"168.758935ms","start":"2026-07-02T03:45:50.999718Z","end":"2026-07-02T03:45:51.168477Z","steps":["trace[284147384] 'process raft request' (duration: 168.420878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:45:51.168699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.502106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:45:51.168959Z","caller":"traceutil/trace.go:171","msg":"trace[2038171029] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11348; }","duration":"162.977356ms","start":"2026-07-02T03:45:51.005958Z","end":"2026-07-02T03:45:51.168935Z","steps":["trace[2038171029] 'agreement among raft nodes before linearized reading' (duration: 162.519547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:45:51.175397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.614052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T03:45:51.175458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.373921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-07-02T03:45:51.1755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.729855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:45:51.175552Z","caller":"traceutil/trace.go:171","msg":"trace[1403203643] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11349; }","duration":"168.855507ms","start":"2026-07-02T03:45:51.006683Z","end":"2026-07-02T03:45:51.175538Z","steps":["trace[1403203643] 'agreement among raft nodes before linearized reading' (duration: 168.731014ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:45:51.175554Z","caller":"traceutil/trace.go:171","msg":"trace[157276594] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11349; }","duration":"166.501953ms","start":"2026-07-02T03:45:51.009027Z","end":"2026-07-02T03:45:51.175529Z","steps":["trace[157276594] 'agreement among raft nodes before linearized reading' (duration: 166.259348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:45:51.175577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.746294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:45:51.175542Z","caller":"traceutil/trace.go:171","msg":"trace[1984554980] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11349; }","duration":"168.822046ms","start":"2026-07-02T03:45:51.006685Z","end":"2026-07-02T03:45:51.175507Z","steps":["trace[1984554980] 'agreement among raft nodes before linearized reading' (duration: 168.578022ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:45:51.175721Z","caller":"traceutil/trace.go:171","msg":"trace[523754031] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11349; }","duration":"168.918008ms","start":"2026-07-02T03:45:51.006767Z","end":"2026-07-02T03:45:51.175685Z","steps":["trace[523754031] 'agreement among raft nodes before linearized reading' (duration: 168.645093ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:49:46.212257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10782} {"level":"info","ts":"2026-07-02T03:49:46.296456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10782,"took":"81.440995ms","hash":3999793119,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":27045888,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-02T03:49:46.296519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3999793119,"revision":10782,"compact-revision":8183} {"level":"warn","ts":"2026-07-02T03:52:29.220475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.825955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:52:29.220581Z","caller":"traceutil/trace.go:171","msg":"trace[1761745402] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14004; }","duration":"168.950617ms","start":"2026-07-02T03:52:29.051605Z","end":"2026-07-02T03:52:29.220555Z","steps":["trace[1761745402] 'range keys from in-memory index tree' (duration: 168.741952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:52:30.078042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.70046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-02T03:52:30.07831Z","caller":"traceutil/trace.go:171","msg":"trace[1524603965] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:14009; }","duration":"117.017646ms","start":"2026-07-02T03:52:29.961248Z","end":"2026-07-02T03:52:30.078266Z","steps":["trace[1524603965] 'range keys from in-memory index tree' (duration: 116.447604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:52:39.514213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.674824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T03:52:39.514342Z","caller":"traceutil/trace.go:171","msg":"trace[371218343] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:14178; }","duration":"110.825657ms","start":"2026-07-02T03:52:39.403491Z","end":"2026-07-02T03:52:39.514317Z","steps":["trace[371218343] 'count revisions from in-memory index tree' (duration: 110.564412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:52:39.514542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.066908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-02T03:52:39.514681Z","caller":"traceutil/trace.go:171","msg":"trace[1583837777] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14178; }","duration":"114.266732ms","start":"2026-07-02T03:52:39.400379Z","end":"2026-07-02T03:52:39.514646Z","steps":["trace[1583837777] 'range keys from in-memory index tree' (duration: 113.60833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:52:39.846107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.181615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-nb-0.18be5ca31f3e90b0\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-07-02T03:52:39.846207Z","caller":"traceutil/trace.go:171","msg":"trace[1864721453] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-nb-0.18be5ca31f3e90b0; range_end:; response_count:1; response_revision:14180; }","duration":"231.347749ms","start":"2026-07-02T03:52:39.614829Z","end":"2026-07-02T03:52:39.846177Z","steps":["trace[1864721453] 'range keys from in-memory index tree' (duration: 230.906931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:52:39.846417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.153032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b8f0db6-9dfe-4ede-9611-0fe15c1fd93b\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-07-02T03:52:39.846547Z","caller":"traceutil/trace.go:171","msg":"trace[1712310889] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b8f0db6-9dfe-4ede-9611-0fe15c1fd93b; range_end:; response_count:1; response_revision:14180; }","duration":"168.355416ms","start":"2026-07-02T03:52:39.678159Z","end":"2026-07-02T03:52:39.846514Z","steps":["trace[1712310889] 'range keys from in-memory index tree' (duration: 167.916297ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:52:39.852475Z","caller":"traceutil/trace.go:171","msg":"trace[887424911] transaction","detail":"{read_only:false; response_revision:14181; number_of_response:1; }","duration":"132.280367ms","start":"2026-07-02T03:52:39.72017Z","end":"2026-07-02T03:52:39.852451Z","steps":["trace[887424911] 'process raft request' (duration: 131.717755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:10.605013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.537119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-02T03:54:10.605082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.623998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T03:54:10.605147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.646421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-02T03:54:10.605178Z","caller":"traceutil/trace.go:171","msg":"trace[2124656757] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15224; }","duration":"156.760073ms","start":"2026-07-02T03:54:10.448376Z","end":"2026-07-02T03:54:10.605136Z","steps":["trace[2124656757] 'range keys from in-memory index tree' (duration: 156.272894ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:10.60521Z","caller":"traceutil/trace.go:171","msg":"trace[1560747958] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15224; }","duration":"237.806401ms","start":"2026-07-02T03:54:10.367368Z","end":"2026-07-02T03:54:10.605175Z","steps":["trace[1560747958] 'range keys from in-memory index tree' (duration: 237.520256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:10.605212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.270691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-02T03:54:10.60552Z","caller":"traceutil/trace.go:171","msg":"trace[755168322] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15224; }","duration":"237.637038ms","start":"2026-07-02T03:54:10.367864Z","end":"2026-07-02T03:54:10.605501Z","steps":["trace[755168322] 'range keys from in-memory index tree' (duration: 237.076758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:10.605048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.958645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T03:54:10.605627Z","caller":"traceutil/trace.go:171","msg":"trace[1327949301] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15224; }","duration":"159.535806ms","start":"2026-07-02T03:54:10.446047Z","end":"2026-07-02T03:54:10.605583Z","steps":["trace[1327949301] 'range keys from in-memory index tree' (duration: 158.741891ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:10.60621Z","caller":"traceutil/trace.go:171","msg":"trace[1607279899] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15224; }","duration":"156.754893ms","start":"2026-07-02T03:54:10.448443Z","end":"2026-07-02T03:54:10.605198Z","steps":["trace[1607279899] 'range keys from in-memory index tree' (duration: 156.467508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T03:54:10.60638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.176949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-02T03:54:10.606447Z","caller":"traceutil/trace.go:171","msg":"trace[1540045319] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15224; }","duration":"236.70218ms","start":"2026-07-02T03:54:10.369728Z","end":"2026-07-02T03:54:10.60643Z","steps":["trace[1540045319] 'range keys from in-memory index tree' (duration: 235.051578ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:10.778912Z","caller":"traceutil/trace.go:171","msg":"trace[506321298] transaction","detail":"{read_only:false; response_revision:15226; number_of_response:1; }","duration":"126.73374ms","start":"2026-07-02T03:54:10.652136Z","end":"2026-07-02T03:54:10.77887Z","steps":["trace[506321298] 'process raft request' (duration: 124.828034ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:10.779147Z","caller":"traceutil/trace.go:171","msg":"trace[1833205406] transaction","detail":"{read_only:false; response_revision:15227; number_of_response:1; }","duration":"125.636639ms","start":"2026-07-02T03:54:10.653469Z","end":"2026-07-02T03:54:10.779106Z","steps":["trace[1833205406] 'process raft request' (duration: 125.417075ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:10.77912Z","caller":"traceutil/trace.go:171","msg":"trace[292364902] linearizableReadLoop","detail":"{readStateIndex:15740; appliedIndex:15738; }","duration":"124.826204ms","start":"2026-07-02T03:54:10.654237Z","end":"2026-07-02T03:54:10.779063Z","steps":["trace[292364902] 'read index received' (duration: 122.829086ms)","trace[292364902] 'applied index is now lower than readState.Index' (duration: 1.995438ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T03:54:10.779413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.140809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-02T03:54:10.77953Z","caller":"traceutil/trace.go:171","msg":"trace[1479042801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15227; }","duration":"125.299733ms","start":"2026-07-02T03:54:10.654197Z","end":"2026-07-02T03:54:10.779496Z","steps":["trace[1479042801] 'agreement among raft nodes before linearized reading' (duration: 125.008447ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T03:54:46.219344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12869} {"level":"info","ts":"2026-07-02T03:54:46.309158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12869,"took":"83.014414ms","hash":1506201722,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":30175232,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T03:54:46.309642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1506201722,"revision":12869,"compact-revision":10782} {"level":"info","ts":"2026-07-02T03:59:46.226233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15408} {"level":"info","ts":"2026-07-02T03:59:46.326039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15408,"took":"95.471523ms","hash":604456432,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":32124928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T03:59:46.326116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":604456432,"revision":15408,"compact-revision":12869} {"level":"info","ts":"2026-07-02T04:00:06.341616Z","caller":"traceutil/trace.go:171","msg":"trace[411065187] linearizableReadLoop","detail":"{readStateIndex:18557; appliedIndex:18556; }","duration":"331.965717ms","start":"2026-07-02T04:00:06.009615Z","end":"2026-07-02T04:00:06.341581Z","steps":["trace[411065187] 'read index received' (duration: 287.744982ms)","trace[411065187] 'applied index is now lower than readState.Index' (duration: 44.218995ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:00:06.341775Z","caller":"traceutil/trace.go:171","msg":"trace[1653812753] transaction","detail":"{read_only:false; response_revision:17957; number_of_response:1; }","duration":"410.413774ms","start":"2026-07-02T04:00:05.931301Z","end":"2026-07-02T04:00:06.341715Z","steps":["trace[1653812753] 'process raft request' (duration: 366.117609ms)","trace[1653812753] 'compare' (duration: 43.270766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:00:06.342033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.406005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-02T04:00:06.342051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:00:05.931275Z","time spent":"410.603017ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8399,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:00:06.342087Z","caller":"traceutil/trace.go:171","msg":"trace[2066134999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17957; }","duration":"332.524097ms","start":"2026-07-02T04:00:06.009546Z","end":"2026-07-02T04:00:06.342071Z","steps":["trace[2066134999] 'agreement among raft nodes before linearized reading' (duration: 332.207401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:06.342132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:00:06.009518Z","time spent":"332.605279ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T04:00:06.342184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.557454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:00:06.342289Z","caller":"traceutil/trace.go:171","msg":"trace[175043182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17957; }","duration":"193.729628ms","start":"2026-07-02T04:00:06.148532Z","end":"2026-07-02T04:00:06.342262Z","steps":["trace[175043182] 'agreement among raft nodes before linearized reading' (duration: 193.551064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:06.34218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.226945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:00:06.342531Z","caller":"traceutil/trace.go:171","msg":"trace[117320432] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17957; }","duration":"328.615613ms","start":"2026-07-02T04:00:06.013898Z","end":"2026-07-02T04:00:06.342514Z","steps":["trace[117320432] 'agreement among raft nodes before linearized reading' (duration: 328.158434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:00:06.342583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:00:06.013828Z","time spent":"328.733146ms","remote":"127.0.0.1:48470","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-07-02T04:02:53.518637Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T04:02:53.5264Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-02T04:02:53.526667Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-02T04:04:46.234955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17695} {"level":"info","ts":"2026-07-02T04:04:46.330147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17695,"took":"91.903735ms","hash":1684122717,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":34607104,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-02T04:04:46.330218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1684122717,"revision":17695,"compact-revision":15408} {"level":"warn","ts":"2026-07-02T04:05:30.374811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.303599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T04:05:30.374933Z","caller":"traceutil/trace.go:171","msg":"trace[2054688387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20494; }","duration":"117.746088ms","start":"2026-07-02T04:05:30.257158Z","end":"2026-07-02T04:05:30.374904Z","steps":["trace[2054688387] 'range keys from in-memory index tree' (duration: 117.082354ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:05:30.569655Z","caller":"traceutil/trace.go:171","msg":"trace[615159273] transaction","detail":"{read_only:false; response_revision:20495; number_of_response:1; }","duration":"189.360354ms","start":"2026-07-02T04:05:30.38027Z","end":"2026-07-02T04:05:30.569631Z","steps":["trace[615159273] 'process raft request' (duration: 189.044718ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:05:30.744064Z","caller":"traceutil/trace.go:171","msg":"trace[948425622] transaction","detail":"{read_only:false; response_revision:20496; number_of_response:1; }","duration":"169.348232ms","start":"2026-07-02T04:05:30.574675Z","end":"2026-07-02T04:05:30.744023Z","steps":["trace[948425622] 'process raft request' (duration: 125.878073ms)","trace[948425622] 'compare' (duration: 42.625213ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:05:30.744106Z","caller":"traceutil/trace.go:171","msg":"trace[334683976] transaction","detail":"{read_only:false; response_revision:20498; number_of_response:1; }","duration":"168.044188ms","start":"2026-07-02T04:05:30.576017Z","end":"2026-07-02T04:05:30.744061Z","steps":["trace[334683976] 'process raft request' (duration: 167.981547ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:05:30.744179Z","caller":"traceutil/trace.go:171","msg":"trace[1348006933] transaction","detail":"{read_only:false; response_revision:20497; number_of_response:1; }","duration":"169.22895ms","start":"2026-07-02T04:05:30.574855Z","end":"2026-07-02T04:05:30.744084Z","steps":["trace[1348006933] 'process raft request' (duration: 169.016777ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:05:31.285242Z","caller":"traceutil/trace.go:171","msg":"trace[760179371] linearizableReadLoop","detail":"{readStateIndex:21181; appliedIndex:21180; }","duration":"137.75325ms","start":"2026-07-02T04:05:31.147456Z","end":"2026-07-02T04:05:31.28521Z","steps":["trace[760179371] 'read index received' (duration: 137.540956ms)","trace[760179371] 'applied index is now lower than readState.Index' (duration: 210.884µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:05:31.285504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.386076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:05:31.285451Z","caller":"traceutil/trace.go:171","msg":"trace[887874521] transaction","detail":"{read_only:false; response_revision:20499; number_of_response:1; }","duration":"138.088696ms","start":"2026-07-02T04:05:31.147272Z","end":"2026-07-02T04:05:31.28536Z","steps":["trace[887874521] 'process raft request' (duration: 137.732339ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:05:31.285561Z","caller":"traceutil/trace.go:171","msg":"trace[280777705] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:20499; }","duration":"112.529849ms","start":"2026-07-02T04:05:31.173013Z","end":"2026-07-02T04:05:31.285543Z","steps":["trace[280777705] 'agreement among raft nodes before linearized reading' (duration: 112.401826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:05:31.285538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.010955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:05:31.285667Z","caller":"traceutil/trace.go:171","msg":"trace[1516485263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20499; }","duration":"138.218349ms","start":"2026-07-02T04:05:31.147411Z","end":"2026-07-02T04:05:31.285629Z","steps":["trace[1516485263] 'agreement among raft nodes before linearized reading' (duration: 137.946654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:05:31.434575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.71442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:05:31.434923Z","caller":"traceutil/trace.go:171","msg":"trace[983736124] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:20499; }","duration":"114.072588ms","start":"2026-07-02T04:05:31.320814Z","end":"2026-07-02T04:05:31.434886Z","steps":["trace[983736124] 'count revisions from in-memory index tree' (duration: 113.65302ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:05:31.682218Z","caller":"traceutil/trace.go:171","msg":"trace[1917997638] transaction","detail":"{read_only:false; response_revision:20500; number_of_response:1; }","duration":"127.903481ms","start":"2026-07-02T04:05:31.554273Z","end":"2026-07-02T04:05:31.682177Z","steps":["trace[1917997638] 'process raft request' (duration: 127.618475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:05:32.10547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.508655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:05:32.105573Z","caller":"traceutil/trace.go:171","msg":"trace[1385932150] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:20501; }","duration":"125.682159ms","start":"2026-07-02T04:05:31.979862Z","end":"2026-07-02T04:05:32.105544Z","steps":["trace[1385932150] 'count revisions from in-memory index tree' (duration: 125.383394ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:05:32.264891Z","caller":"traceutil/trace.go:171","msg":"trace[1017119563] transaction","detail":"{read_only:false; response_revision:20503; number_of_response:1; }","duration":"103.499345ms","start":"2026-07-02T04:05:32.161336Z","end":"2026-07-02T04:05:32.264836Z","steps":["trace[1017119563] 'process raft request' (duration: 103.21398ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:09:36.192582Z","caller":"traceutil/trace.go:171","msg":"trace[575408815] transaction","detail":"{read_only:false; response_revision:22962; number_of_response:1; }","duration":"111.339775ms","start":"2026-07-02T04:09:36.081205Z","end":"2026-07-02T04:09:36.192545Z","steps":["trace[575408815] 'process raft request' (duration: 111.080099ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:09:36.474633Z","caller":"traceutil/trace.go:171","msg":"trace[312046342] transaction","detail":"{read_only:false; response_revision:22965; number_of_response:1; }","duration":"142.775635ms","start":"2026-07-02T04:09:36.331821Z","end":"2026-07-02T04:09:36.474596Z","steps":["trace[312046342] 'process raft request' (duration: 140.512261ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:09:46.244786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20203} {"level":"info","ts":"2026-07-02T04:09:46.361688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20203,"took":"111.646351ms","hash":2367546475,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":42651648,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-02T04:09:46.36176Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2367546475,"revision":20203,"compact-revision":17695} {"level":"info","ts":"2026-07-02T04:11:04.862399Z","caller":"traceutil/trace.go:171","msg":"trace[1747779802] linearizableReadLoop","detail":"{readStateIndex:24735; appliedIndex:24733; }","duration":"111.599509ms","start":"2026-07-02T04:11:04.750768Z","end":"2026-07-02T04:11:04.862368Z","steps":["trace[1747779802] 'read index received' (duration: 53.47166ms)","trace[1747779802] 'applied index is now lower than readState.Index' (duration: 58.126449ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:11:04.862504Z","caller":"traceutil/trace.go:171","msg":"trace[90753995] transaction","detail":"{read_only:false; response_revision:23950; number_of_response:1; }","duration":"126.630447ms","start":"2026-07-02T04:11:04.73583Z","end":"2026-07-02T04:11:04.862461Z","steps":["trace[90753995] 'process raft request' (duration: 104.934553ms)","trace[90753995] 'compare' (duration: 21.47375ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:11:04.862796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.942895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:11:04.8629Z","caller":"traceutil/trace.go:171","msg":"trace[1239195065] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:23950; }","duration":"112.14845ms","start":"2026-07-02T04:11:04.750735Z","end":"2026-07-02T04:11:04.862883Z","steps":["trace[1239195065] 'agreement among raft nodes before linearized reading' (duration: 111.771422ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:11.631213Z","caller":"traceutil/trace.go:171","msg":"trace[1194448731] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"129.247557ms","start":"2026-07-02T04:13:11.501923Z","end":"2026-07-02T04:13:11.631171Z","steps":["trace[1194448731] 'process raft request' (duration: 128.90701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:12.137137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.327466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:12.137333Z","caller":"traceutil/trace.go:171","msg":"trace[1555811097] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"290.166937ms","start":"2026-07-02T04:13:11.847131Z","end":"2026-07-02T04:13:12.137298Z","steps":["trace[1555811097] 'process raft request' (duration: 103.564786ms)","trace[1555811097] 'compare' (duration: 185.589591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:12.62525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.758872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:12.62567Z","caller":"traceutil/trace.go:171","msg":"trace[1269930014] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"747.727188ms","start":"2026-07-02T04:13:11.877907Z","end":"2026-07-02T04:13:12.625634Z","steps":["trace[1269930014] 'process raft request' (duration: 373.456577ms)","trace[1269930014] 'compare' (duration: 372.530068ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:12.625837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:11.877884Z","time spent":"747.896931ms","remote":"127.0.0.1:56002","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-07-02T04:13:12.626161Z","caller":"traceutil/trace.go:171","msg":"trace[767433844] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"444.141124ms","start":"2026-07-02T04:13:12.181986Z","end":"2026-07-02T04:13:12.626128Z","steps":["trace[767433844] 'process raft request' (duration: 444.090463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:12.626347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:12.181947Z","time spent":"444.300558ms","remote":"127.0.0.1:56002","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-07-02T04:13:12.626495Z","caller":"traceutil/trace.go:171","msg":"trace[994204888] linearizableReadLoop","detail":"{readStateIndex:25965; appliedIndex:25963; }","duration":"747.122087ms","start":"2026-07-02T04:13:11.879362Z","end":"2026-07-02T04:13:12.626484Z","steps":["trace[994204888] 'read index received' (duration: 71.416883ms)","trace[994204888] 'applied index is now lower than readState.Index' (duration: 675.704204ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:12.626574Z","caller":"traceutil/trace.go:171","msg":"trace[327473522] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"496.996223ms","start":"2026-07-02T04:13:12.129568Z","end":"2026-07-02T04:13:12.626564Z","steps":["trace[327473522] 'process raft request' (duration: 496.434952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:12.62663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:12.129533Z","time spent":"497.066675ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:12.626756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.390192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:12.6268Z","caller":"traceutil/trace.go:171","msg":"trace[376859331] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25149; }","duration":"747.495455ms","start":"2026-07-02T04:13:11.879293Z","end":"2026-07-02T04:13:12.626789Z","steps":["trace[376859331] 'agreement among raft nodes before linearized reading' (duration: 747.420963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:12.626837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:11.879265Z","time spent":"747.559945ms","remote":"127.0.0.1:45812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-07-02T04:13:12.626461Z","caller":"traceutil/trace.go:171","msg":"trace[545469129] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"737.943252ms","start":"2026-07-02T04:13:11.888476Z","end":"2026-07-02T04:13:12.626419Z","steps":["trace[545469129] 'process raft request' (duration: 737.085545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:12.627069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:11.888456Z","time spent":"738.578194ms","remote":"127.0.0.1:56002","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-07-02T04:13:13.126198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.662024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:13.1264Z","caller":"traceutil/trace.go:171","msg":"trace[1608320291] linearizableReadLoop","detail":"{readStateIndex:25969; appliedIndex:25968; }","duration":"499.844568ms","start":"2026-07-02T04:13:12.626528Z","end":"2026-07-02T04:13:13.126373Z","steps":["trace[1608320291] 'read index received' (duration: 162.919108ms)","trace[1608320291] 'applied index is now lower than readState.Index' (duration: 336.92338ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:13.126529Z","caller":"traceutil/trace.go:171","msg":"trace[1492143435] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"570.094888ms","start":"2026-07-02T04:13:12.556387Z","end":"2026-07-02T04:13:13.126482Z","steps":["trace[1492143435] 'process raft request' (duration: 233.054626ms)","trace[1492143435] 'compare' (duration: 336.295227ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:13.126628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144655003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-07-02T04:13:13.126613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.086803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:13.126701Z","caller":"traceutil/trace.go:171","msg":"trace[1207189072] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25150; }","duration":"1.144773255s","start":"2026-07-02T04:13:11.98191Z","end":"2026-07-02T04:13:13.126683Z","steps":["trace[1207189072] 'agreement among raft nodes before linearized reading' (duration: 1.144532801s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.126717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:12.556356Z","time spent":"570.257981ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:13.126728Z","caller":"traceutil/trace.go:171","msg":"trace[1781507941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25150; }","duration":"979.254847ms","start":"2026-07-02T04:13:12.147441Z","end":"2026-07-02T04:13:13.126696Z","steps":["trace[1781507941] 'agreement among raft nodes before linearized reading' (duration: 979.020282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.126676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.831576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-02T04:13:13.12678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:12.14742Z","time spent":"979.351318ms","remote":"127.0.0.1:55740","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-07-02T04:13:13.126775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:11.981875Z","time spent":"1.144875297s","remote":"127.0.0.1:55914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"info","ts":"2026-07-02T04:13:13.126804Z","caller":"traceutil/trace.go:171","msg":"trace[1054201218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25150; }","duration":"938.028009ms","start":"2026-07-02T04:13:12.188755Z","end":"2026-07-02T04:13:13.126783Z","steps":["trace[1054201218] 'agreement among raft nodes before linearized reading' (duration: 937.696063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.126845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:12.188727Z","time spent":"938.110461ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T04:13:13.334789Z","caller":"traceutil/trace.go:171","msg":"trace[234541529] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"203.372491ms","start":"2026-07-02T04:13:13.131385Z","end":"2026-07-02T04:13:13.334758Z","steps":["trace[234541529] 'process raft request' (duration: 203.3244ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:13.335136Z","caller":"traceutil/trace.go:171","msg":"trace[118568325] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"376.150678ms","start":"2026-07-02T04:13:12.958975Z","end":"2026-07-02T04:13:13.335126Z","steps":["trace[118568325] 'process raft request' (duration: 319.807222ms)","trace[118568325] 'compare' (duration: 55.779565ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:13.335245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:12.958948Z","time spent":"376.205819ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:13.335356Z","caller":"traceutil/trace.go:171","msg":"trace[923084451] linearizableReadLoop","detail":"{readStateIndex:25970; appliedIndex:25969; }","duration":"207.526049ms","start":"2026-07-02T04:13:13.127821Z","end":"2026-07-02T04:13:13.335347Z","steps":["trace[923084451] 'read index received' (duration: 150.984861ms)","trace[923084451] 'applied index is now lower than readState.Index' (duration: 56.540208ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:13.335527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.704974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:13.335554Z","caller":"traceutil/trace.go:171","msg":"trace[424472847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25152; }","duration":"207.771914ms","start":"2026-07-02T04:13:13.127774Z","end":"2026-07-02T04:13:13.335546Z","steps":["trace[424472847] 'agreement among raft nodes before linearized reading' (duration: 207.719653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.335843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.060492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b9c04913-a3ca-4911-a1a3-cbacba99084f\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-02T04:13:13.335934Z","caller":"traceutil/trace.go:171","msg":"trace[238717611] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b9c04913-a3ca-4911-a1a3-cbacba99084f; range_end:; response_count:1; response_revision:25152; }","duration":"206.173774ms","start":"2026-07-02T04:13:13.129728Z","end":"2026-07-02T04:13:13.335902Z","steps":["trace[238717611] 'agreement among raft nodes before linearized reading' (duration: 205.916109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.336219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.995808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T04:13:13.336257Z","caller":"traceutil/trace.go:171","msg":"trace[1358332026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25152; }","duration":"166.07396ms","start":"2026-07-02T04:13:13.170173Z","end":"2026-07-02T04:13:13.336246Z","steps":["trace[1358332026] 'agreement among raft nodes before linearized reading' (duration: 165.920806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.567997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.104881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:13.568474Z","caller":"traceutil/trace.go:171","msg":"trace[123257800] linearizableReadLoop","detail":"{readStateIndex:25973; appliedIndex:25971; }","duration":"222.939224ms","start":"2026-07-02T04:13:13.345514Z","end":"2026-07-02T04:13:13.568453Z","steps":["trace[123257800] 'read index received' (duration: 90.254483ms)","trace[123257800] 'applied index is now lower than readState.Index' (duration: 132.683691ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:13.568458Z","caller":"traceutil/trace.go:171","msg":"trace[1462808434] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"226.91645ms","start":"2026-07-02T04:13:13.341499Z","end":"2026-07-02T04:13:13.568415Z","steps":["trace[1462808434] 'process raft request' (duration: 94.25736ms)","trace[1462808434] 'compare' (duration: 131.760434ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:13.568579Z","caller":"traceutil/trace.go:171","msg":"trace[1905011948] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"223.049006ms","start":"2026-07-02T04:13:13.345487Z","end":"2026-07-02T04:13:13.568536Z","steps":["trace[1905011948] 'process raft request' (duration: 222.817311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.568829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.259351ms","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-07-02T04:13:13.568831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.601128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:13.568882Z","caller":"traceutil/trace.go:171","msg":"trace[121938213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25155; }","duration":"223.349893ms","start":"2026-07-02T04:13:13.345519Z","end":"2026-07-02T04:13:13.568869Z","steps":["trace[121938213] 'agreement among raft nodes before linearized reading' (duration: 223.106547ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:13.568609Z","caller":"traceutil/trace.go:171","msg":"trace[330505843] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"199.199041ms","start":"2026-07-02T04:13:13.369353Z","end":"2026-07-02T04:13:13.568552Z","steps":["trace[330505843] 'process raft request' (duration: 199.058988ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:13.568934Z","caller":"traceutil/trace.go:171","msg":"trace[344556863] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25155; }","duration":"145.780811ms","start":"2026-07-02T04:13:13.423126Z","end":"2026-07-02T04:13:13.568906Z","steps":["trace[344556863] 'agreement among raft nodes before linearized reading' (duration: 145.523477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:13.56876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.2204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:13.56911Z","caller":"traceutil/trace.go:171","msg":"trace[636599622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25155; }","duration":"223.591457ms","start":"2026-07-02T04:13:13.345494Z","end":"2026-07-02T04:13:13.569086Z","steps":["trace[636599622] 'agreement among raft nodes before linearized reading' (duration: 223.098367ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:13.902533Z","caller":"traceutil/trace.go:171","msg":"trace[610634084] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"262.00396ms","start":"2026-07-02T04:13:13.640484Z","end":"2026-07-02T04:13:13.902487Z","steps":["trace[610634084] 'process raft request' (duration: 261.771785ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:14.102084Z","caller":"traceutil/trace.go:171","msg":"trace[1632813507] linearizableReadLoop","detail":"{readStateIndex:25978; appliedIndex:25975; }","duration":"200.664368ms","start":"2026-07-02T04:13:13.901249Z","end":"2026-07-02T04:13:14.101913Z","steps":["trace[1632813507] 'read index received' (duration: 1.04104ms)","trace[1632813507] 'applied index is now lower than readState.Index' (duration: 199.622468ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:14.102237Z","caller":"traceutil/trace.go:171","msg":"trace[1695201117] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"444.226858ms","start":"2026-07-02T04:13:13.657967Z","end":"2026-07-02T04:13:14.102193Z","steps":["trace[1695201117] 'process raft request' (duration: 349.892687ms)","trace[1695201117] 'compare' (duration: 93.769449ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:14.102274Z","caller":"traceutil/trace.go:171","msg":"trace[1113175112] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"318.660801ms","start":"2026-07-02T04:13:13.783555Z","end":"2026-07-02T04:13:14.102216Z","steps":["trace[1113175112] 'process raft request' (duration: 318.300053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:14.102445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:13.657929Z","time spent":"444.39132ms","remote":"127.0.0.1:56002","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-07-02T04:13:14.102499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:13.783526Z","time spent":"318.844805ms","remote":"127.0.0.1:56002","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-07-02T04:13:14.102282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.996735ms","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-07-02T04:13:14.102655Z","caller":"traceutil/trace.go:171","msg":"trace[1872234591] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25159; }","duration":"201.417683ms","start":"2026-07-02T04:13:13.901199Z","end":"2026-07-02T04:13:14.102616Z","steps":["trace[1872234591] 'agreement among raft nodes before linearized reading' (duration: 200.994646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:14.102667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.927087ms","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-07-02T04:13:14.102754Z","caller":"traceutil/trace.go:171","msg":"trace[1183045861] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25159; }","duration":"166.049249ms","start":"2026-07-02T04:13:13.936666Z","end":"2026-07-02T04:13:14.102716Z","steps":["trace[1183045861] 'agreement among raft nodes before linearized reading' (duration: 165.577511ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:19.483076Z","caller":"traceutil/trace.go:171","msg":"trace[601175534] linearizableReadLoop","detail":"{readStateIndex:26014; appliedIndex:26013; }","duration":"116.039334ms","start":"2026-07-02T04:13:19.367005Z","end":"2026-07-02T04:13:19.483044Z","steps":["trace[601175534] 'read index received' (duration: 115.80269ms)","trace[601175534] 'applied index is now lower than readState.Index' (duration: 235.604µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:19.483134Z","caller":"traceutil/trace.go:171","msg":"trace[191813651] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"119.759056ms","start":"2026-07-02T04:13:19.36333Z","end":"2026-07-02T04:13:19.483089Z","steps":["trace[191813651] 'process raft request' (duration: 119.46152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:19.483396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.36612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T04:13:19.483476Z","caller":"traceutil/trace.go:171","msg":"trace[1225493097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25194; }","duration":"116.512833ms","start":"2026-07-02T04:13:19.366943Z","end":"2026-07-02T04:13:19.483456Z","steps":["trace[1225493097] 'agreement among raft nodes before linearized reading' (duration: 116.257688ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:19.927428Z","caller":"traceutil/trace.go:171","msg":"trace[136442710] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"235.626766ms","start":"2026-07-02T04:13:19.691774Z","end":"2026-07-02T04:13:19.927401Z","steps":["trace[136442710] 'process raft request' (duration: 235.586046ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:19.927754Z","caller":"traceutil/trace.go:171","msg":"trace[1638300647] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"236.262848ms","start":"2026-07-02T04:13:19.691482Z","end":"2026-07-02T04:13:19.927745Z","steps":["trace[1638300647] 'process raft request' (duration: 235.742418ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:20.065264Z","caller":"traceutil/trace.go:171","msg":"trace[1240014948] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"128.313258ms","start":"2026-07-02T04:13:19.936896Z","end":"2026-07-02T04:13:20.065209Z","steps":["trace[1240014948] 'process raft request' (duration: 126.354451ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:20.562986Z","caller":"traceutil/trace.go:171","msg":"trace[494845629] linearizableReadLoop","detail":"{readStateIndex:26026; appliedIndex:26025; }","duration":"198.817214ms","start":"2026-07-02T04:13:20.364132Z","end":"2026-07-02T04:13:20.562949Z","steps":["trace[494845629] 'read index received' (duration: 140.244296ms)","trace[494845629] 'applied index is now lower than readState.Index' (duration: 58.571028ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:20.563185Z","caller":"traceutil/trace.go:171","msg":"trace[1132391938] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"321.851611ms","start":"2026-07-02T04:13:20.241271Z","end":"2026-07-02T04:13:20.563122Z","steps":["trace[1132391938] 'process raft request' (duration: 263.137661ms)","trace[1132391938] 'compare' (duration: 58.359433ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:20.563337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.19015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T04:13:20.563386Z","caller":"traceutil/trace.go:171","msg":"trace[448534962] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25205; }","duration":"199.307183ms","start":"2026-07-02T04:13:20.364066Z","end":"2026-07-02T04:13:20.563373Z","steps":["trace[448534962] 'agreement among raft nodes before linearized reading' (duration: 199.058738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:20.563379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:20.241226Z","time spent":"322.049075ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:21.602178Z","caller":"traceutil/trace.go:171","msg":"trace[1733900905] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"112.798511ms","start":"2026-07-02T04:13:21.48933Z","end":"2026-07-02T04:13:21.602128Z","steps":["trace[1733900905] 'process raft request' (duration: 112.508536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:21.896194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.166941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:21.896308Z","caller":"traceutil/trace.go:171","msg":"trace[1320999254] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26030; }","duration":"257.411992ms","start":"2026-07-02T04:13:21.638873Z","end":"2026-07-02T04:13:21.896285Z","steps":["trace[1320999254] 'read index received' (duration: 157.14232ms)","trace[1320999254] 'applied index is now lower than readState.Index' (duration: 100.268572ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:21.896422Z","caller":"traceutil/trace.go:171","msg":"trace[2146699251] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"289.988504ms","start":"2026-07-02T04:13:21.606419Z","end":"2026-07-02T04:13:21.896407Z","steps":["trace[2146699251] 'process raft request' (duration: 189.502867ms)","trace[2146699251] 'compare' (duration: 99.993968ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:21.896675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.79804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:21.896719Z","caller":"traceutil/trace.go:171","msg":"trace[1091805646] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25210; }","duration":"257.893312ms","start":"2026-07-02T04:13:21.638813Z","end":"2026-07-02T04:13:21.896706Z","steps":["trace[1091805646] 'agreement among raft nodes before linearized reading' (duration: 257.809901ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:21.899304Z","caller":"traceutil/trace.go:171","msg":"trace[684195908] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"206.449429ms","start":"2026-07-02T04:13:21.692824Z","end":"2026-07-02T04:13:21.899273Z","steps":["trace[684195908] 'process raft request' (duration: 206.238235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:21.899411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.191802ms","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-07-02T04:13:21.899519Z","caller":"traceutil/trace.go:171","msg":"trace[247609283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25211; }","duration":"143.304895ms","start":"2026-07-02T04:13:21.756183Z","end":"2026-07-02T04:13:21.899488Z","steps":["trace[247609283] 'agreement among raft nodes before linearized reading' (duration: 143.039139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:22.200504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.384619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:22.200839Z","caller":"traceutil/trace.go:171","msg":"trace[1321811228] linearizableReadLoop","detail":"{readStateIndex:26035; appliedIndex:26033; }","duration":"170.109187ms","start":"2026-07-02T04:13:22.030707Z","end":"2026-07-02T04:13:22.200816Z","steps":["trace[1321811228] 'read index received' (duration: 18.318239ms)","trace[1321811228] 'applied index is now lower than readState.Index' (duration: 151.789968ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:22.201021Z","caller":"traceutil/trace.go:171","msg":"trace[366122810] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"264.867354ms","start":"2026-07-02T04:13:21.93611Z","end":"2026-07-02T04:13:22.200977Z","steps":["trace[366122810] 'process raft request' (duration: 264.6474ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:22.201054Z","caller":"traceutil/trace.go:171","msg":"trace[1030478543] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"265.322553ms","start":"2026-07-02T04:13:21.935651Z","end":"2026-07-02T04:13:22.200973Z","steps":["trace[1030478543] 'process raft request' (duration: 113.363622ms)","trace[1030478543] 'compare' (duration: 151.024013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:22.201096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.352531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:22.20127Z","caller":"traceutil/trace.go:171","msg":"trace[2003342582] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25214; }","duration":"170.585905ms","start":"2026-07-02T04:13:22.030647Z","end":"2026-07-02T04:13:22.201233Z","steps":["trace[2003342582] 'agreement among raft nodes before linearized reading' (duration: 170.323101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:22.201346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.531151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T04:13:22.201498Z","caller":"traceutil/trace.go:171","msg":"trace[906019594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25214; }","duration":"131.727335ms","start":"2026-07-02T04:13:22.069733Z","end":"2026-07-02T04:13:22.20146Z","steps":["trace[906019594] 'agreement among raft nodes before linearized reading' (duration: 131.318367ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:22.45837Z","caller":"traceutil/trace.go:171","msg":"trace[1997037006] linearizableReadLoop","detail":"{readStateIndex:26038; appliedIndex:26035; }","duration":"252.797315ms","start":"2026-07-02T04:13:22.205539Z","end":"2026-07-02T04:13:22.458336Z","steps":["trace[1997037006] 'read index received' (duration: 89.680772ms)","trace[1997037006] 'applied index is now lower than readState.Index' (duration: 163.115443ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:22.458514Z","caller":"traceutil/trace.go:171","msg":"trace[62175169] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"253.216913ms","start":"2026-07-02T04:13:22.20528Z","end":"2026-07-02T04:13:22.458497Z","steps":["trace[62175169] 'process raft request' (duration: 224.396382ms)","trace[62175169] 'compare' (duration: 28.486385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:22.458823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.267314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:13:22.45885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.759059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:13:22.458906Z","caller":"traceutil/trace.go:171","msg":"trace[1955805970] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25217; }","duration":"253.408017ms","start":"2026-07-02T04:13:22.205476Z","end":"2026-07-02T04:13:22.458884Z","steps":["trace[1955805970] 'agreement among raft nodes before linearized reading' (duration: 253.263065ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:22.458963Z","caller":"traceutil/trace.go:171","msg":"trace[564725982] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25217; }","duration":"138.937061ms","start":"2026-07-02T04:13:22.319995Z","end":"2026-07-02T04:13:22.458932Z","steps":["trace[564725982] 'agreement among raft nodes before linearized reading' (duration: 138.740097ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:22.717863Z","caller":"traceutil/trace.go:171","msg":"trace[1486494905] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"147.350242ms","start":"2026-07-02T04:13:22.570465Z","end":"2026-07-02T04:13:22.717815Z","steps":["trace[1486494905] 'process raft request' (duration: 135.171079ms)","trace[1486494905] 'compare' (duration: 11.529841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:23.004071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.714153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:23.004372Z","caller":"traceutil/trace.go:171","msg":"trace[1193322045] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"256.483105ms","start":"2026-07-02T04:13:22.747864Z","end":"2026-07-02T04:13:23.004347Z","steps":["trace[1193322045] 'process raft request' (duration: 145.407304ms)","trace[1193322045] 'compare' (duration: 110.607851ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:23.199311Z","caller":"traceutil/trace.go:171","msg":"trace[884432915] linearizableReadLoop","detail":"{readStateIndex:26042; appliedIndex:26039; }","duration":"377.944033ms","start":"2026-07-02T04:13:22.821332Z","end":"2026-07-02T04:13:23.199276Z","steps":["trace[884432915] 'read index received' (duration: 71.968593ms)","trace[884432915] 'applied index is now lower than readState.Index' (duration: 305.97413ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:23.19971Z","caller":"traceutil/trace.go:171","msg":"trace[6215823] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"451.796022ms","start":"2026-07-02T04:13:22.74789Z","end":"2026-07-02T04:13:23.199686Z","steps":["trace[6215823] 'process raft request' (duration: 420.594097ms)","trace[6215823] 'compare' (duration: 30.528953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:23.199914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:22.747852Z","time spent":"451.914155ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:23.199969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.579864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:13:23.199983Z","caller":"traceutil/trace.go:171","msg":"trace[1104188493] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"451.920564ms","start":"2026-07-02T04:13:22.748014Z","end":"2026-07-02T04:13:23.199935Z","steps":["trace[1104188493] 'process raft request' (duration: 451.18799ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:23.200069Z","caller":"traceutil/trace.go:171","msg":"trace[1515845914] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25221; }","duration":"378.765268ms","start":"2026-07-02T04:13:22.82128Z","end":"2026-07-02T04:13:23.200045Z","steps":["trace[1515845914] 'agreement among raft nodes before linearized reading' (duration: 378.555134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:23.200122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:22.821258Z","time spent":"378.853459ms","remote":"127.0.0.1:55920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:13:23.200213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:22.747974Z","time spent":"452.107167ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:23.222479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.420214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-07-02T04:13:23.222579Z","caller":"traceutil/trace.go:171","msg":"trace[846493127] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25222; }","duration":"211.887224ms","start":"2026-07-02T04:13:23.010665Z","end":"2026-07-02T04:13:23.222553Z","steps":["trace[846493127] 'agreement among raft nodes before linearized reading' (duration: 211.289102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:23.222658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.616316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:23.222735Z","caller":"traceutil/trace.go:171","msg":"trace[2050745382] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25222; }","duration":"172.749568ms","start":"2026-07-02T04:13:23.049965Z","end":"2026-07-02T04:13:23.222715Z","steps":["trace[2050745382] 'agreement among raft nodes before linearized reading' (duration: 172.620046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:23.224629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.823416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:23.22515Z","caller":"traceutil/trace.go:171","msg":"trace[638730415] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25222; }","duration":"145.417736ms","start":"2026-07-02T04:13:23.079707Z","end":"2026-07-02T04:13:23.225124Z","steps":["trace[638730415] 'agreement among raft nodes before linearized reading' (duration: 142.853248ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:24.077713Z","caller":"traceutil/trace.go:171","msg":"trace[339623788] linearizableReadLoop","detail":"{readStateIndex:26046; appliedIndex:26044; }","duration":"159.687687ms","start":"2026-07-02T04:13:23.917991Z","end":"2026-07-02T04:13:24.077678Z","steps":["trace[339623788] 'read index received' (duration: 30.016783ms)","trace[339623788] 'applied index is now lower than readState.Index' (duration: 129.669694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:24.077905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.891811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-02T04:13:24.077948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.305118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:24.077976Z","caller":"traceutil/trace.go:171","msg":"trace[1087581709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25225; }","duration":"159.999723ms","start":"2026-07-02T04:13:23.917951Z","end":"2026-07-02T04:13:24.07795Z","steps":["trace[1087581709] 'agreement among raft nodes before linearized reading' (duration: 159.82866ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:24.077991Z","caller":"traceutil/trace.go:171","msg":"trace[875423699] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25225; }","duration":"117.421121ms","start":"2026-07-02T04:13:23.960555Z","end":"2026-07-02T04:13:24.077976Z","steps":["trace[875423699] 'agreement among raft nodes before linearized reading' (duration: 117.310008ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:24.078115Z","caller":"traceutil/trace.go:171","msg":"trace[1719402670] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"171.134216ms","start":"2026-07-02T04:13:23.906937Z","end":"2026-07-02T04:13:24.078071Z","steps":["trace[1719402670] 'process raft request' (duration: 162.388369ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:24.372787Z","caller":"traceutil/trace.go:171","msg":"trace[1254425355] linearizableReadLoop","detail":"{readStateIndex:26048; appliedIndex:26046; }","duration":"227.024542ms","start":"2026-07-02T04:13:24.145731Z","end":"2026-07-02T04:13:24.372756Z","steps":["trace[1254425355] 'read index received' (duration: 191.393403ms)","trace[1254425355] 'applied index is now lower than readState.Index' (duration: 35.629859ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:24.372826Z","caller":"traceutil/trace.go:171","msg":"trace[259824392] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"283.936769ms","start":"2026-07-02T04:13:24.088839Z","end":"2026-07-02T04:13:24.372776Z","steps":["trace[259824392] 'process raft request' (duration: 283.781296ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:24.372911Z","caller":"traceutil/trace.go:171","msg":"trace[541989316] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"289.502455ms","start":"2026-07-02T04:13:24.083373Z","end":"2026-07-02T04:13:24.372876Z","steps":["trace[541989316] 'process raft request' (duration: 253.65831ms)","trace[541989316] 'compare' (duration: 35.422347ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:24.373182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.43621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:24.373232Z","caller":"traceutil/trace.go:171","msg":"trace[30953454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25229; }","duration":"227.556312ms","start":"2026-07-02T04:13:24.145663Z","end":"2026-07-02T04:13:24.373219Z","steps":["trace[30953454] 'agreement among raft nodes before linearized reading' (duration: 227.45735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:24.373249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.551296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T04:13:24.373515Z","caller":"traceutil/trace.go:171","msg":"trace[492837805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25229; }","duration":"167.880733ms","start":"2026-07-02T04:13:24.20562Z","end":"2026-07-02T04:13:24.373501Z","steps":["trace[492837805] 'agreement among raft nodes before linearized reading' (duration: 167.532616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:24.37325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.038156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T04:13:24.373657Z","caller":"traceutil/trace.go:171","msg":"trace[1002437381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25229; }","duration":"168.520467ms","start":"2026-07-02T04:13:24.205113Z","end":"2026-07-02T04:13:24.373634Z","steps":["trace[1002437381] 'agreement among raft nodes before linearized reading' (duration: 167.963495ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:24.536665Z","caller":"traceutil/trace.go:171","msg":"trace[444481520] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"157.183059ms","start":"2026-07-02T04:13:24.379436Z","end":"2026-07-02T04:13:24.536619Z","steps":["trace[444481520] 'process raft request' (duration: 143.512958ms)","trace[444481520] 'compare' (duration: 13.483398ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:24.82501Z","caller":"traceutil/trace.go:171","msg":"trace[224684683] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"282.706915ms","start":"2026-07-02T04:13:24.542269Z","end":"2026-07-02T04:13:24.824976Z","steps":["trace[224684683] 'process raft request' (duration: 282.502782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.223135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.729908ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T04:13:25.22344Z","caller":"traceutil/trace.go:171","msg":"trace[1682876483] linearizableReadLoop","detail":"{readStateIndex:26055; appliedIndex:26053; }","duration":"250.352727ms","start":"2026-07-02T04:13:24.973067Z","end":"2026-07-02T04:13:25.22342Z","steps":["trace[1682876483] 'read index received' (duration: 38.91µs)","trace[1682876483] 'applied index is now lower than readState.Index' (duration: 250.312347ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:25.223531Z","caller":"traceutil/trace.go:171","msg":"trace[467854509] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"499.954641ms","start":"2026-07-02T04:13:24.723568Z","end":"2026-07-02T04:13:25.223523Z","steps":["trace[467854509] 'process raft request' (duration: 499.673245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.223646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:24.723535Z","time spent":"500.021461ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:25.380293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.774891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-07-02T04:13:25.3803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.20092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-07-02T04:13:25.380441Z","caller":"traceutil/trace.go:171","msg":"trace[394133457] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25233; }","duration":"407.383814ms","start":"2026-07-02T04:13:24.973006Z","end":"2026-07-02T04:13:25.38039Z","steps":["trace[394133457] 'agreement among raft nodes before linearized reading' (duration: 250.558932ms)","trace[394133457] 'range keys from in-memory index tree' (duration: 156.533126ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:25.380479Z","caller":"traceutil/trace.go:171","msg":"trace[1004430520] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"175.328685ms","start":"2026-07-02T04:13:25.205139Z","end":"2026-07-02T04:13:25.380468Z","steps":["trace[1004430520] 'process raft request' (duration: 175.258004ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:25.380479Z","caller":"traceutil/trace.go:171","msg":"trace[1861047285] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"369.893408ms","start":"2026-07-02T04:13:25.010551Z","end":"2026-07-02T04:13:25.380445Z","steps":["trace[1861047285] 'process raft request' (duration: 212.858892ms)","trace[1861047285] 'compare' (duration: 156.585927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:25.380497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:24.972985Z","time spent":"407.500706ms","remote":"127.0.0.1:55914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-02T04:13:25.380574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:25.010518Z","time spent":"370.003511ms","remote":"127.0.0.1:56002","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-07-02T04:13:25.380717Z","caller":"traceutil/trace.go:171","msg":"trace[316142499] linearizableReadLoop","detail":"{readStateIndex:26058; appliedIndex:26055; }","duration":"157.23967ms","start":"2026-07-02T04:13:25.223467Z","end":"2026-07-02T04:13:25.380706Z","steps":["trace[316142499] 'read index received' (duration: 9.122033ms)","trace[316142499] 'applied index is now lower than readState.Index' (duration: 148.117057ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:25.380789Z","caller":"traceutil/trace.go:171","msg":"trace[1797380200] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"174.974438ms","start":"2026-07-02T04:13:25.205807Z","end":"2026-07-02T04:13:25.380782Z","steps":["trace[1797380200] 'process raft request' (duration: 174.629132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.380859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.068147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:25.380876Z","caller":"traceutil/trace.go:171","msg":"trace[2145233422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25236; }","duration":"233.13959ms","start":"2026-07-02T04:13:25.14773Z","end":"2026-07-02T04:13:25.38087Z","steps":["trace[2145233422] 'agreement among raft nodes before linearized reading' (duration: 233.103319ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:25.698926Z","caller":"traceutil/trace.go:171","msg":"trace[677566856] linearizableReadLoop","detail":"{readStateIndex:26059; appliedIndex:26058; }","duration":"318.13524ms","start":"2026-07-02T04:13:25.380754Z","end":"2026-07-02T04:13:25.69889Z","steps":["trace[677566856] 'read index received' (duration: 298.7585ms)","trace[677566856] 'applied index is now lower than readState.Index' (duration: 19.37542ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:25.699272Z","caller":"traceutil/trace.go:171","msg":"trace[1212367395] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"450.15605ms","start":"2026-07-02T04:13:25.249094Z","end":"2026-07-02T04:13:25.69925Z","steps":["trace[1212367395] 'process raft request' (duration: 430.393463ms)","trace[1212367395] 'compare' (duration: 19.043524ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:25.6993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.44521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T04:13:25.699365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.960877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-07-02T04:13:25.699403Z","caller":"traceutil/trace.go:171","msg":"trace[289123553] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25237; }","duration":"445.593162ms","start":"2026-07-02T04:13:25.253782Z","end":"2026-07-02T04:13:25.699375Z","steps":["trace[289123553] 'agreement among raft nodes before linearized reading' (duration: 445.414409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.699438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:25.249064Z","time spent":"450.251891ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:13:25.699459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:25.253773Z","time spent":"445.674284ms","remote":"127.0.0.1:45762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-07-02T04:13:25.699487Z","caller":"traceutil/trace.go:171","msg":"trace[852474291] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25237; }","duration":"405.099151ms","start":"2026-07-02T04:13:25.294337Z","end":"2026-07-02T04:13:25.699436Z","steps":["trace[852474291] 'agreement among raft nodes before linearized reading' (duration: 404.853826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.699562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:25.294313Z","time spent":"405.232133ms","remote":"127.0.0.1:39748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7721,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-07-02T04:13:25.825191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.159521ms","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":"warn","ts":"2026-07-02T04:13:25.825234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.983815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:25.825262Z","caller":"traceutil/trace.go:171","msg":"trace[87003674] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"184.864578ms","start":"2026-07-02T04:13:25.64036Z","end":"2026-07-02T04:13:25.825225Z","steps":["trace[87003674] 'process raft request' (duration: 181.236239ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:25.825298Z","caller":"traceutil/trace.go:171","msg":"trace[1466622169] 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:25238; }","duration":"263.334435ms","start":"2026-07-02T04:13:25.561937Z","end":"2026-07-02T04:13:25.825272Z","steps":["trace[1466622169] 'agreement among raft nodes before linearized reading' (duration: 263.146141ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:25.825331Z","caller":"traceutil/trace.go:171","msg":"trace[252955377] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25238; }","duration":"375.144697ms","start":"2026-07-02T04:13:25.450165Z","end":"2026-07-02T04:13:25.82531Z","steps":["trace[252955377] 'agreement among raft nodes before linearized reading' (duration: 374.941324ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:25.825271Z","caller":"traceutil/trace.go:171","msg":"trace[1664114600] linearizableReadLoop","detail":"{readStateIndex:26060; appliedIndex:26059; }","duration":"126.063786ms","start":"2026-07-02T04:13:25.699004Z","end":"2026-07-02T04:13:25.825067Z","steps":["trace[1664114600] 'read index received' (duration: 122.558609ms)","trace[1664114600] 'applied index is now lower than readState.Index' (duration: 3.502017ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:25.825437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:25.450152Z","time spent":"375.266941ms","remote":"127.0.0.1:48544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-07-02T04:13:25.825502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.938693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:25.825605Z","caller":"traceutil/trace.go:171","msg":"trace[1653760891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25238; }","duration":"443.097426ms","start":"2026-07-02T04:13:25.382493Z","end":"2026-07-02T04:13:25.82559Z","steps":["trace[1653760891] 'agreement among raft nodes before linearized reading' (duration: 442.960473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.825659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:25.382464Z","time spent":"443.181658ms","remote":"127.0.0.1:55748","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-07-02T04:13:25.825678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.548275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-02T04:13:25.826755Z","caller":"traceutil/trace.go:171","msg":"trace[1602273806] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25238; }","duration":"181.678387ms","start":"2026-07-02T04:13:25.64505Z","end":"2026-07-02T04:13:25.826728Z","steps":["trace[1602273806] 'agreement among raft nodes before linearized reading' (duration: 180.412243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.826062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.545527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d8ad0b48-06b0-44f6-b677-c2595d73d4a5\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-07-02T04:13:25.826956Z","caller":"traceutil/trace.go:171","msg":"trace[1681996589] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d8ad0b48-06b0-44f6-b677-c2595d73d4a5; range_end:; response_count:1; response_revision:25238; }","duration":"441.517156ms","start":"2026-07-02T04:13:25.385425Z","end":"2026-07-02T04:13:25.826942Z","steps":["trace[1681996589] 'agreement among raft nodes before linearized reading' (duration: 439.781753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:25.828019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:25.385374Z","time spent":"442.631867ms","remote":"127.0.0.1:55908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1459,"request content":"key:\"/registry/persistentvolumes/pvc-d8ad0b48-06b0-44f6-b677-c2595d73d4a5\" "} {"level":"info","ts":"2026-07-02T04:13:35.391368Z","caller":"traceutil/trace.go:171","msg":"trace[934163656] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"122.233993ms","start":"2026-07-02T04:13:35.269104Z","end":"2026-07-02T04:13:35.391338Z","steps":["trace[934163656] 'process raft request' (duration: 109.578362ms)","trace[934163656] 'compare' (duration: 12.002998ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:35.579783Z","caller":"traceutil/trace.go:171","msg":"trace[1633587060] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"167.024157ms","start":"2026-07-02T04:13:35.412689Z","end":"2026-07-02T04:13:35.579713Z","steps":["trace[1633587060] 'process raft request' (duration: 124.862123ms)","trace[1633587060] 'compare' (duration: 41.790877ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:35.723896Z","caller":"traceutil/trace.go:171","msg":"trace[191075722] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"301.92342ms","start":"2026-07-02T04:13:35.421923Z","end":"2026-07-02T04:13:35.723846Z","steps":["trace[191075722] 'process raft request' (duration: 284.485098ms)","trace[191075722] 'compare' (duration: 17.178938ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:35.723945Z","caller":"traceutil/trace.go:171","msg":"trace[1507094878] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"301.531044ms","start":"2026-07-02T04:13:35.422371Z","end":"2026-07-02T04:13:35.723903Z","steps":["trace[1507094878] 'process raft request' (duration: 301.388081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:35.724146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:35.421888Z","time spent":"302.131676ms","remote":"127.0.0.1:56002","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-07-02T04:13:35.72416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:35.422347Z","time spent":"301.706586ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:13:36.152964Z","caller":"traceutil/trace.go:171","msg":"trace[1336643866] linearizableReadLoop","detail":"{readStateIndex:26193; appliedIndex:26192; }","duration":"199.379095ms","start":"2026-07-02T04:13:35.953555Z","end":"2026-07-02T04:13:36.152934Z","steps":["trace[1336643866] 'read index received' (duration: 199.19174ms)","trace[1336643866] 'applied index is now lower than readState.Index' (duration: 186.135µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:36.153085Z","caller":"traceutil/trace.go:171","msg":"trace[145685782] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"210.505918ms","start":"2026-07-02T04:13:35.94253Z","end":"2026-07-02T04:13:36.153036Z","steps":["trace[145685782] 'process raft request' (duration: 210.212131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.153223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.64322ms","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-07-02T04:13:36.153294Z","caller":"traceutil/trace.go:171","msg":"trace[1649955199] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25369; }","duration":"199.751092ms","start":"2026-07-02T04:13:35.953526Z","end":"2026-07-02T04:13:36.153277Z","steps":["trace[1649955199] 'agreement among raft nodes before linearized reading' (duration: 199.547478ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:36.165128Z","caller":"traceutil/trace.go:171","msg":"trace[2115721078] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"167.815412ms","start":"2026-07-02T04:13:35.997279Z","end":"2026-07-02T04:13:36.165095Z","steps":["trace[2115721078] 'process raft request' (duration: 167.71887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.165212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.147795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5525104-13a4-49db-a168-44cfacec514b\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-07-02T04:13:36.165359Z","caller":"traceutil/trace.go:171","msg":"trace[767400256] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5525104-13a4-49db-a168-44cfacec514b; range_end:; response_count:1; response_revision:25371; }","duration":"172.281397ms","start":"2026-07-02T04:13:35.992988Z","end":"2026-07-02T04:13:36.165269Z","steps":["trace[767400256] 'agreement among raft nodes before linearized reading' (duration: 172.125865ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:36.16544Z","caller":"traceutil/trace.go:171","msg":"trace[230668401] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"196.899917ms","start":"2026-07-02T04:13:35.968532Z","end":"2026-07-02T04:13:36.165432Z","steps":["trace[230668401] 'process raft request' (duration: 196.302915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.165501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.500867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-07-02T04:13:36.165597Z","caller":"traceutil/trace.go:171","msg":"trace[383323256] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25371; }","duration":"166.607299ms","start":"2026-07-02T04:13:35.998964Z","end":"2026-07-02T04:13:36.165571Z","steps":["trace[383323256] 'agreement among raft nodes before linearized reading' (duration: 166.343083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.428822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.770398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-16991375-225b-46e2-9474-d3a5419add59\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-07-02T04:13:36.428937Z","caller":"traceutil/trace.go:171","msg":"trace[573132183] range","detail":"{range_begin:/registry/persistentvolumes/pvc-16991375-225b-46e2-9474-d3a5419add59; range_end:; response_count:1; response_revision:25371; }","duration":"259.90503ms","start":"2026-07-02T04:13:36.169004Z","end":"2026-07-02T04:13:36.428909Z","steps":["trace[573132183] 'range keys from in-memory index tree' (duration: 259.639375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.42942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.347206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:36.429533Z","caller":"traceutil/trace.go:171","msg":"trace[1945269979] linearizableReadLoop","detail":"{readStateIndex:26196; appliedIndex:26195; }","duration":"257.81239ms","start":"2026-07-02T04:13:36.1717Z","end":"2026-07-02T04:13:36.429513Z","steps":["trace[1945269979] 'read index received' (duration: 125.157008ms)","trace[1945269979] 'applied index is now lower than readState.Index' (duration: 132.653992ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:36.429567Z","caller":"traceutil/trace.go:171","msg":"trace[86150283] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"260.386999ms","start":"2026-07-02T04:13:36.169161Z","end":"2026-07-02T04:13:36.429548Z","steps":["trace[86150283] 'process raft request' (duration: 127.769038ms)","trace[86150283] 'compare' (duration: 131.648302ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:36.429738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.035104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-07-02T04:13:36.429765Z","caller":"traceutil/trace.go:171","msg":"trace[1119655794] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25372; }","duration":"258.073495ms","start":"2026-07-02T04:13:36.171682Z","end":"2026-07-02T04:13:36.429755Z","steps":["trace[1119655794] 'agreement among raft nodes before linearized reading' (duration: 257.907432ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:36.753655Z","caller":"traceutil/trace.go:171","msg":"trace[2089882597] linearizableReadLoop","detail":"{readStateIndex:26198; appliedIndex:26196; }","duration":"324.061964ms","start":"2026-07-02T04:13:36.429556Z","end":"2026-07-02T04:13:36.753618Z","steps":["trace[2089882597] 'read index received' (duration: 30.471091ms)","trace[2089882597] 'applied index is now lower than readState.Index' (duration: 293.589373ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:36.753738Z","caller":"traceutil/trace.go:171","msg":"trace[608529878] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"335.334369ms","start":"2026-07-02T04:13:36.418381Z","end":"2026-07-02T04:13:36.753715Z","steps":["trace[608529878] 'process raft request' (duration: 334.971802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.753887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:36.41835Z","time spent":"335.429431ms","remote":"127.0.0.1:56002","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-07-02T04:13:36.753964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.639939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18be5dc783dd73ca\" ","response":"range_response_count:1 size:742"} {"level":"warn","ts":"2026-07-02T04:13:36.75399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.945186ms","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-07-02T04:13:36.754035Z","caller":"traceutil/trace.go:171","msg":"trace[601997764] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18be5dc783dd73ca; range_end:; response_count:1; response_revision:25374; }","duration":"319.757412ms","start":"2026-07-02T04:13:36.434256Z","end":"2026-07-02T04:13:36.754013Z","steps":["trace[601997764] 'agreement among raft nodes before linearized reading' (duration: 319.550988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.754071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.12824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1444"} {"level":"warn","ts":"2026-07-02T04:13:36.75407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.88726ms","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-07-02T04:13:36.754096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:36.434235Z","time spent":"319.848733ms","remote":"127.0.0.1:55826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":765,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18be5dc783dd73ca\" "} {"level":"info","ts":"2026-07-02T04:13:36.754096Z","caller":"traceutil/trace.go:171","msg":"trace[817479345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25374; }","duration":"330.10616ms","start":"2026-07-02T04:13:36.423964Z","end":"2026-07-02T04:13:36.75407Z","steps":["trace[817479345] 'agreement among raft nodes before linearized reading' (duration: 329.781204ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:36.754142Z","caller":"traceutil/trace.go:171","msg":"trace[2124057920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25374; }","duration":"302.976732ms","start":"2026-07-02T04:13:36.451145Z","end":"2026-07-02T04:13:36.754122Z","steps":["trace[2124057920] 'agreement among raft nodes before linearized reading' (duration: 302.729417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.754162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:36.423951Z","time spent":"330.200771ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T04:13:36.754178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:36.451112Z","time spent":"303.060083ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T04:13:36.754121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.965095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T04:13:36.754096Z","caller":"traceutil/trace.go:171","msg":"trace[1362199258] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25374; }","duration":"319.174241ms","start":"2026-07-02T04:13:36.434914Z","end":"2026-07-02T04:13:36.754088Z","steps":["trace[1362199258] 'agreement among raft nodes before linearized reading' (duration: 319.09602ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:36.754257Z","caller":"traceutil/trace.go:171","msg":"trace[1809300331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25374; }","duration":"146.172099ms","start":"2026-07-02T04:13:36.608054Z","end":"2026-07-02T04:13:36.754226Z","steps":["trace[1809300331] 'agreement among raft nodes before linearized reading' (duration: 145.792612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.754225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.431674ms","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-07-02T04:13:36.754518Z","caller":"traceutil/trace.go:171","msg":"trace[1533416976] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25374; }","duration":"277.816801ms","start":"2026-07-02T04:13:36.47669Z","end":"2026-07-02T04:13:36.754507Z","steps":["trace[1533416976] 'agreement among raft nodes before linearized reading' (duration: 277.365472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.754294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:36.434904Z","time spent":"319.383295ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1467,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-07-02T04:13:36.978461Z","caller":"traceutil/trace.go:171","msg":"trace[1847652973] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"220.030488ms","start":"2026-07-02T04:13:36.758399Z","end":"2026-07-02T04:13:36.978429Z","steps":["trace[1847652973] 'process raft request' (duration: 217.377688ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:36.978668Z","caller":"traceutil/trace.go:171","msg":"trace[1679805414] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26198; }","duration":"219.429946ms","start":"2026-07-02T04:13:36.759215Z","end":"2026-07-02T04:13:36.978645Z","steps":["trace[1679805414] 'read index received' (duration: 19.062273ms)","trace[1679805414] 'applied index is now lower than readState.Index' (duration: 200.366563ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:36.978878Z","caller":"traceutil/trace.go:171","msg":"trace[2137866108] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"219.439827ms","start":"2026-07-02T04:13:36.759428Z","end":"2026-07-02T04:13:36.978868Z","steps":["trace[2137866108] 'process raft request' (duration: 218.890866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.978975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.198615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18be5dc7841e0675\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-07-02T04:13:36.97901Z","caller":"traceutil/trace.go:171","msg":"trace[1131927354] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18be5dc7841e0675; range_end:; response_count:1; response_revision:25378; }","duration":"196.305397ms","start":"2026-07-02T04:13:36.78269Z","end":"2026-07-02T04:13:36.978996Z","steps":["trace[1131927354] 'agreement among raft nodes before linearized reading' (duration: 196.157303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:36.979041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.805484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-07-02T04:13:36.979136Z","caller":"traceutil/trace.go:171","msg":"trace[1636180327] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25378; }","duration":"219.923426ms","start":"2026-07-02T04:13:36.759188Z","end":"2026-07-02T04:13:36.979111Z","steps":["trace[1636180327] 'agreement among raft nodes before linearized reading' (duration: 219.618379ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:36.979174Z","caller":"traceutil/trace.go:171","msg":"trace[76784418] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"219.502689ms","start":"2026-07-02T04:13:36.759594Z","end":"2026-07-02T04:13:36.979097Z","steps":["trace[76784418] 'process raft request' (duration: 218.993308ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:39.494895Z","caller":"traceutil/trace.go:171","msg":"trace[1750437830] linearizableReadLoop","detail":"{readStateIndex:26226; appliedIndex:26225; }","duration":"100.65169ms","start":"2026-07-02T04:13:39.394194Z","end":"2026-07-02T04:13:39.494846Z","steps":["trace[1750437830] 'read index received' (duration: 100.342244ms)","trace[1750437830] 'applied index is now lower than readState.Index' (duration: 307.146µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:39.494965Z","caller":"traceutil/trace.go:171","msg":"trace[206796887] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"110.747474ms","start":"2026-07-02T04:13:39.384168Z","end":"2026-07-02T04:13:39.494915Z","steps":["trace[206796887] 'process raft request' (duration: 110.402657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:39.495272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.028007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-02T04:13:39.495356Z","caller":"traceutil/trace.go:171","msg":"trace[1669232568] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:25402; }","duration":"101.197791ms","start":"2026-07-02T04:13:39.394138Z","end":"2026-07-02T04:13:39.495336Z","steps":["trace[1669232568] 'agreement among raft nodes before linearized reading' (duration: 100.872955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:43.858096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.853717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:13:43.858286Z","caller":"traceutil/trace.go:171","msg":"trace[1254053130] linearizableReadLoop","detail":"{readStateIndex:26283; appliedIndex:26282; }","duration":"221.245262ms","start":"2026-07-02T04:13:43.636999Z","end":"2026-07-02T04:13:43.858244Z","steps":["trace[1254053130] 'read index received' (duration: 77.966458ms)","trace[1254053130] 'applied index is now lower than readState.Index' (duration: 143.274144ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:43.858404Z","caller":"traceutil/trace.go:171","msg":"trace[911303323] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"247.938362ms","start":"2026-07-02T04:13:43.610433Z","end":"2026-07-02T04:13:43.858371Z","steps":["trace[911303323] 'process raft request' (duration: 104.561006ms)","trace[911303323] 'compare' (duration: 140.406149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:43.858655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.603159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:13:43.858789Z","caller":"traceutil/trace.go:171","msg":"trace[1183045356] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25457; }","duration":"221.846713ms","start":"2026-07-02T04:13:43.636913Z","end":"2026-07-02T04:13:43.858759Z","steps":["trace[1183045356] 'agreement among raft nodes before linearized reading' (duration: 221.555208ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:43.914759Z","caller":"traceutil/trace.go:171","msg":"trace[599940604] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"166.588649ms","start":"2026-07-02T04:13:43.748151Z","end":"2026-07-02T04:13:43.914739Z","steps":["trace[599940604] 'process raft request' (duration: 166.411866ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:43.945949Z","caller":"traceutil/trace.go:171","msg":"trace[1843955584] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"182.682076ms","start":"2026-07-02T04:13:43.763197Z","end":"2026-07-02T04:13:43.94588Z","steps":["trace[1843955584] 'process raft request' (duration: 182.408771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:43.94596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.851317ms","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-07-02T04:13:43.9462Z","caller":"traceutil/trace.go:171","msg":"trace[1553387484] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25459; }","duration":"208.100432ms","start":"2026-07-02T04:13:43.738049Z","end":"2026-07-02T04:13:43.94615Z","steps":["trace[1553387484] 'agreement among raft nodes before linearized reading' (duration: 207.720284ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:44.188353Z","caller":"traceutil/trace.go:171","msg":"trace[1759137012] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"263.627821ms","start":"2026-07-02T04:13:43.924666Z","end":"2026-07-02T04:13:44.188294Z","steps":["trace[1759137012] 'process raft request' (duration: 211.078248ms)","trace[1759137012] 'compare' (duration: 52.337988ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:48.638652Z","caller":"traceutil/trace.go:171","msg":"trace[550331849] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"149.159925ms","start":"2026-07-02T04:13:48.489439Z","end":"2026-07-02T04:13:48.638599Z","steps":["trace[550331849] 'process raft request' (duration: 148.916711ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:48.912798Z","caller":"traceutil/trace.go:171","msg":"trace[758580749] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"405.497248ms","start":"2026-07-02T04:13:48.507255Z","end":"2026-07-02T04:13:48.912752Z","steps":["trace[758580749] 'process raft request' (duration: 334.784868ms)","trace[758580749] 'compare' (duration: 70.480525ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:13:48.912797Z","caller":"traceutil/trace.go:171","msg":"trace[124288762] linearizableReadLoop","detail":"{readStateIndex:26324; appliedIndex:26323; }","duration":"287.993256ms","start":"2026-07-02T04:13:48.624767Z","end":"2026-07-02T04:13:48.91276Z","steps":["trace[124288762] 'read index received' (duration: 14.62629ms)","trace[124288762] 'applied index is now lower than readState.Index' (duration: 273.365096ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:13:48.913066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:13:48.50723Z","time spent":"405.668761ms","remote":"127.0.0.1:56002","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-07-02T04:13:48.913327Z","caller":"traceutil/trace.go:171","msg":"trace[1241207880] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"118.680354ms","start":"2026-07-02T04:13:48.794624Z","end":"2026-07-02T04:13:48.913305Z","steps":["trace[1241207880] 'process raft request' (duration: 118.080163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:13:48.913619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.80837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277956"} {"level":"info","ts":"2026-07-02T04:13:48.913868Z","caller":"traceutil/trace.go:171","msg":"trace[57715025] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25498; }","duration":"289.142138ms","start":"2026-07-02T04:13:48.624696Z","end":"2026-07-02T04:13:48.913839Z","steps":["trace[57715025] 'agreement among raft nodes before linearized reading' (duration: 288.175239ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:13:53.033881Z","caller":"traceutil/trace.go:171","msg":"trace[2061649738] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"101.423115ms","start":"2026-07-02T04:13:52.932437Z","end":"2026-07-02T04:13:53.03386Z","steps":["trace[2061649738] 'process raft request' (duration: 101.348544ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.096923Z","caller":"traceutil/trace.go:171","msg":"trace[1002289203] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"115.072506ms","start":"2026-07-02T04:14:06.981816Z","end":"2026-07-02T04:14:07.096889Z","steps":["trace[1002289203] 'process raft request' (duration: 114.823372ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.283941Z","caller":"traceutil/trace.go:171","msg":"trace[727722109] linearizableReadLoop","detail":"{readStateIndex:26474; appliedIndex:26472; }","duration":"187.06487ms","start":"2026-07-02T04:14:07.096847Z","end":"2026-07-02T04:14:07.283912Z","steps":["trace[727722109] 'read index received' (duration: 109.292816ms)","trace[727722109] 'applied index is now lower than readState.Index' (duration: 77.771174ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:07.284086Z","caller":"traceutil/trace.go:171","msg":"trace[165845152] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"207.377767ms","start":"2026-07-02T04:14:07.076692Z","end":"2026-07-02T04:14:07.28407Z","steps":["trace[165845152] 'process raft request' (duration: 207.170843ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.284065Z","caller":"traceutil/trace.go:171","msg":"trace[1134461700] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"218.815265ms","start":"2026-07-02T04:14:07.06518Z","end":"2026-07-02T04:14:07.283995Z","steps":["trace[1134461700] 'process raft request' (duration: 140.987919ms)","trace[1134461700] 'compare' (duration: 77.563881ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:07.284236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.621036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T04:14:07.284273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.386903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:07.284306Z","caller":"traceutil/trace.go:171","msg":"trace[1152423353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25642; }","duration":"135.479415ms","start":"2026-07-02T04:14:07.148817Z","end":"2026-07-02T04:14:07.284296Z","steps":["trace[1152423353] 'agreement among raft nodes before linearized reading' (duration: 135.409894ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.284316Z","caller":"traceutil/trace.go:171","msg":"trace[1295708870] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25642; }","duration":"170.718338ms","start":"2026-07-02T04:14:07.113571Z","end":"2026-07-02T04:14:07.28429Z","steps":["trace[1295708870] 'agreement among raft nodes before linearized reading' (duration: 170.572696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:07.284459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.649763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-02T04:14:07.28451Z","caller":"traceutil/trace.go:171","msg":"trace[931550578] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25642; }","duration":"152.760165ms","start":"2026-07-02T04:14:07.131732Z","end":"2026-07-02T04:14:07.284493Z","steps":["trace[931550578] 'agreement among raft nodes before linearized reading' (duration: 152.561681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:07.284518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.408709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-07-02T04:14:07.284628Z","caller":"traceutil/trace.go:171","msg":"trace[320785201] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25642; }","duration":"238.589572ms","start":"2026-07-02T04:14:07.046013Z","end":"2026-07-02T04:14:07.284603Z","steps":["trace[320785201] 'agreement among raft nodes before linearized reading' (duration: 238.313027ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.510524Z","caller":"traceutil/trace.go:171","msg":"trace[1155620181] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"195.364608ms","start":"2026-07-02T04:14:07.315104Z","end":"2026-07-02T04:14:07.510468Z","steps":["trace[1155620181] 'process raft request' (duration: 136.555426ms)","trace[1155620181] 'compare' (duration: 58.500676ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:07.510649Z","caller":"traceutil/trace.go:171","msg":"trace[331113365] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"192.526634ms","start":"2026-07-02T04:14:07.318083Z","end":"2026-07-02T04:14:07.51061Z","steps":["trace[331113365] 'process raft request' (duration: 192.378231ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.510696Z","caller":"traceutil/trace.go:171","msg":"trace[1073150666] linearizableReadLoop","detail":"{readStateIndex:26475; appliedIndex:26474; }","duration":"192.904971ms","start":"2026-07-02T04:14:07.317754Z","end":"2026-07-02T04:14:07.510659Z","steps":["trace[1073150666] 'read index received' (duration: 133.895185ms)","trace[1073150666] 'applied index is now lower than readState.Index' (duration: 59.007966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:07.510978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.194886ms","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-07-02T04:14:07.511199Z","caller":"traceutil/trace.go:171","msg":"trace[1846515982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25644; }","duration":"193.475452ms","start":"2026-07-02T04:14:07.317711Z","end":"2026-07-02T04:14:07.511186Z","steps":["trace[1846515982] 'agreement among raft nodes before linearized reading' (duration: 193.088925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:07.5113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.040534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-02T04:14:07.511356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.825955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9547"} {"level":"info","ts":"2026-07-02T04:14:07.511354Z","caller":"traceutil/trace.go:171","msg":"trace[937998557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25644; }","duration":"192.137666ms","start":"2026-07-02T04:14:07.319204Z","end":"2026-07-02T04:14:07.511341Z","steps":["trace[937998557] 'agreement among raft nodes before linearized reading' (duration: 191.722649ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.511382Z","caller":"traceutil/trace.go:171","msg":"trace[1197973263] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25644; }","duration":"186.872435ms","start":"2026-07-02T04:14:07.324501Z","end":"2026-07-02T04:14:07.511373Z","steps":["trace[1197973263] 'agreement among raft nodes before linearized reading' (duration: 186.788554ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:07.739686Z","caller":"traceutil/trace.go:171","msg":"trace[320045520] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"105.168617ms","start":"2026-07-02T04:14:07.634477Z","end":"2026-07-02T04:14:07.739646Z","steps":["trace[320045520] 'process raft request' (duration: 104.915952ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:08.144312Z","caller":"traceutil/trace.go:171","msg":"trace[672838476] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"132.052759ms","start":"2026-07-02T04:14:08.012214Z","end":"2026-07-02T04:14:08.144267Z","steps":["trace[672838476] 'process raft request' (duration: 131.772154ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:08.149745Z","caller":"traceutil/trace.go:171","msg":"trace[647499568] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"136.073486ms","start":"2026-07-02T04:14:08.013639Z","end":"2026-07-02T04:14:08.149713Z","steps":["trace[647499568] 'process raft request' (duration: 135.827022ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:08.149817Z","caller":"traceutil/trace.go:171","msg":"trace[750118558] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"117.972831ms","start":"2026-07-02T04:14:08.031793Z","end":"2026-07-02T04:14:08.149765Z","steps":["trace[750118558] 'process raft request' (duration: 117.818558ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:08.468848Z","caller":"traceutil/trace.go:171","msg":"trace[1117714836] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"170.507444ms","start":"2026-07-02T04:14:08.298296Z","end":"2026-07-02T04:14:08.468803Z","steps":["trace[1117714836] 'process raft request' (duration: 126.847771ms)","trace[1117714836] 'compare' (duration: 43.267366ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:08.469071Z","caller":"traceutil/trace.go:171","msg":"trace[170876791] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"138.358379ms","start":"2026-07-02T04:14:08.330681Z","end":"2026-07-02T04:14:08.46904Z","steps":["trace[170876791] 'process raft request' (duration: 137.985953ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:10.368687Z","caller":"traceutil/trace.go:171","msg":"trace[984221983] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"213.979123ms","start":"2026-07-02T04:14:10.154676Z","end":"2026-07-02T04:14:10.368655Z","steps":["trace[984221983] 'process raft request' (duration: 153.249195ms)","trace[984221983] 'compare' (duration: 60.533895ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:10.368878Z","caller":"traceutil/trace.go:171","msg":"trace[669301743] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"214.003564ms","start":"2026-07-02T04:14:10.154834Z","end":"2026-07-02T04:14:10.368838Z","steps":["trace[669301743] 'process raft request' (duration: 213.770739ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:14.401122Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067a3.wal"} {"level":"info","ts":"2026-07-02T04:14:20.391641Z","caller":"traceutil/trace.go:171","msg":"trace[56648962] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"137.212328ms","start":"2026-07-02T04:14:20.254372Z","end":"2026-07-02T04:14:20.391584Z","steps":["trace[56648962] 'process raft request' (duration: 136.990853ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:20.540837Z","caller":"traceutil/trace.go:171","msg":"trace[1762957031] linearizableReadLoop","detail":"{readStateIndex:26574; appliedIndex:26572; }","duration":"241.134451ms","start":"2026-07-02T04:14:20.299666Z","end":"2026-07-02T04:14:20.5408Z","steps":["trace[1762957031] 'read index received' (duration: 91.645878ms)","trace[1762957031] 'applied index is now lower than readState.Index' (duration: 149.486743ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:20.541079Z","caller":"traceutil/trace.go:171","msg":"trace[504097352] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"251.875916ms","start":"2026-07-02T04:14:20.289162Z","end":"2026-07-02T04:14:20.541038Z","steps":["trace[504097352] 'process raft request' (duration: 162.95704ms)","trace[504097352] 'compare' (duration: 88.512179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:20.541855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.153381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:14:20.541989Z","caller":"traceutil/trace.go:171","msg":"trace[1422502399] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25739; }","duration":"242.321053ms","start":"2026-07-02T04:14:20.299643Z","end":"2026-07-02T04:14:20.541964Z","steps":["trace[1422502399] 'agreement among raft nodes before linearized reading' (duration: 241.496417ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:20.542598Z","caller":"traceutil/trace.go:171","msg":"trace[1440593130] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"131.429157ms","start":"2026-07-02T04:14:20.411152Z","end":"2026-07-02T04:14:20.542581Z","steps":["trace[1440593130] 'process raft request' (duration: 131.347765ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:20.542662Z","caller":"traceutil/trace.go:171","msg":"trace[19940813] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"101.6419ms","start":"2026-07-02T04:14:20.441002Z","end":"2026-07-02T04:14:20.542644Z","steps":["trace[19940813] 'process raft request' (duration: 101.545687ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:20.542766Z","caller":"traceutil/trace.go:171","msg":"trace[286171400] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"133.268444ms","start":"2026-07-02T04:14:20.409486Z","end":"2026-07-02T04:14:20.542754Z","steps":["trace[286171400] 'process raft request' (duration: 132.882886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:20.555186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.822679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:20.555395Z","caller":"traceutil/trace.go:171","msg":"trace[43143866] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25743; }","duration":"181.962052ms","start":"2026-07-02T04:14:20.373313Z","end":"2026-07-02T04:14:20.555275Z","steps":["trace[43143866] 'agreement among raft nodes before linearized reading' (duration: 181.783008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:21.274984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.652927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T04:14:21.275084Z","caller":"traceutil/trace.go:171","msg":"trace[569724557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25744; }","duration":"127.81143ms","start":"2026-07-02T04:14:21.147246Z","end":"2026-07-02T04:14:21.275057Z","steps":["trace[569724557] 'range keys from in-memory index tree' (duration: 127.547315ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:26.539664Z","caller":"traceutil/trace.go:171","msg":"trace[845449104] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"127.044075ms","start":"2026-07-02T04:14:26.412587Z","end":"2026-07-02T04:14:26.539631Z","steps":["trace[845449104] 'process raft request' (duration: 126.597056ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:26.785337Z","caller":"traceutil/trace.go:171","msg":"trace[1893689551] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"223.991604ms","start":"2026-07-02T04:14:26.561307Z","end":"2026-07-02T04:14:26.785298Z","steps":["trace[1893689551] 'process raft request' (duration: 223.785801ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:26.950551Z","caller":"traceutil/trace.go:171","msg":"trace[590258081] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"385.444275ms","start":"2026-07-02T04:14:26.565063Z","end":"2026-07-02T04:14:26.950508Z","steps":["trace[590258081] 'process raft request' (duration: 384.742292ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:26.949942Z","caller":"traceutil/trace.go:171","msg":"trace[1341132460] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"386.074747ms","start":"2026-07-02T04:14:26.563797Z","end":"2026-07-02T04:14:26.949872Z","steps":["trace[1341132460] 'process raft request' (duration: 320.900613ms)","trace[1341132460] 'compare' (duration: 64.820947ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:26.950775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:26.565048Z","time spent":"385.605438ms","remote":"127.0.0.1:56002","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-07-02T04:14:26.950816Z","caller":"traceutil/trace.go:171","msg":"trace[255726789] linearizableReadLoop","detail":"{readStateIndex:26632; appliedIndex:26629; }","duration":"163.620253ms","start":"2026-07-02T04:14:26.787131Z","end":"2026-07-02T04:14:26.950752Z","steps":["trace[255726789] 'read index received' (duration: 97.547172ms)","trace[255726789] 'applied index is now lower than readState.Index' (duration: 66.069861ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:26.950865Z","caller":"traceutil/trace.go:171","msg":"trace[1120940980] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"377.929672ms","start":"2026-07-02T04:14:26.572882Z","end":"2026-07-02T04:14:26.950811Z","steps":["trace[1120940980] 'process raft request' (duration: 377.493544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:26.950986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:26.563757Z","time spent":"387.073276ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T04:14:26.951526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:26.572849Z","time spent":"378.550805ms","remote":"127.0.0.1:56002","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-07-02T04:14:26.951025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.847837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:14:26.951864Z","caller":"traceutil/trace.go:171","msg":"trace[993440606] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25796; }","duration":"164.736014ms","start":"2026-07-02T04:14:26.787084Z","end":"2026-07-02T04:14:26.95182Z","steps":["trace[993440606] 'agreement among raft nodes before linearized reading' (duration: 163.836067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:26.951146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.130758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T04:14:26.951934Z","caller":"traceutil/trace.go:171","msg":"trace[1719712954] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25796; }","duration":"102.979084ms","start":"2026-07-02T04:14:26.848933Z","end":"2026-07-02T04:14:26.951912Z","steps":["trace[1719712954] 'agreement among raft nodes before linearized reading' (duration: 102.031316ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:27.515817Z","caller":"traceutil/trace.go:171","msg":"trace[742741260] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"150.221587ms","start":"2026-07-02T04:14:27.365561Z","end":"2026-07-02T04:14:27.515782Z","steps":["trace[742741260] 'process raft request' (duration: 150.013733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:27.686551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.432351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T04:14:27.686662Z","caller":"traceutil/trace.go:171","msg":"trace[1579279993] linearizableReadLoop","detail":"{readStateIndex:26635; appliedIndex:26633; }","duration":"229.693613ms","start":"2026-07-02T04:14:27.456951Z","end":"2026-07-02T04:14:27.686645Z","steps":["trace[1579279993] 'read index received' (duration: 58.66594ms)","trace[1579279993] 'applied index is now lower than readState.Index' (duration: 171.026843ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:27.686707Z","caller":"traceutil/trace.go:171","msg":"trace[769607032] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"318.968387ms","start":"2026-07-02T04:14:27.367695Z","end":"2026-07-02T04:14:27.686664Z","steps":["trace[769607032] 'process raft request' (duration: 180.316611ms)","trace[769607032] 'compare' (duration: 138.294149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:27.686828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.878706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-07-02T04:14:27.686846Z","caller":"traceutil/trace.go:171","msg":"trace[377149276] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25799; }","duration":"229.955278ms","start":"2026-07-02T04:14:27.456885Z","end":"2026-07-02T04:14:27.68684Z","steps":["trace[377149276] 'agreement among raft nodes before linearized reading' (duration: 229.835826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:27.686876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T04:14:27.367672Z","time spent":"319.10306ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T04:14:27.794998Z","caller":"traceutil/trace.go:171","msg":"trace[257418236] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"102.755971ms","start":"2026-07-02T04:14:27.692209Z","end":"2026-07-02T04:14:27.794965Z","steps":["trace[257418236] 'process raft request' (duration: 102.516366ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:27.795659Z","caller":"traceutil/trace.go:171","msg":"trace[280690427] linearizableReadLoop","detail":"{readStateIndex:26639; appliedIndex:26635; }","duration":"100.436367ms","start":"2026-07-02T04:14:27.69519Z","end":"2026-07-02T04:14:27.795626Z","steps":["trace[280690427] 'read index received' (duration: 70.867943ms)","trace[280690427] 'applied index is now lower than readState.Index' (duration: 29.566824ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T04:14:27.795913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.696281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-07-02T04:14:27.795956Z","caller":"traceutil/trace.go:171","msg":"trace[2095284187] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25805; }","duration":"100.764143ms","start":"2026-07-02T04:14:27.69518Z","end":"2026-07-02T04:14:27.795944Z","steps":["trace[2095284187] 'agreement among raft nodes before linearized reading' (duration: 100.567419ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:27.796048Z","caller":"traceutil/trace.go:171","msg":"trace[1889627135] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"103.747629ms","start":"2026-07-02T04:14:27.692271Z","end":"2026-07-02T04:14:27.796018Z","steps":["trace[1889627135] 'process raft request' (duration: 102.648918ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:27.796229Z","caller":"traceutil/trace.go:171","msg":"trace[2111268519] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"103.882082ms","start":"2026-07-02T04:14:27.692271Z","end":"2026-07-02T04:14:27.796153Z","steps":["trace[2111268519] 'process raft request' (duration: 103.049366ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:42.088216Z","caller":"traceutil/trace.go:171","msg":"trace[476908168] linearizableReadLoop","detail":"{readStateIndex:26746; appliedIndex:26744; }","duration":"208.240854ms","start":"2026-07-02T04:14:41.879942Z","end":"2026-07-02T04:14:42.088183Z","steps":["trace[476908168] 'read index received' (duration: 56.788354ms)","trace[476908168] 'applied index is now lower than readState.Index' (duration: 151.451ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T04:14:42.088333Z","caller":"traceutil/trace.go:171","msg":"trace[1319066074] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"216.043703ms","start":"2026-07-02T04:14:41.87224Z","end":"2026-07-02T04:14:42.088284Z","steps":["trace[1319066074] 'process raft request' (duration: 215.724987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:42.088533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.379659ms","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-07-02T04:14:42.088586Z","caller":"traceutil/trace.go:171","msg":"trace[1619635199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25907; }","duration":"195.506211ms","start":"2026-07-02T04:14:41.893062Z","end":"2026-07-02T04:14:42.088568Z","steps":["trace[1619635199] 'agreement among raft nodes before linearized reading' (duration: 195.296137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T04:14:42.088927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.960777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T04:14:42.089069Z","caller":"traceutil/trace.go:171","msg":"trace[463693555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25907; }","duration":"209.14978ms","start":"2026-07-02T04:14:41.87987Z","end":"2026-07-02T04:14:42.089019Z","steps":["trace[463693555] 'agreement among raft nodes before linearized reading' (duration: 208.793464ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T04:14:46.253179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23056} {"level":"info","ts":"2026-07-02T04:14:46.393368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23056,"took":"134.291723ms","hash":3377018504,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":36696064,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-02T04:14:46.393516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3377018504,"revision":23056,"compact-revision":20203} {"level":"info","ts":"2026-07-02T04:19:46.260299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25938} {"level":"info","ts":"2026-07-02T04:19:46.412472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25938,"took":"142.825745ms","hash":4127831742,"current-db-size-bytes":60383232,"current-db-size":"60 MB","current-db-size-in-use-bytes":35946496,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-02T04:19:46.41256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4127831742,"revision":25938,"compact-revision":23056} {"level":"info","ts":"2026-07-02T04:20:18.985835Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T04:20:18.990756Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-02T04:20:18.990895Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-02T04:24:46.270051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28809} {"level":"info","ts":"2026-07-02T04:24:46.415684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28809,"took":"141.174004ms","hash":169454747,"current-db-size-bytes":60383232,"current-db-size":"60 MB","current-db-size-in-use-bytes":31141888,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T04:24:46.415753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":169454747,"revision":28809,"compact-revision":25938} {"level":"info","ts":"2026-07-02T04:29:46.279082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31028} {"level":"info","ts":"2026-07-02T04:29:46.411897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31028,"took":"127.484683ms","hash":3321384454,"current-db-size-bytes":60383232,"current-db-size":"60 MB","current-db-size-in-use-bytes":30670848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T04:29:46.411994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3321384454,"revision":31028,"compact-revision":28809} {"level":"info","ts":"2026-07-02T04:34:46.287233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33289} {"level":"info","ts":"2026-07-02T04:34:46.445815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33289,"took":"150.249637ms","hash":3625750336,"current-db-size-bytes":61423616,"current-db-size":"61 MB","current-db-size-in-use-bytes":31281152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T04:34:46.445923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3625750336,"revision":33289,"compact-revision":31028} {"level":"info","ts":"2026-07-02T04:39:46.300279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35582} {"level":"info","ts":"2026-07-02T04:39:46.445977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35582,"took":"140.806698ms","hash":1554390333,"current-db-size-bytes":61423616,"current-db-size":"61 MB","current-db-size-in-use-bytes":32309248,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T04:39:46.446054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1554390333,"revision":35582,"compact-revision":33289} {"level":"info","ts":"2026-07-02T04:41:50.153148Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T04:41:50.157932Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-02T04:41:50.158252Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-02T04:44:46.308143Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37810} {"level":"info","ts":"2026-07-02T04:44:46.434695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37810,"took":"121.864305ms","hash":3048927647,"current-db-size-bytes":61845504,"current-db-size":"62 MB","current-db-size-in-use-bytes":32305152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T04:44:46.434778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3048927647,"revision":37810,"compact-revision":35582}