{"level":"warn","ts":"2026-05-11T10:47:57.976081Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:47:57.976199Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T10:47:57.976291Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:47:57.976308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-11T10:47:57.976333Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T10:47:57.976968Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-05-11T10:47:57.977045Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T10:47:57.981398Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.142721ms"} {"level":"info","ts":"2026-05-11T10:47:57.995124Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-05-11T10:47:57.995261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T10:47:57.995296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-05-11T10:47:57.995316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T10:47:57.995336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-05-11T10:47:57.995388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-05-11T10:47:58.000179Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T10:47:58.002434Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T10:47:58.005921Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T10:47:58.008132Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T10:47:58.008441Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T10:47:58.008482Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:47:58.008685Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:47:58.008719Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:47:58.010444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-05-11T10:47:58.010749Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-11T10:47:58.018959Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T10:47:58.019035Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-11T10:47:58.019168Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-11T10:47:58.01932Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T10:47:58.019383Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T10:47:58.495915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T10:47:58.496021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T10:47:58.496056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-05-11T10:47:58.496075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-05-11T10:47:58.496083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-11T10:47:58.496198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-05-11T10:47:58.496219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-11T10:47:58.49722Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:47:58.498165Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T10:47:58.498175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:47:58.49823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:47:58.49858Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T10:47:58.498622Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:47:58.498642Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T10:47:58.498727Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:47:58.498763Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:47:58.50165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-05-11T10:47:58.501989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T10:48:23.351556Z","caller":"traceutil/trace.go:171","msg":"trace[970635140] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"120.05971ms","start":"2026-05-11T10:48:23.231457Z","end":"2026-05-11T10:48:23.351516Z","steps":["trace[970635140] 'process raft request' (duration: 119.821745ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:37.933842Z","caller":"traceutil/trace.go:171","msg":"trace[1603980144] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"118.344916ms","start":"2026-05-11T10:48:37.815466Z","end":"2026-05-11T10:48:37.933811Z","steps":["trace[1603980144] 'read index received' (duration: 118.107002ms)","trace[1603980144] 'applied index is now lower than readState.Index' (duration: 236.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:48:37.933901Z","caller":"traceutil/trace.go:171","msg":"trace[2105828670] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"240.440257ms","start":"2026-05-11T10:48:37.693418Z","end":"2026-05-11T10:48:37.933858Z","steps":["trace[2105828670] 'process raft request' (duration: 240.226352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:37.934172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.673963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T10:48:37.934307Z","caller":"traceutil/trace.go:171","msg":"trace[1527429907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"118.854327ms","start":"2026-05-11T10:48:37.815427Z","end":"2026-05-11T10:48:37.934281Z","steps":["trace[1527429907] 'agreement among raft nodes before linearized reading' (duration: 118.524031ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:13.821455Z","caller":"traceutil/trace.go:171","msg":"trace[2027974971] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1914; }","duration":"146.87773ms","start":"2026-05-11T10:50:13.67455Z","end":"2026-05-11T10:50:13.821427Z","steps":["trace[2027974971] 'read index received' (duration: 146.742517ms)","trace[2027974971] 'applied index is now lower than readState.Index' (duration: 134.032µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:13.821585Z","caller":"traceutil/trace.go:171","msg":"trace[1070482571] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"182.485284ms","start":"2026-05-11T10:50:13.639055Z","end":"2026-05-11T10:50:13.821541Z","steps":["trace[1070482571] 'process raft request' (duration: 182.190978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:13.821716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.647227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-11T10:50:13.821725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.137724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-11T10:50:13.821786Z","caller":"traceutil/trace.go:171","msg":"trace[1967883234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1842; }","duration":"147.285537ms","start":"2026-05-11T10:50:13.674487Z","end":"2026-05-11T10:50:13.821773Z","steps":["trace[1967883234] 'agreement among raft nodes before linearized reading' (duration: 147.078313ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:13.821783Z","caller":"traceutil/trace.go:171","msg":"trace[297900517] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"106.75952ms","start":"2026-05-11T10:50:13.715004Z","end":"2026-05-11T10:50:13.821763Z","steps":["trace[297900517] 'agreement among raft nodes before linearized reading' (duration: 106.593497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:14.225367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.670143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:50:14.225559Z","caller":"traceutil/trace.go:171","msg":"trace[2054966417] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"236.804716ms","start":"2026-05-11T10:50:13.988727Z","end":"2026-05-11T10:50:14.225532Z","steps":["trace[2054966417] 'process raft request' (duration: 11.314157ms)","trace[2054966417] 'compare' (duration: 224.555531ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:14.225593Z","caller":"traceutil/trace.go:171","msg":"trace[873758977] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"105.359264ms","start":"2026-05-11T10:50:14.120206Z","end":"2026-05-11T10:50:14.225565Z","steps":["trace[873758977] 'process raft request' (duration: 105.274932ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:28.784776Z","caller":"traceutil/trace.go:171","msg":"trace[1370662343] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"114.751564ms","start":"2026-05-11T10:50:28.669943Z","end":"2026-05-11T10:50:28.784695Z","steps":["trace[1370662343] 'process raft request' (duration: 114.589521ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:08.591628Z","caller":"traceutil/trace.go:171","msg":"trace[14085423] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"109.149748ms","start":"2026-05-11T10:51:08.482457Z","end":"2026-05-11T10:51:08.591607Z","steps":["trace[14085423] 'read index received' (duration: 108.960604ms)","trace[14085423] 'applied index is now lower than readState.Index' (duration: 188.664µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:51:08.591668Z","caller":"traceutil/trace.go:171","msg":"trace[1095612355] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"131.764876ms","start":"2026-05-11T10:51:08.459871Z","end":"2026-05-11T10:51:08.591636Z","steps":["trace[1095612355] 'process raft request' (duration: 131.605943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:51:08.592109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.606276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T10:51:08.59222Z","caller":"traceutil/trace.go:171","msg":"trace[1335398333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2186; }","duration":"109.746258ms","start":"2026-05-11T10:51:08.482447Z","end":"2026-05-11T10:51:08.592194Z","steps":["trace[1335398333] 'agreement among raft nodes before linearized reading' (duration: 109.25756ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:08.744116Z","caller":"traceutil/trace.go:171","msg":"trace[607702106] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"147.043585ms","start":"2026-05-11T10:51:08.597033Z","end":"2026-05-11T10:51:08.744076Z","steps":["trace[607702106] 'process raft request' (duration: 126.656799ms)","trace[607702106] 'compare' (duration: 20.118801ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:51:43.220543Z","caller":"traceutil/trace.go:171","msg":"trace[1623069309] linearizableReadLoop","detail":"{readStateIndex:2651; appliedIndex:2650; }","duration":"124.773444ms","start":"2026-05-11T10:51:43.095746Z","end":"2026-05-11T10:51:43.220519Z","steps":["trace[1623069309] 'read index received' (duration: 120.103006ms)","trace[1623069309] 'applied index is now lower than readState.Index' (duration: 4.669338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:51:43.220739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.986348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-11T10:51:43.220801Z","caller":"traceutil/trace.go:171","msg":"trace[1843967981] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2551; }","duration":"125.100731ms","start":"2026-05-11T10:51:43.095691Z","end":"2026-05-11T10:51:43.220791Z","steps":["trace[1843967981] 'agreement among raft nodes before linearized reading' (duration: 124.918147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:51:43.220852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.927194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-11T10:51:43.220891Z","caller":"traceutil/trace.go:171","msg":"trace[743379437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2551; }","duration":"118.020246ms","start":"2026-05-11T10:51:43.10286Z","end":"2026-05-11T10:51:43.220881Z","steps":["trace[743379437] 'agreement among raft nodes before linearized reading' (duration: 117.859743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:51:43.22092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.886044ms","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:5"} {"level":"info","ts":"2026-05-11T10:51:43.220986Z","caller":"traceutil/trace.go:171","msg":"trace[125111255] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2551; }","duration":"107.977325ms","start":"2026-05-11T10:51:43.112991Z","end":"2026-05-11T10:51:43.220968Z","steps":["trace[125111255] 'agreement among raft nodes before linearized reading' (duration: 107.852933ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:43.221034Z","caller":"traceutil/trace.go:171","msg":"trace[434193145] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"167.670107ms","start":"2026-05-11T10:51:43.052899Z","end":"2026-05-11T10:51:43.220569Z","steps":["trace[434193145] 'process raft request' (duration: 163.043218ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:52:11.592551Z","caller":"traceutil/trace.go:171","msg":"trace[945209224] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"103.760371ms","start":"2026-05-11T10:52:11.488748Z","end":"2026-05-11T10:52:11.592508Z","steps":["trace[945209224] 'process raft request' (duration: 73.772234ms)","trace[945209224] 'compare' (duration: 29.547729ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:52:17.152592Z","caller":"traceutil/trace.go:171","msg":"trace[2037721142] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"111.577217ms","start":"2026-05-11T10:52:17.040992Z","end":"2026-05-11T10:52:17.15257Z","steps":["trace[2037721142] 'process raft request' (duration: 111.385434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:52:17.609587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.455677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:52:17.609692Z","caller":"traceutil/trace.go:171","msg":"trace[2119235476] linearizableReadLoop","detail":"{readStateIndex:2866; appliedIndex:2865; }","duration":"208.5501ms","start":"2026-05-11T10:52:17.401122Z","end":"2026-05-11T10:52:17.609672Z","steps":["trace[2119235476] 'read index received' (duration: 52.92283ms)","trace[2119235476] 'applied index is now lower than readState.Index' (duration: 155.62591ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:52:17.609982Z","caller":"traceutil/trace.go:171","msg":"trace[716800437] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"224.487881ms","start":"2026-05-11T10:52:17.385477Z","end":"2026-05-11T10:52:17.609965Z","steps":["trace[716800437] 'process raft request' (duration: 68.560335ms)","trace[716800437] 'compare' (duration: 155.276424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:52:17.610002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.543017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:52:17.61008Z","caller":"traceutil/trace.go:171","msg":"trace[148321979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2758; }","duration":"102.664729ms","start":"2026-05-11T10:52:17.507394Z","end":"2026-05-11T10:52:17.610059Z","steps":["trace[148321979] 'agreement among raft nodes before linearized reading' (duration: 102.528377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:52:17.610166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.018569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T10:52:17.610261Z","caller":"traceutil/trace.go:171","msg":"trace[1107015761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2758; }","duration":"209.143991ms","start":"2026-05-11T10:52:17.401098Z","end":"2026-05-11T10:52:17.610242Z","steps":["trace[1107015761] 'agreement among raft nodes before linearized reading' (duration: 208.879116ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:52:23.305358Z","caller":"traceutil/trace.go:171","msg":"trace[1928317045] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"124.338849ms","start":"2026-05-11T10:52:23.180989Z","end":"2026-05-11T10:52:23.305328Z","steps":["trace[1928317045] 'process raft request' (duration: 124.170966ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:52:23.33117Z","caller":"traceutil/trace.go:171","msg":"trace[1354928229] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"125.163755ms","start":"2026-05-11T10:52:23.205971Z","end":"2026-05-11T10:52:23.331135Z","steps":["trace[1354928229] 'process raft request' (duration: 110.13745ms)","trace[1354928229] 'compare' (duration: 14.677568ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:56:25.135175Z","caller":"traceutil/trace.go:171","msg":"trace[1057331865] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"162.489944ms","start":"2026-05-11T10:56:24.972649Z","end":"2026-05-11T10:56:25.135139Z","steps":["trace[1057331865] 'process raft request' (duration: 123.184436ms)","trace[1057331865] 'compare' (duration: 39.139815ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:57:58.642172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-05-11T10:57:58.714634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"70.964166ms","hash":1763555531,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":34283520,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T10:57:58.714732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1763555531,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-05-11T11:02:58.648956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6104} {"level":"info","ts":"2026-05-11T11:02:58.74572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6104,"took":"94.321062ms","hash":2897182305,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":20754432,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-11T11:02:58.745803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2897182305,"revision":6104,"compact-revision":2984} {"level":"warn","ts":"2026-05-11T11:03:17.200274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.513167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:03:17.200397Z","caller":"traceutil/trace.go:171","msg":"trace[1413398703] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8355; }","duration":"166.711911ms","start":"2026-05-11T11:03:17.033666Z","end":"2026-05-11T11:03:17.200378Z","steps":["trace[1413398703] 'count revisions from in-memory index tree' (duration: 166.425326ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:03:17.373811Z","caller":"traceutil/trace.go:171","msg":"trace[1694107506] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"333.884082ms","start":"2026-05-11T11:03:17.039894Z","end":"2026-05-11T11:03:17.373778Z","steps":["trace[1694107506] 'process raft request' (duration: 333.286311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:17.37401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:03:17.039879Z","time spent":"334.023464ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:03:17.37629Z","caller":"traceutil/trace.go:171","msg":"trace[1803328250] linearizableReadLoop","detail":"{readStateIndex:8654; appliedIndex:8652; }","duration":"198.486818ms","start":"2026-05-11T11:03:17.177772Z","end":"2026-05-11T11:03:17.376259Z","steps":["trace[1803328250] 'read index received' (duration: 195.434801ms)","trace[1803328250] 'applied index is now lower than readState.Index' (duration: 3.050167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:03:17.376597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.007289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T11:03:17.376642Z","caller":"traceutil/trace.go:171","msg":"trace[1907495483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8356; }","duration":"174.1234ms","start":"2026-05-11T11:03:17.202508Z","end":"2026-05-11T11:03:17.376631Z","steps":["trace[1907495483] 'agreement among raft nodes before linearized reading' (duration: 173.909566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:17.376656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.874145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T11:03:17.376716Z","caller":"traceutil/trace.go:171","msg":"trace[1246235099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8356; }","duration":"198.966557ms","start":"2026-05-11T11:03:17.177735Z","end":"2026-05-11T11:03:17.376702Z","steps":["trace[1246235099] 'agreement among raft nodes before linearized reading' (duration: 198.775834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:17.37669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.56394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-57bwd\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-11T11:03:17.376812Z","caller":"traceutil/trace.go:171","msg":"trace[1552869543] range","detail":"{range_begin:/registry/pods/openstack/keepalived-57bwd; range_end:; response_count:1; response_revision:8356; }","duration":"164.702483ms","start":"2026-05-11T11:03:17.212081Z","end":"2026-05-11T11:03:17.376784Z","steps":["trace[1552869543] 'agreement among raft nodes before linearized reading' (duration: 164.385667ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:03:29.155401Z","caller":"traceutil/trace.go:171","msg":"trace[633424260] linearizableReadLoop","detail":"{readStateIndex:8716; appliedIndex:8715; }","duration":"182.813565ms","start":"2026-05-11T11:03:28.972555Z","end":"2026-05-11T11:03:29.155368Z","steps":["trace[633424260] 'read index received' (duration: 182.465318ms)","trace[633424260] 'applied index is now lower than readState.Index' (duration: 346.757µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:03:29.155602Z","caller":"traceutil/trace.go:171","msg":"trace[120630054] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"328.551132ms","start":"2026-05-11T11:03:28.827006Z","end":"2026-05-11T11:03:29.155557Z","steps":["trace[120630054] 'process raft request' (duration: 328.140855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:29.155787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.875606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-11T11:03:29.155871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.289893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-11T11:03:29.155891Z","caller":"traceutil/trace.go:171","msg":"trace[1277560881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8416; }","duration":"158.049549ms","start":"2026-05-11T11:03:28.997812Z","end":"2026-05-11T11:03:29.155862Z","steps":["trace[1277560881] 'agreement among raft nodes before linearized reading' (duration: 157.762093ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:03:29.155925Z","caller":"traceutil/trace.go:171","msg":"trace[664845972] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8416; }","duration":"183.449756ms","start":"2026-05-11T11:03:28.97246Z","end":"2026-05-11T11:03:29.15591Z","steps":["trace[664845972] 'agreement among raft nodes before linearized reading' (duration: 183.06406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:29.155983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:03:28.826966Z","time spent":"328.779856ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:03:29.841446Z","caller":"traceutil/trace.go:171","msg":"trace[384508777] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"200.428345ms","start":"2026-05-11T11:03:29.64098Z","end":"2026-05-11T11:03:29.841408Z","steps":["trace[384508777] 'process raft request' (duration: 200.15093ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:03:29.850903Z","caller":"traceutil/trace.go:171","msg":"trace[938154942] linearizableReadLoop","detail":"{readStateIndex:8722; appliedIndex:8720; }","duration":"193.100648ms","start":"2026-05-11T11:03:29.657772Z","end":"2026-05-11T11:03:29.850873Z","steps":["trace[938154942] 'read index received' (duration: 183.403376ms)","trace[938154942] 'applied index is now lower than readState.Index' (duration: 9.695382ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:03:29.850963Z","caller":"traceutil/trace.go:171","msg":"trace[900541661] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"209.586468ms","start":"2026-05-11T11:03:29.641345Z","end":"2026-05-11T11:03:29.850932Z","steps":["trace[900541661] 'process raft request' (duration: 209.354823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:29.851365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.554146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-11T11:03:29.851467Z","caller":"traceutil/trace.go:171","msg":"trace[1427702682] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8422; }","duration":"193.737939ms","start":"2026-05-11T11:03:29.657709Z","end":"2026-05-11T11:03:29.851447Z","steps":["trace[1427702682] 'agreement among raft nodes before linearized reading' (duration: 193.291171ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:03:31.160633Z","caller":"traceutil/trace.go:171","msg":"trace[1220002308] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"105.327888ms","start":"2026-05-11T11:03:31.055277Z","end":"2026-05-11T11:03:31.160605Z","steps":["trace[1220002308] 'process raft request' (duration: 105.149275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:58.420114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.463703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-11T11:03:58.420236Z","caller":"traceutil/trace.go:171","msg":"trace[1174680947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8628; }","duration":"375.643066ms","start":"2026-05-11T11:03:58.044564Z","end":"2026-05-11T11:03:58.420207Z","steps":["trace[1174680947] 'range keys from in-memory index tree' (duration: 375.206778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:03:58.420302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:03:58.044544Z","time spent":"375.741408ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T11:03:58.457166Z","caller":"traceutil/trace.go:171","msg":"trace[1957607175] linearizableReadLoop","detail":"{readStateIndex:8937; appliedIndex:8936; }","duration":"138.365009ms","start":"2026-05-11T11:03:58.318757Z","end":"2026-05-11T11:03:58.457122Z","steps":["trace[1957607175] 'read index received' (duration: 138.224327ms)","trace[1957607175] 'applied index is now lower than readState.Index' (duration: 139.622µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:03:58.457434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.642694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T11:03:58.457522Z","caller":"traceutil/trace.go:171","msg":"trace[1559938801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8629; }","duration":"138.808117ms","start":"2026-05-11T11:03:58.318698Z","end":"2026-05-11T11:03:58.457506Z","steps":["trace[1559938801] 'agreement among raft nodes before linearized reading' (duration: 138.644754ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:03:58.457905Z","caller":"traceutil/trace.go:171","msg":"trace[487376777] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"248.66864ms","start":"2026-05-11T11:03:58.209214Z","end":"2026-05-11T11:03:58.457883Z","steps":["trace[487376777] 'process raft request' (duration: 247.583999ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:04:01.609292Z","caller":"traceutil/trace.go:171","msg":"trace[1788454812] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"281.022969ms","start":"2026-05-11T11:04:01.328241Z","end":"2026-05-11T11:04:01.609264Z","steps":["trace[1788454812] 'process raft request' (duration: 280.848796ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:04:01.609299Z","caller":"traceutil/trace.go:171","msg":"trace[626854508] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8953; }","duration":"249.871164ms","start":"2026-05-11T11:04:01.359388Z","end":"2026-05-11T11:04:01.60926Z","steps":["trace[626854508] 'read index received' (duration: 249.854204ms)","trace[626854508] 'applied index is now lower than readState.Index' (duration: 14.63µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:04:01.609644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.19342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T11:04:01.609692Z","caller":"traceutil/trace.go:171","msg":"trace[1593807638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8644; }","duration":"250.313463ms","start":"2026-05-11T11:04:01.359364Z","end":"2026-05-11T11:04:01.609678Z","steps":["trace[1593807638] 'agreement among raft nodes before linearized reading' (duration: 249.997577ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:04:01.643131Z","caller":"traceutil/trace.go:171","msg":"trace[1386933281] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"100.06725ms","start":"2026-05-11T11:04:01.543036Z","end":"2026-05-11T11:04:01.643104Z","steps":["trace[1386933281] 'process raft request' (duration: 99.840256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:04:10.92275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.656965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T11:04:10.922876Z","caller":"traceutil/trace.go:171","msg":"trace[1271952468] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"156.531341ms","start":"2026-05-11T11:04:10.76632Z","end":"2026-05-11T11:04:10.922851Z","steps":["trace[1271952468] 'process raft request' (duration: 50.671272ms)","trace[1271952468] 'compare' (duration: 105.509662ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:04:11.706932Z","caller":"traceutil/trace.go:171","msg":"trace[1219292358] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"195.780008ms","start":"2026-05-11T11:04:11.511121Z","end":"2026-05-11T11:04:11.706901Z","steps":["trace[1219292358] 'process raft request' (duration: 195.485692ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:04:27.622814Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002416.wal"} {"level":"info","ts":"2026-05-11T11:05:26.956259Z","caller":"traceutil/trace.go:171","msg":"trace[100876681] transaction","detail":"{read_only:false; response_revision:9483; number_of_response:1; }","duration":"123.016665ms","start":"2026-05-11T11:05:26.833216Z","end":"2026-05-11T11:05:26.956232Z","steps":["trace[100876681] 'process raft request' (duration: 122.76381ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:05:26.956242Z","caller":"traceutil/trace.go:171","msg":"trace[922387835] linearizableReadLoop","detail":"{readStateIndex:9818; appliedIndex:9816; }","duration":"120.6703ms","start":"2026-05-11T11:05:26.835537Z","end":"2026-05-11T11:05:26.956207Z","steps":["trace[922387835] 'read index received' (duration: 71.610692ms)","trace[922387835] 'applied index is now lower than readState.Index' (duration: 49.058278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:05:26.956417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.844604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T11:05:26.956613Z","caller":"traceutil/trace.go:171","msg":"trace[485068280] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:9483; }","duration":"121.083938ms","start":"2026-05-11T11:05:26.835487Z","end":"2026-05-11T11:05:26.956571Z","steps":["trace[485068280] 'agreement among raft nodes before linearized reading' (duration: 120.823943ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:05:50.314405Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T11:05:50.320391Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T11:05:50.320474Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T11:07:58.655166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8085} {"level":"info","ts":"2026-05-11T11:07:58.719163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8085,"took":"60.357826ms","hash":90223611,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":27717632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T11:07:58.719257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":90223611,"revision":8085,"compact-revision":6104} {"level":"info","ts":"2026-05-11T11:12:58.661848Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10482} {"level":"info","ts":"2026-05-11T11:12:58.7382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10482,"took":"73.897328ms","hash":2387601002,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":26054656,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T11:12:58.738262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2387601002,"revision":10482,"compact-revision":8085} {"level":"warn","ts":"2026-05-11T11:16:55.443971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.910824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T11:16:55.444062Z","caller":"traceutil/trace.go:171","msg":"trace[718006106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14307; }","duration":"126.037656ms","start":"2026-05-11T11:16:55.318003Z","end":"2026-05-11T11:16:55.444041Z","steps":["trace[718006106] 'range keys from in-memory index tree' (duration: 125.824362ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:16.789717Z","caller":"traceutil/trace.go:171","msg":"trace[209504332] transaction","detail":"{read_only:false; response_revision:14423; number_of_response:1; }","duration":"213.656705ms","start":"2026-05-11T11:17:16.576033Z","end":"2026-05-11T11:17:16.789689Z","steps":["trace[209504332] 'process raft request' (duration: 213.478582ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:16.79199Z","caller":"traceutil/trace.go:171","msg":"trace[1961969288] transaction","detail":"{read_only:false; response_revision:14425; number_of_response:1; }","duration":"110.279212ms","start":"2026-05-11T11:17:16.681674Z","end":"2026-05-11T11:17:16.791953Z","steps":["trace[1961969288] 'process raft request' (duration: 110.17884ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:16.791977Z","caller":"traceutil/trace.go:171","msg":"trace[190731319] transaction","detail":"{read_only:false; response_revision:14424; number_of_response:1; }","duration":"112.172128ms","start":"2026-05-11T11:17:16.679759Z","end":"2026-05-11T11:17:16.791931Z","steps":["trace[190731319] 'process raft request' (duration: 111.957163ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:31.683867Z","caller":"traceutil/trace.go:171","msg":"trace[1831571562] transaction","detail":"{read_only:false; response_revision:14514; number_of_response:1; }","duration":"120.104506ms","start":"2026-05-11T11:17:31.563723Z","end":"2026-05-11T11:17:31.683827Z","steps":["trace[1831571562] 'process raft request' (duration: 119.79146ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:17:58.695084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12682} {"level":"info","ts":"2026-05-11T11:17:58.792098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12682,"took":"93.187578ms","hash":854319369,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":29356032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T11:17:58.79217Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":854319369,"revision":12682,"compact-revision":10482} {"level":"info","ts":"2026-05-11T11:19:21.621503Z","caller":"traceutil/trace.go:171","msg":"trace[1256941203] transaction","detail":"{read_only:false; response_revision:15771; number_of_response:1; }","duration":"109.529644ms","start":"2026-05-11T11:19:21.511921Z","end":"2026-05-11T11:19:21.621451Z","steps":["trace[1256941203] 'process raft request' (duration: 109.28554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:19:21.849482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.385795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T11:19:21.849624Z","caller":"traceutil/trace.go:171","msg":"trace[2030836914] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15771; }","duration":"163.65465ms","start":"2026-05-11T11:19:21.685942Z","end":"2026-05-11T11:19:21.849596Z","steps":["trace[2030836914] 'count revisions from in-memory index tree' (duration: 162.922947ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:19:21.921267Z","caller":"traceutil/trace.go:171","msg":"trace[274839206] transaction","detail":"{read_only:false; response_revision:15772; number_of_response:1; }","duration":"138.09522ms","start":"2026-05-11T11:19:21.783149Z","end":"2026-05-11T11:19:21.921244Z","steps":["trace[274839206] 'process raft request' (duration: 137.904066ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:19:21.990668Z","caller":"traceutil/trace.go:171","msg":"trace[2013425297] linearizableReadLoop","detail":"{readStateIndex:16314; appliedIndex:16312; }","duration":"118.176377ms","start":"2026-05-11T11:19:21.872469Z","end":"2026-05-11T11:19:21.990645Z","steps":["trace[2013425297] 'read index received' (duration: 48.550541ms)","trace[2013425297] 'applied index is now lower than readState.Index' (duration: 69.624596ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:19:21.990872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.373441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T11:19:21.990921Z","caller":"traceutil/trace.go:171","msg":"trace[329672581] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:15773; }","duration":"118.502674ms","start":"2026-05-11T11:19:21.872405Z","end":"2026-05-11T11:19:21.990908Z","steps":["trace[329672581] 'agreement among raft nodes before linearized reading' (duration: 118.33361ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:22:58.702076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14986} {"level":"info","ts":"2026-05-11T11:22:58.791528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14986,"took":"87.345596ms","hash":3583204022,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T11:22:58.791676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3583204022,"revision":14986,"compact-revision":12682} {"level":"warn","ts":"2026-05-11T11:24:58.409608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.541933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:24:58.409725Z","caller":"traceutil/trace.go:171","msg":"trace[667836277] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18360; }","duration":"122.711326ms","start":"2026-05-11T11:24:58.286989Z","end":"2026-05-11T11:24:58.4097Z","steps":["trace[667836277] 'range keys from in-memory index tree' (duration: 122.469242ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:25:04.655695Z","caller":"traceutil/trace.go:171","msg":"trace[1817528352] transaction","detail":"{read_only:false; response_revision:18401; number_of_response:1; }","duration":"226.958268ms","start":"2026-05-11T11:25:04.428706Z","end":"2026-05-11T11:25:04.655664Z","steps":["trace[1817528352] 'process raft request' (duration: 226.763055ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:25:04.706523Z","caller":"traceutil/trace.go:171","msg":"trace[1887575165] transaction","detail":"{read_only:false; response_revision:18402; number_of_response:1; }","duration":"101.397298ms","start":"2026-05-11T11:25:04.605094Z","end":"2026-05-11T11:25:04.706491Z","steps":["trace[1887575165] 'process raft request' (duration: 101.141413ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:26:42.89834Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T11:26:42.902636Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T11:26:42.902746Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T11:27:58.708514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17424} {"level":"info","ts":"2026-05-11T11:27:58.801832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17424,"took":"89.734666ms","hash":2713208216,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":33964032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T11:27:58.801885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2713208216,"revision":17424,"compact-revision":14986} {"level":"info","ts":"2026-05-11T11:30:35.411278Z","caller":"traceutil/trace.go:171","msg":"trace[1696683154] transaction","detail":"{read_only:false; response_revision:20908; number_of_response:1; }","duration":"109.318322ms","start":"2026-05-11T11:30:35.301931Z","end":"2026-05-11T11:30:35.411249Z","steps":["trace[1696683154] 'process raft request' (duration: 108.825164ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:30:35.689915Z","caller":"traceutil/trace.go:171","msg":"trace[1667995151] transaction","detail":"{read_only:false; response_revision:20909; number_of_response:1; }","duration":"110.309181ms","start":"2026-05-11T11:30:35.579577Z","end":"2026-05-11T11:30:35.689886Z","steps":["trace[1667995151] 'process raft request' (duration: 110.126998ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:30:35.868084Z","caller":"traceutil/trace.go:171","msg":"trace[1958792469] transaction","detail":"{read_only:false; response_revision:20910; number_of_response:1; }","duration":"288.364087ms","start":"2026-05-11T11:30:35.579694Z","end":"2026-05-11T11:30:35.868058Z","steps":["trace[1958792469] 'process raft request' (duration: 288.140783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:30:36.13727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.331178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:30:36.137509Z","caller":"traceutil/trace.go:171","msg":"trace[1473263440] transaction","detail":"{read_only:false; response_revision:20911; number_of_response:1; }","duration":"422.765888ms","start":"2026-05-11T11:30:35.714691Z","end":"2026-05-11T11:30:36.137457Z","steps":["trace[1473263440] 'process raft request' (duration: 318.111723ms)","trace[1473263440] 'compare' (duration: 104.140845ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:30:36.137561Z","caller":"traceutil/trace.go:171","msg":"trace[2011279744] transaction","detail":"{read_only:false; response_revision:20912; number_of_response:1; }","duration":"290.13704ms","start":"2026-05-11T11:30:35.847383Z","end":"2026-05-11T11:30:36.13752Z","steps":["trace[2011279744] 'process raft request' (duration: 290.027088ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:30:36.137599Z","caller":"traceutil/trace.go:171","msg":"trace[923320295] transaction","detail":"{read_only:false; response_revision:20913; number_of_response:1; }","duration":"254.585576ms","start":"2026-05-11T11:30:35.882991Z","end":"2026-05-11T11:30:36.137577Z","steps":["trace[923320295] 'process raft request' (duration: 254.484544ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:30:36.137655Z","caller":"traceutil/trace.go:171","msg":"trace[1810960666] transaction","detail":"{read_only:false; response_revision:20914; number_of_response:1; }","duration":"252.858284ms","start":"2026-05-11T11:30:35.884778Z","end":"2026-05-11T11:30:36.137637Z","steps":["trace[1810960666] 'process raft request' (duration: 252.752672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:30:36.137685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:30:35.714662Z","time spent":"422.91836ms","remote":"127.0.0.1:51440","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-05-11T11:30:36.137645Z","caller":"traceutil/trace.go:171","msg":"trace[502362656] linearizableReadLoop","detail":"{readStateIndex:21620; appliedIndex:21618; }","duration":"326.52083ms","start":"2026-05-11T11:30:35.811053Z","end":"2026-05-11T11:30:36.137574Z","steps":["trace[502362656] 'read index received' (duration: 56.781001ms)","trace[502362656] 'applied index is now lower than readState.Index' (duration: 269.715499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:30:36.13788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.757895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:30:36.137974Z","caller":"traceutil/trace.go:171","msg":"trace[1661786576] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:20914; }","duration":"326.949759ms","start":"2026-05-11T11:30:35.810987Z","end":"2026-05-11T11:30:36.137937Z","steps":["trace[1661786576] 'agreement among raft nodes before linearized reading' (duration: 326.754455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:30:36.138016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.257221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:30:36.138071Z","caller":"traceutil/trace.go:171","msg":"trace[1622983199] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:20914; }","duration":"108.373814ms","start":"2026-05-11T11:30:36.029687Z","end":"2026-05-11T11:30:36.13806Z","steps":["trace[1622983199] 'agreement among raft nodes before linearized reading' (duration: 108.214281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:30:36.138053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:30:35.810966Z","time spent":"327.07266ms","remote":"127.0.0.1:51076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:30:36.501859Z","caller":"traceutil/trace.go:171","msg":"trace[1478849351] transaction","detail":"{read_only:false; response_revision:20915; number_of_response:1; }","duration":"213.942977ms","start":"2026-05-11T11:30:36.287886Z","end":"2026-05-11T11:30:36.501829Z","steps":["trace[1478849351] 'process raft request' (duration: 213.748623ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:32:58.714394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19811} {"level":"info","ts":"2026-05-11T11:32:58.825285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19811,"took":"107.304105ms","hash":3395814737,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":29671424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T11:32:58.825332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3395814737,"revision":19811,"compact-revision":17424} {"level":"info","ts":"2026-05-11T11:34:25.067273Z","caller":"traceutil/trace.go:171","msg":"trace[812276714] transaction","detail":"{read_only:false; response_revision:23289; number_of_response:1; }","duration":"156.447302ms","start":"2026-05-11T11:34:24.910796Z","end":"2026-05-11T11:34:25.067243Z","steps":["trace[812276714] 'process raft request' (duration: 135.784806ms)","trace[812276714] 'compare' (duration: 20.476222ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:34:25.069847Z","caller":"traceutil/trace.go:171","msg":"trace[238617275] transaction","detail":"{read_only:false; response_revision:23290; number_of_response:1; }","duration":"157.273468ms","start":"2026-05-11T11:34:24.912553Z","end":"2026-05-11T11:34:25.069826Z","steps":["trace[238617275] 'process raft request' (duration: 157.111025ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:34:25.069952Z","caller":"traceutil/trace.go:171","msg":"trace[1646546493] linearizableReadLoop","detail":"{readStateIndex:24073; appliedIndex:24070; }","duration":"143.404569ms","start":"2026-05-11T11:34:24.926515Z","end":"2026-05-11T11:34:25.069919Z","steps":["trace[1646546493] 'read index received' (duration: 120.138385ms)","trace[1646546493] 'applied index is now lower than readState.Index' (duration: 23.264524ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:34:25.069994Z","caller":"traceutil/trace.go:171","msg":"trace[1140827114] transaction","detail":"{read_only:false; response_revision:23291; number_of_response:1; }","duration":"152.403737ms","start":"2026-05-11T11:34:24.917583Z","end":"2026-05-11T11:34:25.069987Z","steps":["trace[1140827114] 'process raft request' (duration: 152.192453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:34:25.07012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.579492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T11:34:25.070149Z","caller":"traceutil/trace.go:171","msg":"trace[779529116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23291; }","duration":"143.662383ms","start":"2026-05-11T11:34:24.92648Z","end":"2026-05-11T11:34:25.070142Z","steps":["trace[779529116] 'agreement among raft nodes before linearized reading' (duration: 143.540311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:37:57.352519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.287424ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-11T11:37:57.352781Z","caller":"traceutil/trace.go:171","msg":"trace[527633212] linearizableReadLoop","detail":"{readStateIndex:26266; appliedIndex:26265; }","duration":"124.249274ms","start":"2026-05-11T11:37:57.228493Z","end":"2026-05-11T11:37:57.352742Z","steps":["trace[527633212] 'read index received' (duration: 43.051µs)","trace[527633212] 'applied index is now lower than readState.Index' (duration: 124.198853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:37:57.352939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.429839ms","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-05-11T11:37:57.352998Z","caller":"traceutil/trace.go:171","msg":"trace[157957219] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25430; }","duration":"124.544881ms","start":"2026-05-11T11:37:57.22844Z","end":"2026-05-11T11:37:57.352985Z","steps":["trace[157957219] 'agreement among raft nodes before linearized reading' (duration: 124.403307ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:37:57.563941Z","caller":"traceutil/trace.go:171","msg":"trace[1289573366] linearizableReadLoop","detail":"{readStateIndex:26268; appliedIndex:26267; }","duration":"121.851781ms","start":"2026-05-11T11:37:57.442057Z","end":"2026-05-11T11:37:57.563908Z","steps":["trace[1289573366] 'read index received' (duration: 72.263753ms)","trace[1289573366] 'applied index is now lower than readState.Index' (duration: 49.586768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:37:57.564164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.094595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:37:57.564229Z","caller":"traceutil/trace.go:171","msg":"trace[579859835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25431; }","duration":"122.198787ms","start":"2026-05-11T11:37:57.442014Z","end":"2026-05-11T11:37:57.564213Z","steps":["trace[579859835] 'agreement among raft nodes before linearized reading' (duration: 121.996444ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:37:57.564352Z","caller":"traceutil/trace.go:171","msg":"trace[1529747976] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"167.824041ms","start":"2026-05-11T11:37:57.396121Z","end":"2026-05-11T11:37:57.563945Z","steps":["trace[1529747976] 'process raft request' (duration: 118.215643ms)","trace[1529747976] 'compare' (duration: 49.401435ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:37:58.719632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22031} {"level":"info","ts":"2026-05-11T11:37:58.848336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22031,"took":"124.707845ms","hash":3499195476,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":43470848,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-11T11:37:58.848379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3499195476,"revision":22031,"compact-revision":19811} {"level":"info","ts":"2026-05-11T11:38:01.207826Z","caller":"traceutil/trace.go:171","msg":"trace[1322939449] linearizableReadLoop","detail":"{readStateIndex:26298; appliedIndex:26297; }","duration":"115.769117ms","start":"2026-05-11T11:38:01.092033Z","end":"2026-05-11T11:38:01.207802Z","steps":["trace[1322939449] 'read index received' (duration: 115.581154ms)","trace[1322939449] 'applied index is now lower than readState.Index' (duration: 186.943µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:01.208039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.002011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T11:38:01.208082Z","caller":"traceutil/trace.go:171","msg":"trace[1428176888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25460; }","duration":"116.092343ms","start":"2026-05-11T11:38:01.091975Z","end":"2026-05-11T11:38:01.208067Z","steps":["trace[1428176888] 'agreement among raft nodes before linearized reading' (duration: 115.92725ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:01.207999Z","caller":"traceutil/trace.go:171","msg":"trace[490228753] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"115.924589ms","start":"2026-05-11T11:38:01.091938Z","end":"2026-05-11T11:38:01.207863Z","steps":["trace[490228753] 'process raft request' (duration: 115.723336ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:01.468404Z","caller":"traceutil/trace.go:171","msg":"trace[19659296] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"106.160317ms","start":"2026-05-11T11:38:01.362216Z","end":"2026-05-11T11:38:01.468376Z","steps":["trace[19659296] 'process raft request' (duration: 106.048185ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:01.468567Z","caller":"traceutil/trace.go:171","msg":"trace[365404600] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"254.592075ms","start":"2026-05-11T11:38:01.213939Z","end":"2026-05-11T11:38:01.468531Z","steps":["trace[365404600] 'process raft request' (duration: 186.502ms)","trace[365404600] 'compare' (duration: 67.451143ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:01.468577Z","caller":"traceutil/trace.go:171","msg":"trace[2053725987] linearizableReadLoop","detail":"{readStateIndex:26299; appliedIndex:26298; }","duration":"150.037378ms","start":"2026-05-11T11:38:01.318515Z","end":"2026-05-11T11:38:01.468552Z","steps":["trace[2053725987] 'read index received' (duration: 81.959814ms)","trace[2053725987] 'applied index is now lower than readState.Index' (duration: 68.076184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:01.46869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.16447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:01.468723Z","caller":"traceutil/trace.go:171","msg":"trace[1840669545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25462; }","duration":"150.252102ms","start":"2026-05-11T11:38:01.318462Z","end":"2026-05-11T11:38:01.468714Z","steps":["trace[1840669545] 'agreement among raft nodes before linearized reading' (duration: 150.169981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:01.81453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.042855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T11:38:01.814615Z","caller":"traceutil/trace.go:171","msg":"trace[413732442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25463; }","duration":"238.168317ms","start":"2026-05-11T11:38:01.576423Z","end":"2026-05-11T11:38:01.814591Z","steps":["trace[413732442] 'range keys from in-memory index tree' (duration: 237.823631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:01.814637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.188894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:38:01.814748Z","caller":"traceutil/trace.go:171","msg":"trace[2145965948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25463; }","duration":"233.353627ms","start":"2026-05-11T11:38:01.581366Z","end":"2026-05-11T11:38:01.81472Z","steps":["trace[2145965948] 'range keys from in-memory index tree' (duration: 232.936859ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:01.815653Z","caller":"traceutil/trace.go:171","msg":"trace[500929366] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"159.836202ms","start":"2026-05-11T11:38:01.655781Z","end":"2026-05-11T11:38:01.815617Z","steps":["trace[500929366] 'process raft request' (duration: 159.623388ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:01.844358Z","caller":"traceutil/trace.go:171","msg":"trace[1938444828] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"186.504571ms","start":"2026-05-11T11:38:01.657827Z","end":"2026-05-11T11:38:01.844332Z","steps":["trace[1938444828] 'process raft request' (duration: 186.369908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:02.070512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.48337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:38:02.070746Z","caller":"traceutil/trace.go:171","msg":"trace[1099079871] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"252.160319ms","start":"2026-05-11T11:38:01.818555Z","end":"2026-05-11T11:38:02.070716Z","steps":["trace[1099079871] 'process raft request' (duration: 119.402624ms)","trace[1099079871] 'compare' (duration: 132.118204ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:02.070825Z","caller":"traceutil/trace.go:171","msg":"trace[1880498153] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"201.833948ms","start":"2026-05-11T11:38:01.868972Z","end":"2026-05-11T11:38:02.070806Z","steps":["trace[1880498153] 'process raft request' (duration: 201.795277ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:02.070945Z","caller":"traceutil/trace.go:171","msg":"trace[2146927945] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"251.273432ms","start":"2026-05-11T11:38:01.819664Z","end":"2026-05-11T11:38:02.070938Z","steps":["trace[2146927945] 'process raft request' (duration: 250.929956ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:02.071024Z","caller":"traceutil/trace.go:171","msg":"trace[1864730469] linearizableReadLoop","detail":"{readStateIndex:26305; appliedIndex:26302; }","duration":"243.59729ms","start":"2026-05-11T11:38:01.827417Z","end":"2026-05-11T11:38:02.071014Z","steps":["trace[1864730469] 'read index received' (duration: 16.882206ms)","trace[1864730469] 'applied index is now lower than readState.Index' (duration: 226.714194ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:02.071088Z","caller":"traceutil/trace.go:171","msg":"trace[32664148] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"210.854046ms","start":"2026-05-11T11:38:01.860226Z","end":"2026-05-11T11:38:02.07108Z","steps":["trace[32664148] 'process raft request' (duration: 210.436918ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:02.071171Z","caller":"traceutil/trace.go:171","msg":"trace[1033652587] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"202.617321ms","start":"2026-05-11T11:38:01.868545Z","end":"2026-05-11T11:38:02.071162Z","steps":["trace[1033652587] 'process raft request' (duration: 202.180923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:02.071317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.894505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-11T11:38:02.071349Z","caller":"traceutil/trace.go:171","msg":"trace[1284976844] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:25470; }","duration":"243.947506ms","start":"2026-05-11T11:38:01.82739Z","end":"2026-05-11T11:38:02.071338Z","steps":["trace[1284976844] 'agreement among raft nodes before linearized reading' (duration: 243.837143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:02.071407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.22234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T11:38:02.071457Z","caller":"traceutil/trace.go:171","msg":"trace[1133760085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25470; }","duration":"206.300202ms","start":"2026-05-11T11:38:01.865145Z","end":"2026-05-11T11:38:02.071445Z","steps":["trace[1133760085] 'agreement among raft nodes before linearized reading' (duration: 206.157409ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:02.304765Z","caller":"traceutil/trace.go:171","msg":"trace[1310978727] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"227.844325ms","start":"2026-05-11T11:38:02.076888Z","end":"2026-05-11T11:38:02.304732Z","steps":["trace[1310978727] 'process raft request' (duration: 227.524409ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:02.304766Z","caller":"traceutil/trace.go:171","msg":"trace[392769973] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"227.855955ms","start":"2026-05-11T11:38:02.076888Z","end":"2026-05-11T11:38:02.304744Z","steps":["trace[392769973] 'process raft request' (duration: 227.796764ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:02.304949Z","caller":"traceutil/trace.go:171","msg":"trace[612413889] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"159.493215ms","start":"2026-05-11T11:38:02.145437Z","end":"2026-05-11T11:38:02.30493Z","steps":["trace[612413889] 'process raft request' (duration: 159.278631ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:02.317254Z","caller":"traceutil/trace.go:171","msg":"trace[32346974] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"156.397957ms","start":"2026-05-11T11:38:02.160822Z","end":"2026-05-11T11:38:02.31722Z","steps":["trace[32346974] 'process raft request' (duration: 156.218744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:07.429829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.42143ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-11T11:38:07.430288Z","caller":"traceutil/trace.go:171","msg":"trace[533126845] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"200.060694ms","start":"2026-05-11T11:38:07.2302Z","end":"2026-05-11T11:38:07.430261Z","steps":["trace[533126845] 'process raft request' (duration: 199.741248ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:07.430295Z","caller":"traceutil/trace.go:171","msg":"trace[1205010644] linearizableReadLoop","detail":"{readStateIndex:26343; appliedIndex:26341; }","duration":"111.892364ms","start":"2026-05-11T11:38:07.318376Z","end":"2026-05-11T11:38:07.430268Z","steps":["trace[1205010644] 'read index received' (duration: 69.903029ms)","trace[1205010644] 'applied index is now lower than readState.Index' (duration: 41.983715ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:07.665789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.323761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:07.665918Z","caller":"traceutil/trace.go:171","msg":"trace[1501747648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25503; }","duration":"346.490304ms","start":"2026-05-11T11:38:07.3194Z","end":"2026-05-11T11:38:07.66589Z","steps":["trace[1501747648] 'agreement among raft nodes before linearized reading' (duration: 111.087728ms)","trace[1501747648] 'range keys from in-memory index tree' (duration: 235.221973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:07.665968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:07.319388Z","time spent":"346.570276ms","remote":"127.0.0.1:51196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T11:38:07.666315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.904652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:07.666431Z","caller":"traceutil/trace.go:171","msg":"trace[1175955214] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:25503; }","duration":"348.073645ms","start":"2026-05-11T11:38:07.318324Z","end":"2026-05-11T11:38:07.666397Z","steps":["trace[1175955214] 'agreement among raft nodes before linearized reading' (duration: 112.113268ms)","trace[1175955214] 'count revisions from in-memory index tree' (duration: 235.791912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:07.666495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:07.318298Z","time spent":"348.178177ms","remote":"127.0.0.1:57326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:38:07.668873Z","caller":"traceutil/trace.go:171","msg":"trace[968577705] linearizableReadLoop","detail":"{readStateIndex:26344; appliedIndex:26343; }","duration":"170.935399ms","start":"2026-05-11T11:38:07.497912Z","end":"2026-05-11T11:38:07.668848Z","steps":["trace[968577705] 'read index received' (duration: 170.634434ms)","trace[968577705] 'applied index is now lower than readState.Index' (duration: 299.875µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:07.668983Z","caller":"traceutil/trace.go:171","msg":"trace[79886051] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"174.869471ms","start":"2026-05-11T11:38:07.494089Z","end":"2026-05-11T11:38:07.668958Z","steps":["trace[79886051] 'process raft request' (duration: 174.515295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:07.669173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.228215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T11:38:07.669256Z","caller":"traceutil/trace.go:171","msg":"trace[120978053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25504; }","duration":"171.368247ms","start":"2026-05-11T11:38:07.497874Z","end":"2026-05-11T11:38:07.669242Z","steps":["trace[120978053] 'agreement among raft nodes before linearized reading' (duration: 171.129592ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:07.976439Z","caller":"traceutil/trace.go:171","msg":"trace[1583643996] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"136.005595ms","start":"2026-05-11T11:38:07.84041Z","end":"2026-05-11T11:38:07.976415Z","steps":["trace[1583643996] 'process raft request' (duration: 135.804621ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:08.099941Z","caller":"traceutil/trace.go:171","msg":"trace[869868854] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"232.036722ms","start":"2026-05-11T11:38:07.86788Z","end":"2026-05-11T11:38:08.099917Z","steps":["trace[869868854] 'process raft request' (duration: 229.698489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:08.100143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.517876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:08.100258Z","caller":"traceutil/trace.go:171","msg":"trace[1114487827] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25507; }","duration":"197.68514ms","start":"2026-05-11T11:38:07.902533Z","end":"2026-05-11T11:38:08.100219Z","steps":["trace[1114487827] 'agreement among raft nodes before linearized reading' (duration: 197.480686ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:08.100319Z","caller":"traceutil/trace.go:171","msg":"trace[1402630662] linearizableReadLoop","detail":"{readStateIndex:26347; appliedIndex:26345; }","duration":"197.290472ms","start":"2026-05-11T11:38:07.902588Z","end":"2026-05-11T11:38:08.099879Z","steps":["trace[1402630662] 'read index received' (duration: 73.632189ms)","trace[1402630662] 'applied index is now lower than readState.Index' (duration: 123.657023ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:08.364354Z","caller":"traceutil/trace.go:171","msg":"trace[1090702884] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"175.927702ms","start":"2026-05-11T11:38:08.187909Z","end":"2026-05-11T11:38:08.363837Z","steps":["trace[1090702884] 'process raft request' (duration: 143.961014ms)","trace[1090702884] 'compare' (duration: 31.857296ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:08.364627Z","caller":"traceutil/trace.go:171","msg":"trace[920395660] linearizableReadLoop","detail":"{readStateIndex:26353; appliedIndex:26351; }","duration":"118.626589ms","start":"2026-05-11T11:38:08.24597Z","end":"2026-05-11T11:38:08.364597Z","steps":["trace[920395660] 'read index received' (duration: 85.951778ms)","trace[920395660] 'applied index is now lower than readState.Index' (duration: 32.673551ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:08.364863Z","caller":"traceutil/trace.go:171","msg":"trace[998010461] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"175.83432ms","start":"2026-05-11T11:38:08.188922Z","end":"2026-05-11T11:38:08.364757Z","steps":["trace[998010461] 'process raft request' (duration: 175.417502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:08.364933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.951786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-11T11:38:08.364975Z","caller":"traceutil/trace.go:171","msg":"trace[590299612] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25513; }","duration":"119.030898ms","start":"2026-05-11T11:38:08.245933Z","end":"2026-05-11T11:38:08.364964Z","steps":["trace[590299612] 'agreement among raft nodes before linearized reading' (duration: 118.848403ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:08.674384Z","caller":"traceutil/trace.go:171","msg":"trace[315650899] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"305.165871ms","start":"2026-05-11T11:38:08.369196Z","end":"2026-05-11T11:38:08.674362Z","steps":["trace[315650899] 'process raft request' (duration: 208.40341ms)","trace[315650899] 'compare' (duration: 96.281152ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:08.674526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:08.369171Z","time spent":"305.271214ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:08.677492Z","caller":"traceutil/trace.go:171","msg":"trace[1633435625] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"308.252519ms","start":"2026-05-11T11:38:08.369228Z","end":"2026-05-11T11:38:08.677481Z","steps":["trace[1633435625] 'process raft request' (duration: 307.912302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:08.677562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:08.369207Z","time spent":"308.31403ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:38:08.677946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.087436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-11T11:38:08.678165Z","caller":"traceutil/trace.go:171","msg":"trace[1792403730] linearizableReadLoop","detail":"{readStateIndex:26356; appliedIndex:26354; }","duration":"307.635868ms","start":"2026-05-11T11:38:08.369835Z","end":"2026-05-11T11:38:08.67747Z","steps":["trace[1792403730] 'read index received' (duration: 207.779309ms)","trace[1792403730] 'applied index is now lower than readState.Index' (duration: 99.855249ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:08.678247Z","caller":"traceutil/trace.go:171","msg":"trace[1749259345] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25516; }","duration":"308.32871ms","start":"2026-05-11T11:38:08.369812Z","end":"2026-05-11T11:38:08.67814Z","steps":["trace[1749259345] 'agreement among raft nodes before linearized reading' (duration: 307.849192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:08.678484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.433967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:08.678565Z","caller":"traceutil/trace.go:171","msg":"trace[933722932] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:25516; }","duration":"141.56794ms","start":"2026-05-11T11:38:08.536983Z","end":"2026-05-11T11:38:08.678551Z","steps":["trace[933722932] 'agreement among raft nodes before linearized reading' (duration: 141.448368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:08.678629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:08.369802Z","time spent":"308.730817ms","remote":"127.0.0.1:51380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18643,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-11T11:38:09.535922Z","caller":"traceutil/trace.go:171","msg":"trace[1932760834] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"100.565703ms","start":"2026-05-11T11:38:09.435331Z","end":"2026-05-11T11:38:09.535897Z","steps":["trace[1932760834] 'process raft request' (duration: 99.991291ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:09.832294Z","caller":"traceutil/trace.go:171","msg":"trace[323362815] linearizableReadLoop","detail":"{readStateIndex:26358; appliedIndex:26357; }","duration":"150.338175ms","start":"2026-05-11T11:38:09.681931Z","end":"2026-05-11T11:38:09.832269Z","steps":["trace[323362815] 'read index received' (duration: 150.11934ms)","trace[323362815] 'applied index is now lower than readState.Index' (duration: 217.855µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:09.832522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.574989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T11:38:09.832566Z","caller":"traceutil/trace.go:171","msg":"trace[1811141576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25518; }","duration":"150.65288ms","start":"2026-05-11T11:38:09.6819Z","end":"2026-05-11T11:38:09.832553Z","steps":["trace[1811141576] 'agreement among raft nodes before linearized reading' (duration: 150.463947ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:09.832786Z","caller":"traceutil/trace.go:171","msg":"trace[279748301] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"158.15764ms","start":"2026-05-11T11:38:09.67459Z","end":"2026-05-11T11:38:09.832748Z","steps":["trace[279748301] 'process raft request' (duration: 157.524718ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.262935Z","caller":"traceutil/trace.go:171","msg":"trace[1293834610] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"281.232624ms","start":"2026-05-11T11:38:09.98168Z","end":"2026-05-11T11:38:10.262912Z","steps":["trace[1293834610] 'process raft request' (duration: 281.076371ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.26296Z","caller":"traceutil/trace.go:171","msg":"trace[1637926699] linearizableReadLoop","detail":"{readStateIndex:26360; appliedIndex:26359; }","duration":"266.08544ms","start":"2026-05-11T11:38:09.996821Z","end":"2026-05-11T11:38:10.262907Z","steps":["trace[1637926699] 'read index received' (duration: 266.07222ms)","trace[1637926699] 'applied index is now lower than readState.Index' (duration: 11.04µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:10.263134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.275773ms","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-05-11T11:38:10.263203Z","caller":"traceutil/trace.go:171","msg":"trace[1308172257] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25520; }","duration":"266.411346ms","start":"2026-05-11T11:38:09.996778Z","end":"2026-05-11T11:38:10.263189Z","steps":["trace[1308172257] 'agreement among raft nodes before linearized reading' (duration: 266.256883ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.336165Z","caller":"traceutil/trace.go:171","msg":"trace[1719622121] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"228.342483ms","start":"2026-05-11T11:38:10.107799Z","end":"2026-05-11T11:38:10.336141Z","steps":["trace[1719622121] 'process raft request' (duration: 227.520268ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.336294Z","caller":"traceutil/trace.go:171","msg":"trace[1479504279] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"148.626442ms","start":"2026-05-11T11:38:10.187658Z","end":"2026-05-11T11:38:10.336285Z","steps":["trace[1479504279] 'process raft request' (duration: 147.935339ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.336377Z","caller":"traceutil/trace.go:171","msg":"trace[1844891045] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"148.611402ms","start":"2026-05-11T11:38:10.187756Z","end":"2026-05-11T11:38:10.336367Z","steps":["trace[1844891045] 'process raft request' (duration: 147.901638ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.336464Z","caller":"traceutil/trace.go:171","msg":"trace[1295002020] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"146.081515ms","start":"2026-05-11T11:38:10.190374Z","end":"2026-05-11T11:38:10.336456Z","steps":["trace[1295002020] 'process raft request' (duration: 145.32271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:10.564232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.271523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T11:38:10.564313Z","caller":"traceutil/trace.go:171","msg":"trace[1732077596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25524; }","duration":"196.402085ms","start":"2026-05-11T11:38:10.367894Z","end":"2026-05-11T11:38:10.564296Z","steps":["trace[1732077596] 'range keys from in-memory index tree' (duration: 196.08794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:10.565032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.037538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T11:38:10.565077Z","caller":"traceutil/trace.go:171","msg":"trace[511852625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25524; }","duration":"196.11519ms","start":"2026-05-11T11:38:10.368951Z","end":"2026-05-11T11:38:10.565066Z","steps":["trace[511852625] 'range keys from in-memory index tree' (duration: 195.501718ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.58911Z","caller":"traceutil/trace.go:171","msg":"trace[1053105394] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"219.138411ms","start":"2026-05-11T11:38:10.369956Z","end":"2026-05-11T11:38:10.589094Z","steps":["trace[1053105394] 'process raft request' (duration: 219.004858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:10.781151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.772716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T11:38:10.781231Z","caller":"traceutil/trace.go:171","msg":"trace[1126732507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25527; }","duration":"100.893039ms","start":"2026-05-11T11:38:10.68032Z","end":"2026-05-11T11:38:10.781213Z","steps":["trace[1126732507] 'range keys from in-memory index tree' (duration: 100.642544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:10.781342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.531028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T11:38:10.781445Z","caller":"traceutil/trace.go:171","msg":"trace[1812853398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25527; }","duration":"102.837884ms","start":"2026-05-11T11:38:10.678575Z","end":"2026-05-11T11:38:10.781413Z","steps":["trace[1812853398] 'range keys from in-memory index tree' (duration: 102.323435ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:10.909712Z","caller":"traceutil/trace.go:171","msg":"trace[1701540491] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"124.386808ms","start":"2026-05-11T11:38:10.785301Z","end":"2026-05-11T11:38:10.909688Z","steps":["trace[1701540491] 'process raft request' (duration: 124.241415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:11.178562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.556929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:38:11.178817Z","caller":"traceutil/trace.go:171","msg":"trace[509926503] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"393.291221ms","start":"2026-05-11T11:38:10.785486Z","end":"2026-05-11T11:38:11.178777Z","steps":["trace[509926503] 'process raft request' (duration: 274.424657ms)","trace[509926503] 'compare' (duration: 118.375655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:11.179042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:10.785467Z","time spent":"393.440864ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:11.875411Z","caller":"traceutil/trace.go:171","msg":"trace[1320640404] linearizableReadLoop","detail":"{readStateIndex:26371; appliedIndex:26370; }","duration":"313.518388ms","start":"2026-05-11T11:38:11.561868Z","end":"2026-05-11T11:38:11.875386Z","steps":["trace[1320640404] 'read index received' (duration: 313.445527ms)","trace[1320640404] 'applied index is now lower than readState.Index' (duration: 71.871µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:11.875526Z","caller":"traceutil/trace.go:171","msg":"trace[1019332225] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"324.239289ms","start":"2026-05-11T11:38:11.551275Z","end":"2026-05-11T11:38:11.875514Z","steps":["trace[1019332225] 'process raft request' (duration: 323.952844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:11.875677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:11.551246Z","time spent":"324.328041ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:38:11.875845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.944676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-11T11:38:11.875985Z","caller":"traceutil/trace.go:171","msg":"trace[1103835223] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25531; }","duration":"314.143579ms","start":"2026-05-11T11:38:11.561818Z","end":"2026-05-11T11:38:11.875962Z","steps":["trace[1103835223] 'agreement among raft nodes before linearized reading' (duration: 313.776532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:11.876104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:11.561797Z","time spent":"314.283822ms","remote":"127.0.0.1:51506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":183,"response size":32,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-11T11:38:12.149936Z","caller":"traceutil/trace.go:171","msg":"trace[2067088124] linearizableReadLoop","detail":"{readStateIndex:26373; appliedIndex:26371; }","duration":"274.440937ms","start":"2026-05-11T11:38:11.875479Z","end":"2026-05-11T11:38:12.14992Z","steps":["trace[2067088124] 'read index received' (duration: 254.717968ms)","trace[2067088124] 'applied index is now lower than readState.Index' (duration: 19.722389ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:12.149963Z","caller":"traceutil/trace.go:171","msg":"trace[1383119243] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"311.092142ms","start":"2026-05-11T11:38:11.838847Z","end":"2026-05-11T11:38:12.14994Z","steps":["trace[1383119243] 'process raft request' (duration: 311.043251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:12.150069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:11.838819Z","time spent":"311.186564ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:12.150037Z","caller":"traceutil/trace.go:171","msg":"trace[1551478314] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"587.472266ms","start":"2026-05-11T11:38:11.562519Z","end":"2026-05-11T11:38:12.149992Z","steps":["trace[1551478314] 'process raft request' (duration: 567.776447ms)","trace[1551478314] 'compare' (duration: 19.494835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:12.150176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.546587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-11T11:38:12.150187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.51409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T11:38:12.150226Z","caller":"traceutil/trace.go:171","msg":"trace[1908534092] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:25533; }","duration":"322.651859ms","start":"2026-05-11T11:38:11.82756Z","end":"2026-05-11T11:38:12.150212Z","steps":["trace[1908534092] 'agreement among raft nodes before linearized reading' (duration: 322.419305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:12.150261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:11.827536Z","time spent":"322.71716ms","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"info","ts":"2026-05-11T11:38:12.150267Z","caller":"traceutil/trace.go:171","msg":"trace[1855192564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25533; }","duration":"280.654072ms","start":"2026-05-11T11:38:11.869591Z","end":"2026-05-11T11:38:12.150245Z","steps":["trace[1855192564] 'agreement among raft nodes before linearized reading' (duration: 280.346607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:12.150261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:11.562491Z","time spent":"587.666759ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:12.333557Z","caller":"traceutil/trace.go:171","msg":"trace[1406160223] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"179.722103ms","start":"2026-05-11T11:38:12.15381Z","end":"2026-05-11T11:38:12.333532Z","steps":["trace[1406160223] 'process raft request' (duration: 179.56867ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:12.639184Z","caller":"traceutil/trace.go:171","msg":"trace[1262900283] linearizableReadLoop","detail":"{readStateIndex:26377; appliedIndex:26376; }","duration":"304.608221ms","start":"2026-05-11T11:38:12.334545Z","end":"2026-05-11T11:38:12.639153Z","steps":["trace[1262900283] 'read index received' (duration: 207.034365ms)","trace[1262900283] 'applied index is now lower than readState.Index' (duration: 97.572636ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:12.639289Z","caller":"traceutil/trace.go:171","msg":"trace[1095289704] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"304.739653ms","start":"2026-05-11T11:38:12.334478Z","end":"2026-05-11T11:38:12.639218Z","steps":["trace[1095289704] 'process raft request' (duration: 207.024244ms)","trace[1095289704] 'compare' (duration: 97.495835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:12.639589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:12.334461Z","time spent":"304.968457ms","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:38:12.639836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.261333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:12.63992Z","caller":"traceutil/trace.go:171","msg":"trace[1426806252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25536; }","duration":"305.381515ms","start":"2026-05-11T11:38:12.334518Z","end":"2026-05-11T11:38:12.6399Z","steps":["trace[1426806252] 'agreement among raft nodes before linearized reading' (duration: 304.764943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:12.640012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:12.334502Z","time spent":"305.456916ms","remote":"127.0.0.1:51204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T11:38:12.672618Z","caller":"traceutil/trace.go:171","msg":"trace[434460523] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"332.078645ms","start":"2026-05-11T11:38:12.340516Z","end":"2026-05-11T11:38:12.672595Z","steps":["trace[434460523] 'process raft request' (duration: 331.859361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:12.672828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:12.3405Z","time spent":"332.220567ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:12.673042Z","caller":"traceutil/trace.go:171","msg":"trace[2088775481] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"332.303079ms","start":"2026-05-11T11:38:12.340708Z","end":"2026-05-11T11:38:12.673011Z","steps":["trace[2088775481] 'process raft request' (duration: 331.820991ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:12.673141Z","caller":"traceutil/trace.go:171","msg":"trace[1372880129] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"320.764213ms","start":"2026-05-11T11:38:12.352351Z","end":"2026-05-11T11:38:12.673115Z","steps":["trace[1372880129] 'process raft request' (duration: 320.664141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:12.673182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:12.340699Z","time spent":"332.413581ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:12.673182Z","caller":"traceutil/trace.go:171","msg":"trace[3471931] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"332.285379ms","start":"2026-05-11T11:38:12.340811Z","end":"2026-05-11T11:38:12.673096Z","steps":["trace[3471931] 'process raft request' (duration: 332.083455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:12.673307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:12.352326Z","time spent":"320.890067ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T11:38:12.673499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:12.340791Z","time spent":"332.556664ms","remote":"127.0.0.1:51440","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-05-11T11:38:12.899991Z","caller":"traceutil/trace.go:171","msg":"trace[152474064] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"222.343711ms","start":"2026-05-11T11:38:12.677614Z","end":"2026-05-11T11:38:12.899957Z","steps":["trace[152474064] 'process raft request' (duration: 196.516897ms)","trace[152474064] 'compare' (duration: 25.436246ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:12.900073Z","caller":"traceutil/trace.go:171","msg":"trace[1863527772] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"220.160291ms","start":"2026-05-11T11:38:12.679884Z","end":"2026-05-11T11:38:12.900044Z","steps":["trace[1863527772] 'process raft request' (duration: 219.972547ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:12.899965Z","caller":"traceutil/trace.go:171","msg":"trace[676818531] linearizableReadLoop","detail":"{readStateIndex:26383; appliedIndex:26382; }","duration":"221.159838ms","start":"2026-05-11T11:38:12.678762Z","end":"2026-05-11T11:38:12.899921Z","steps":["trace[676818531] 'read index received' (duration: 195.360365ms)","trace[676818531] 'applied index is now lower than readState.Index' (duration: 25.797193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:12.900305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.502785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T11:38:12.900377Z","caller":"traceutil/trace.go:171","msg":"trace[316336332] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25543; }","duration":"221.630929ms","start":"2026-05-11T11:38:12.678729Z","end":"2026-05-11T11:38:12.90036Z","steps":["trace[316336332] 'agreement among raft nodes before linearized reading' (duration: 221.319833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.134498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.039248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-052c3749-7337-480d-b6db-3db6aee79d20\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-11T11:38:19.134583Z","caller":"traceutil/trace.go:171","msg":"trace[1036222246] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-052c3749-7337-480d-b6db-3db6aee79d20; range_end:; response_count:1; response_revision:25643; }","duration":"221.18808ms","start":"2026-05-11T11:38:18.913375Z","end":"2026-05-11T11:38:19.134563Z","steps":["trace[1036222246] 'range keys from in-memory index tree' (duration: 220.842823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.134597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.001518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T11:38:19.134675Z","caller":"traceutil/trace.go:171","msg":"trace[402194777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25643; }","duration":"212.105459ms","start":"2026-05-11T11:38:18.922551Z","end":"2026-05-11T11:38:19.134657Z","steps":["trace[402194777] 'range keys from in-memory index tree' (duration: 211.860335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.134862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.350059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:19.134916Z","caller":"traceutil/trace.go:171","msg":"trace[295323123] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25643; }","duration":"240.441001ms","start":"2026-05-11T11:38:18.894464Z","end":"2026-05-11T11:38:19.134905Z","steps":["trace[295323123] 'range keys from in-memory index tree' (duration: 240.282638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.13492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.247863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-11T11:38:19.135117Z","caller":"traceutil/trace.go:171","msg":"trace[122138729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25643; }","duration":"212.470647ms","start":"2026-05-11T11:38:18.922622Z","end":"2026-05-11T11:38:19.135093Z","steps":["trace[122138729] 'range keys from in-memory index tree' (duration: 212.121471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.135278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.94219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T11:38:19.135319Z","caller":"traceutil/trace.go:171","msg":"trace[875085622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25643; }","duration":"163.010682ms","start":"2026-05-11T11:38:18.972299Z","end":"2026-05-11T11:38:19.13531Z","steps":["trace[875085622] 'range keys from in-memory index tree' (duration: 162.834648ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:19.244882Z","caller":"traceutil/trace.go:171","msg":"trace[1856068176] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"104.977524ms","start":"2026-05-11T11:38:19.139618Z","end":"2026-05-11T11:38:19.244595Z","steps":["trace[1856068176] 'process raft request' (duration: 104.76834ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:19.267788Z","caller":"traceutil/trace.go:171","msg":"trace[1604140845] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"127.774102ms","start":"2026-05-11T11:38:19.140004Z","end":"2026-05-11T11:38:19.267778Z","steps":["trace[1604140845] 'process raft request' (duration: 127.615569ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:19.267992Z","caller":"traceutil/trace.go:171","msg":"trace[648241147] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"126.835494ms","start":"2026-05-11T11:38:19.141139Z","end":"2026-05-11T11:38:19.267974Z","steps":["trace[648241147] 'process raft request' (duration: 126.582028ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:19.26809Z","caller":"traceutil/trace.go:171","msg":"trace[1250988258] linearizableReadLoop","detail":"{readStateIndex:26487; appliedIndex:26485; }","duration":"127.14433ms","start":"2026-05-11T11:38:19.140933Z","end":"2026-05-11T11:38:19.268078Z","steps":["trace[1250988258] 'read index received' (duration: 103.589949ms)","trace[1250988258] 'applied index is now lower than readState.Index' (duration: 23.228234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:19.268242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.296142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-11T11:38:19.268271Z","caller":"traceutil/trace.go:171","msg":"trace[1310767147] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25646; }","duration":"127.371794ms","start":"2026-05-11T11:38:19.14089Z","end":"2026-05-11T11:38:19.268262Z","steps":["trace[1310767147] 'agreement among raft nodes before linearized reading' (duration: 127.248402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.268571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.48733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:38:19.268818Z","caller":"traceutil/trace.go:171","msg":"trace[1535625723] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25646; }","duration":"124.735144ms","start":"2026-05-11T11:38:19.144073Z","end":"2026-05-11T11:38:19.268808Z","steps":["trace[1535625723] 'agreement among raft nodes before linearized reading' (duration: 124.469669ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:19.448475Z","caller":"traceutil/trace.go:171","msg":"trace[202489480] linearizableReadLoop","detail":"{readStateIndex:26489; appliedIndex:26488; }","duration":"165.827744ms","start":"2026-05-11T11:38:19.282626Z","end":"2026-05-11T11:38:19.448453Z","steps":["trace[202489480] 'read index received' (duration: 87.342284ms)","trace[202489480] 'applied index is now lower than readState.Index' (duration: 78.48463ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:19.448539Z","caller":"traceutil/trace.go:171","msg":"trace[610475028] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"175.77967ms","start":"2026-05-11T11:38:19.272728Z","end":"2026-05-11T11:38:19.448507Z","steps":["trace[610475028] 'process raft request' (duration: 97.210369ms)","trace[610475028] 'compare' (duration: 78.398767ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:19.448689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.052899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-05-11T11:38:19.44869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.852116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:19.448729Z","caller":"traceutil/trace.go:171","msg":"trace[113374974] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25647; }","duration":"166.14084ms","start":"2026-05-11T11:38:19.282575Z","end":"2026-05-11T11:38:19.448716Z","steps":["trace[113374974] 'agreement among raft nodes before linearized reading' (duration: 166.006947ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:19.448735Z","caller":"traceutil/trace.go:171","msg":"trace[1643694472] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25647; }","duration":"141.935867ms","start":"2026-05-11T11:38:19.306788Z","end":"2026-05-11T11:38:19.448724Z","steps":["trace[1643694472] 'agreement among raft nodes before linearized reading' (duration: 141.857216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.448793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.612765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:19.448879Z","caller":"traceutil/trace.go:171","msg":"trace[1743096790] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25647; }","duration":"123.714136ms","start":"2026-05-11T11:38:19.325143Z","end":"2026-05-11T11:38:19.448858Z","steps":["trace[1743096790] 'agreement among raft nodes before linearized reading' (duration: 123.563574ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:19.946087Z","caller":"traceutil/trace.go:171","msg":"trace[1796213523] linearizableReadLoop","detail":"{readStateIndex:26490; appliedIndex:26489; }","duration":"162.930659ms","start":"2026-05-11T11:38:19.783137Z","end":"2026-05-11T11:38:19.946068Z","steps":["trace[1796213523] 'read index received' (duration: 162.727275ms)","trace[1796213523] 'applied index is now lower than readState.Index' (duration: 202.484µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:19.946325Z","caller":"traceutil/trace.go:171","msg":"trace[1639013213] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"212.033448ms","start":"2026-05-11T11:38:19.734265Z","end":"2026-05-11T11:38:19.946299Z","steps":["trace[1639013213] 'process raft request' (duration: 211.662541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:19.946418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.256875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:38:19.946467Z","caller":"traceutil/trace.go:171","msg":"trace[1113714530] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25648; }","duration":"163.367657ms","start":"2026-05-11T11:38:19.783088Z","end":"2026-05-11T11:38:19.946455Z","steps":["trace[1113714530] 'agreement among raft nodes before linearized reading' (duration: 163.217904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:26.605493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.698193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T11:38:26.605617Z","caller":"traceutil/trace.go:171","msg":"trace[796083503] linearizableReadLoop","detail":"{readStateIndex:26565; appliedIndex:26564; }","duration":"215.142475ms","start":"2026-05-11T11:38:26.390456Z","end":"2026-05-11T11:38:26.605598Z","steps":["trace[796083503] 'read index received' (duration: 58.201308ms)","trace[796083503] 'applied index is now lower than readState.Index' (duration: 156.939817ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:26.605762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.302669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T11:38:26.605766Z","caller":"traceutil/trace.go:171","msg":"trace[684553822] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"217.910847ms","start":"2026-05-11T11:38:26.387814Z","end":"2026-05-11T11:38:26.605725Z","steps":["trace[684553822] 'process raft request' (duration: 60.889609ms)","trace[684553822] 'compare' (duration: 156.5555ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:26.605803Z","caller":"traceutil/trace.go:171","msg":"trace[608608428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25722; }","duration":"215.3795ms","start":"2026-05-11T11:38:26.39041Z","end":"2026-05-11T11:38:26.60579Z","steps":["trace[608608428] 'agreement among raft nodes before linearized reading' (duration: 215.235487ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:26.815247Z","caller":"traceutil/trace.go:171","msg":"trace[174000718] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"205.179281ms","start":"2026-05-11T11:38:26.610029Z","end":"2026-05-11T11:38:26.815208Z","steps":["trace[174000718] 'process raft request' (duration: 204.970277ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:26.931747Z","caller":"traceutil/trace.go:171","msg":"trace[815773359] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"212.128559ms","start":"2026-05-11T11:38:26.719599Z","end":"2026-05-11T11:38:26.931728Z","steps":["trace[815773359] 'process raft request' (duration: 211.963576ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:26.932156Z","caller":"traceutil/trace.go:171","msg":"trace[2078719132] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"205.18276ms","start":"2026-05-11T11:38:26.726942Z","end":"2026-05-11T11:38:26.932124Z","steps":["trace[2078719132] 'process raft request' (duration: 205.104728ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:26.932265Z","caller":"traceutil/trace.go:171","msg":"trace[1945289611] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"207.809639ms","start":"2026-05-11T11:38:26.724438Z","end":"2026-05-11T11:38:26.932248Z","steps":["trace[1945289611] 'process raft request' (duration: 207.254698ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:26.932292Z","caller":"traceutil/trace.go:171","msg":"trace[1159384457] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"206.590817ms","start":"2026-05-11T11:38:26.725689Z","end":"2026-05-11T11:38:26.93228Z","steps":["trace[1159384457] 'process raft request' (duration: 206.23986ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:26.932388Z","caller":"traceutil/trace.go:171","msg":"trace[1943860725] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"204.328014ms","start":"2026-05-11T11:38:26.728052Z","end":"2026-05-11T11:38:26.93238Z","steps":["trace[1943860725] 'process raft request' (duration: 204.037839ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:40.190487Z","caller":"traceutil/trace.go:171","msg":"trace[830595284] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"153.30393ms","start":"2026-05-11T11:38:40.037155Z","end":"2026-05-11T11:38:40.190459Z","steps":["trace[830595284] 'process raft request' (duration: 153.151907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:40.465696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.934653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:40.465761Z","caller":"traceutil/trace.go:171","msg":"trace[1276719043] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25829; }","duration":"199.071925ms","start":"2026-05-11T11:38:40.266675Z","end":"2026-05-11T11:38:40.465747Z","steps":["trace[1276719043] 'count revisions from in-memory index tree' (duration: 198.75227ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:40.466482Z","caller":"traceutil/trace.go:171","msg":"trace[1609077506] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"115.42761ms","start":"2026-05-11T11:38:40.351033Z","end":"2026-05-11T11:38:40.46646Z","steps":["trace[1609077506] 'process raft request' (duration: 18.14827ms)","trace[1609077506] 'compare' (duration: 96.554906ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:41.02351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.204359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T11:38:41.023612Z","caller":"traceutil/trace.go:171","msg":"trace[1767567353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25831; }","duration":"152.338172ms","start":"2026-05-11T11:38:40.87125Z","end":"2026-05-11T11:38:41.023588Z","steps":["trace[1767567353] 'range keys from in-memory index tree' (duration: 151.963364ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.172452Z","caller":"traceutil/trace.go:171","msg":"trace[571465109] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"190.708099ms","start":"2026-05-11T11:38:40.981719Z","end":"2026-05-11T11:38:41.172427Z","steps":["trace[571465109] 'process raft request' (duration: 190.628818ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.172474Z","caller":"traceutil/trace.go:171","msg":"trace[1946054129] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"192.689325ms","start":"2026-05-11T11:38:40.979754Z","end":"2026-05-11T11:38:41.172443Z","steps":["trace[1946054129] 'process raft request' (duration: 192.443401ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.172823Z","caller":"traceutil/trace.go:171","msg":"trace[856334629] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"187.871626ms","start":"2026-05-11T11:38:40.98493Z","end":"2026-05-11T11:38:41.172802Z","steps":["trace[856334629] 'process raft request' (duration: 187.751644ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.172843Z","caller":"traceutil/trace.go:171","msg":"trace[370374500] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"187.947888ms","start":"2026-05-11T11:38:40.984863Z","end":"2026-05-11T11:38:41.172811Z","steps":["trace[370374500] 'process raft request' (duration: 187.530531ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.172896Z","caller":"traceutil/trace.go:171","msg":"trace[237569344] linearizableReadLoop","detail":"{readStateIndex:26683; appliedIndex:26678; }","duration":"108.698755ms","start":"2026-05-11T11:38:41.06416Z","end":"2026-05-11T11:38:41.172859Z","steps":["trace[237569344] 'read index received' (duration: 108.054812ms)","trace[237569344] 'applied index is now lower than readState.Index' (duration: 642.663µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:41.172947Z","caller":"traceutil/trace.go:171","msg":"trace[1549234633] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"145.863801ms","start":"2026-05-11T11:38:41.027072Z","end":"2026-05-11T11:38:41.172936Z","steps":["trace[1549234633] 'process raft request' (duration: 145.695217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:41.173117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.946199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T11:38:41.17317Z","caller":"traceutil/trace.go:171","msg":"trace[2016130398] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25837; }","duration":"109.055181ms","start":"2026-05-11T11:38:41.064097Z","end":"2026-05-11T11:38:41.173152Z","steps":["trace[2016130398] 'agreement among raft nodes before linearized reading' (duration: 108.873988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:41.461778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.050489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-11T11:38:41.461803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.340954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:41.461857Z","caller":"traceutil/trace.go:171","msg":"trace[1185395483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25837; }","duration":"118.177652ms","start":"2026-05-11T11:38:41.343662Z","end":"2026-05-11T11:38:41.46184Z","steps":["trace[1185395483] 'range keys from in-memory index tree' (duration: 117.903977ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.461914Z","caller":"traceutil/trace.go:171","msg":"trace[1103819842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25837; }","duration":"143.488987ms","start":"2026-05-11T11:38:41.318386Z","end":"2026-05-11T11:38:41.461875Z","steps":["trace[1103819842] 'range keys from in-memory index tree' (duration: 143.22196ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.582573Z","caller":"traceutil/trace.go:171","msg":"trace[1401023142] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"116.604652ms","start":"2026-05-11T11:38:41.465941Z","end":"2026-05-11T11:38:41.582545Z","steps":["trace[1401023142] 'process raft request' (duration: 116.440259ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:41.779755Z","caller":"traceutil/trace.go:171","msg":"trace[789624401] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"313.333545ms","start":"2026-05-11T11:38:41.4664Z","end":"2026-05-11T11:38:41.779734Z","steps":["trace[789624401] 'process raft request' (duration: 313.181522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:41.779874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:41.466379Z","time spent":"313.429596ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:41.779861Z","caller":"traceutil/trace.go:171","msg":"trace[1866919697] linearizableReadLoop","detail":"{readStateIndex:26686; appliedIndex:26683; }","duration":"237.582367ms","start":"2026-05-11T11:38:41.542249Z","end":"2026-05-11T11:38:41.779832Z","steps":["trace[1866919697] 'read index received' (duration: 40.224763ms)","trace[1866919697] 'applied index is now lower than readState.Index' (duration: 197.355674ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:41.77996Z","caller":"traceutil/trace.go:171","msg":"trace[663823947] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"313.414706ms","start":"2026-05-11T11:38:41.466519Z","end":"2026-05-11T11:38:41.779934Z","steps":["trace[663823947] 'process raft request' (duration: 313.180342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:41.780072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.809421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T11:38:41.780106Z","caller":"traceutil/trace.go:171","msg":"trace[881544260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25840; }","duration":"237.906753ms","start":"2026-05-11T11:38:41.542189Z","end":"2026-05-11T11:38:41.780096Z","steps":["trace[881544260] 'agreement among raft nodes before linearized reading' (duration: 237.73709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:41.780282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:41.466483Z","time spent":"313.609569ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:41.781473Z","caller":"traceutil/trace.go:171","msg":"trace[1345767461] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"182.423814ms","start":"2026-05-11T11:38:41.599036Z","end":"2026-05-11T11:38:41.78146Z","steps":["trace[1345767461] 'process raft request' (duration: 182.333872ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:42.299485Z","caller":"traceutil/trace.go:171","msg":"trace[588351043] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"104.350262ms","start":"2026-05-11T11:38:42.195108Z","end":"2026-05-11T11:38:42.299459Z","steps":["trace[588351043] 'process raft request' (duration: 104.18806ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:42.634942Z","caller":"traceutil/trace.go:171","msg":"trace[941835696] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"420.446149ms","start":"2026-05-11T11:38:42.214468Z","end":"2026-05-11T11:38:42.634914Z","steps":["trace[941835696] 'process raft request' (duration: 328.199983ms)","trace[941835696] 'compare' (duration: 92.087493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:42.63508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T11:38:42.21444Z","time spent":"420.568832ms","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T11:38:42.679091Z","caller":"traceutil/trace.go:171","msg":"trace[1825829230] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"207.191648ms","start":"2026-05-11T11:38:42.471875Z","end":"2026-05-11T11:38:42.679066Z","steps":["trace[1825829230] 'process raft request' (duration: 207.044605ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:45.937475Z","caller":"traceutil/trace.go:171","msg":"trace[478588225] linearizableReadLoop","detail":"{readStateIndex:26732; appliedIndex:26731; }","duration":"138.141625ms","start":"2026-05-11T11:38:45.79931Z","end":"2026-05-11T11:38:45.937452Z","steps":["trace[478588225] 'read index received' (duration: 27.178908ms)","trace[478588225] 'applied index is now lower than readState.Index' (duration: 110.961777ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:45.937541Z","caller":"traceutil/trace.go:171","msg":"trace[1353615223] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"139.918849ms","start":"2026-05-11T11:38:45.797593Z","end":"2026-05-11T11:38:45.937512Z","steps":["trace[1353615223] 'process raft request' (duration: 71.141481ms)","trace[1353615223] 'compare' (duration: 68.542573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:45.937676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.35757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T11:38:45.9377Z","caller":"traceutil/trace.go:171","msg":"trace[143435881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25884; }","duration":"138.433381ms","start":"2026-05-11T11:38:45.799259Z","end":"2026-05-11T11:38:45.937692Z","steps":["trace[143435881] 'agreement among raft nodes before linearized reading' (duration: 138.272598ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:50.971575Z","caller":"traceutil/trace.go:171","msg":"trace[129943886] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"103.160291ms","start":"2026-05-11T11:38:50.868389Z","end":"2026-05-11T11:38:50.971549Z","steps":["trace[129943886] 'process raft request' (duration: 102.957088ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:53.108751Z","caller":"traceutil/trace.go:171","msg":"trace[418803748] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"132.316447ms","start":"2026-05-11T11:38:52.97641Z","end":"2026-05-11T11:38:53.108726Z","steps":["trace[418803748] 'process raft request' (duration: 132.169644ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:54.894011Z","caller":"traceutil/trace.go:171","msg":"trace[929011368] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"107.538371ms","start":"2026-05-11T11:38:54.786408Z","end":"2026-05-11T11:38:54.893947Z","steps":["trace[929011368] 'process raft request' (duration: 106.871459ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:56.206743Z","caller":"traceutil/trace.go:171","msg":"trace[1601317295] linearizableReadLoop","detail":"{readStateIndex:26805; appliedIndex:26804; }","duration":"192.83033ms","start":"2026-05-11T11:38:56.013886Z","end":"2026-05-11T11:38:56.206716Z","steps":["trace[1601317295] 'read index received' (duration: 129.941662ms)","trace[1601317295] 'applied index is now lower than readState.Index' (duration: 62.887578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:56.206971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.079654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-11T11:38:56.207013Z","caller":"traceutil/trace.go:171","msg":"trace[974112172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25955; }","duration":"193.178266ms","start":"2026-05-11T11:38:56.013824Z","end":"2026-05-11T11:38:56.207002Z","steps":["trace[974112172] 'agreement among raft nodes before linearized reading' (duration: 193.000553ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:56.207179Z","caller":"traceutil/trace.go:171","msg":"trace[447314965] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"204.051759ms","start":"2026-05-11T11:38:56.003103Z","end":"2026-05-11T11:38:56.207155Z","steps":["trace[447314965] 'process raft request' (duration: 140.691893ms)","trace[447314965] 'compare' (duration: 62.761375ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:57.359114Z","caller":"traceutil/trace.go:171","msg":"trace[395798703] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"127.611288ms","start":"2026-05-11T11:38:57.231473Z","end":"2026-05-11T11:38:57.359084Z","steps":["trace[395798703] 'process raft request' (duration: 120.556876ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:57.359448Z","caller":"traceutil/trace.go:171","msg":"trace[1201226381] linearizableReadLoop","detail":"{readStateIndex:26814; appliedIndex:26811; }","duration":"108.222015ms","start":"2026-05-11T11:38:57.251162Z","end":"2026-05-11T11:38:57.359384Z","steps":["trace[1201226381] 'read index received' (duration: 43.83307ms)","trace[1201226381] 'applied index is now lower than readState.Index' (duration: 64.386155ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:57.359489Z","caller":"traceutil/trace.go:171","msg":"trace[1243384535] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"123.832207ms","start":"2026-05-11T11:38:57.235622Z","end":"2026-05-11T11:38:57.359454Z","steps":["trace[1243384535] 'process raft request' (duration: 123.415669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:57.359732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.557851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T11:38:57.359795Z","caller":"traceutil/trace.go:171","msg":"trace[1050536415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25963; }","duration":"108.663264ms","start":"2026-05-11T11:38:57.251113Z","end":"2026-05-11T11:38:57.359776Z","steps":["trace[1050536415] 'agreement among raft nodes before linearized reading' (duration: 108.451909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:59.482586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.246148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:59.482659Z","caller":"traceutil/trace.go:171","msg":"trace[875908448] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25976; }","duration":"138.35353ms","start":"2026-05-11T11:38:59.344286Z","end":"2026-05-11T11:38:59.48264Z","steps":["trace[875908448] 'count revisions from in-memory index tree' (duration: 138.173877ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:59.482827Z","caller":"traceutil/trace.go:171","msg":"trace[1659756488] linearizableReadLoop","detail":"{readStateIndex:26828; appliedIndex:26827; }","duration":"115.174656ms","start":"2026-05-11T11:38:59.367622Z","end":"2026-05-11T11:38:59.482796Z","steps":["trace[1659756488] 'read index received' (duration: 19.934803ms)","trace[1659756488] 'applied index is now lower than readState.Index' (duration: 95.238953ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:38:59.482836Z","caller":"traceutil/trace.go:171","msg":"trace[1528415441] transaction","detail":"{read_only:false; response_revision:25977; number_of_response:1; }","duration":"118.226443ms","start":"2026-05-11T11:38:59.364582Z","end":"2026-05-11T11:38:59.482808Z","steps":["trace[1528415441] 'process raft request' (duration: 22.838358ms)","trace[1528415441] 'compare' (duration: 95.243163ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T11:38:59.48306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.41297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T11:38:59.4831Z","caller":"traceutil/trace.go:171","msg":"trace[1798591983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25977; }","duration":"115.511221ms","start":"2026-05-11T11:38:59.367578Z","end":"2026-05-11T11:38:59.483089Z","steps":["trace[1798591983] 'agreement among raft nodes before linearized reading' (duration: 115.347108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:38:59.520513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.311011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T11:38:59.520511Z","caller":"traceutil/trace.go:171","msg":"trace[872396537] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"151.674459ms","start":"2026-05-11T11:38:59.36881Z","end":"2026-05-11T11:38:59.520484Z","steps":["trace[872396537] 'process raft request' (duration: 151.578168ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:59.520596Z","caller":"traceutil/trace.go:171","msg":"trace[1858522658] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25979; }","duration":"105.440544ms","start":"2026-05-11T11:38:59.41512Z","end":"2026-05-11T11:38:59.520561Z","steps":["trace[1858522658] 'agreement among raft nodes before linearized reading' (duration: 105.312282ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:38:59.520553Z","caller":"traceutil/trace.go:171","msg":"trace[1602500525] transaction","detail":"{read_only:false; response_revision:25978; number_of_response:1; }","duration":"151.943505ms","start":"2026-05-11T11:38:59.368556Z","end":"2026-05-11T11:38:59.5205Z","steps":["trace[1602500525] 'process raft request' (duration: 151.68926ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:03.290451Z","caller":"traceutil/trace.go:171","msg":"trace[2066028538] transaction","detail":"{read_only:false; response_revision:26008; number_of_response:1; }","duration":"150.16116ms","start":"2026-05-11T11:39:03.140255Z","end":"2026-05-11T11:39:03.290416Z","steps":["trace[2066028538] 'process raft request' (duration: 138.455701ms)","trace[2066028538] 'compare' (duration: 11.593017ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:03.322529Z","caller":"traceutil/trace.go:171","msg":"trace[1833868451] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"162.823728ms","start":"2026-05-11T11:39:03.15968Z","end":"2026-05-11T11:39:03.322503Z","steps":["trace[1833868451] 'process raft request' (duration: 162.658955ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:03.514782Z","caller":"traceutil/trace.go:171","msg":"trace[1571772053] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"153.068154ms","start":"2026-05-11T11:39:03.361686Z","end":"2026-05-11T11:39:03.514754Z","steps":["trace[1571772053] 'process raft request' (duration: 76.360309ms)","trace[1571772053] 'compare' (duration: 76.542372ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:03.684219Z","caller":"traceutil/trace.go:171","msg":"trace[1054921717] linearizableReadLoop","detail":"{readStateIndex:26866; appliedIndex:26863; }","duration":"138.745998ms","start":"2026-05-11T11:39:03.545453Z","end":"2026-05-11T11:39:03.684199Z","steps":["trace[1054921717] 'read index received' (duration: 29.142866ms)","trace[1054921717] 'applied index is now lower than readState.Index' (duration: 109.601912ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T11:39:03.684328Z","caller":"traceutil/trace.go:171","msg":"trace[1205492322] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"150.976336ms","start":"2026-05-11T11:39:03.533344Z","end":"2026-05-11T11:39:03.68432Z","steps":["trace[1205492322] 'process raft request' (duration: 144.607356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:03.68445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.979192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-blp6n\" ","response":"range_response_count:1 size:3396"} {"level":"info","ts":"2026-05-11T11:39:03.684527Z","caller":"traceutil/trace.go:171","msg":"trace[1361479391] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-blp6n; range_end:; response_count:1; response_revision:26014; }","duration":"139.087684ms","start":"2026-05-11T11:39:03.545422Z","end":"2026-05-11T11:39:03.684509Z","steps":["trace[1361479391] 'agreement among raft nodes before linearized reading' (duration: 138.909381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T11:39:03.684722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.408141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T11:39:03.684746Z","caller":"traceutil/trace.go:171","msg":"trace[1030055436] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"148.867066ms","start":"2026-05-11T11:39:03.535865Z","end":"2026-05-11T11:39:03.684732Z","steps":["trace[1030055436] 'process raft request' (duration: 148.257934ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:03.684761Z","caller":"traceutil/trace.go:171","msg":"trace[245953268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26014; }","duration":"138.466202ms","start":"2026-05-11T11:39:03.546283Z","end":"2026-05-11T11:39:03.684749Z","steps":["trace[245953268] 'agreement among raft nodes before linearized reading' (duration: 138.314799ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T11:39:33.354164Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a73.wal"} {"level":"info","ts":"2026-05-11T11:42:58.728853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25442} {"level":"info","ts":"2026-05-11T11:42:58.883041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25442,"took":"148.258135ms","hash":4184182784,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":35782656,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-11T11:42:58.883091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4184182784,"revision":25442,"compact-revision":22031} {"level":"info","ts":"2026-05-11T11:44:29.008353Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T11:44:29.014534Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T11:44:29.014623Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T11:47:58.735548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28399} {"level":"info","ts":"2026-05-11T11:47:58.890151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28399,"took":"150.68463ms","hash":1933383701,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T11:47:58.890248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1933383701,"revision":28399,"compact-revision":25442} {"level":"info","ts":"2026-05-11T11:52:58.742322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30635} {"level":"info","ts":"2026-05-11T11:52:58.855894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30635,"took":"109.147382ms","hash":1497326754,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":30900224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T11:52:58.855972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1497326754,"revision":30635,"compact-revision":28399} {"level":"info","ts":"2026-05-11T11:57:58.748102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32896} {"level":"info","ts":"2026-05-11T11:57:58.90544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32896,"took":"150.797221ms","hash":3170096284,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T11:57:58.905548Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3170096284,"revision":32896,"compact-revision":30635} {"level":"info","ts":"2026-05-11T12:02:58.754445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35187} {"level":"info","ts":"2026-05-11T12:02:58.895913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35187,"took":"136.596635ms","hash":2625435006,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":31870976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T12:02:58.89599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2625435006,"revision":35187,"compact-revision":32896} {"level":"info","ts":"2026-05-11T12:05:37.396771Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T12:05:37.401176Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T12:05:37.401266Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-11T12:07:58.763672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37518} {"level":"info","ts":"2026-05-11T12:07:58.87949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37518,"took":"112.636607ms","hash":3819100401,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":31989760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T12:07:58.879564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3819100401,"revision":37518,"compact-revision":35187}