{"level":"warn","ts":"2026-05-27T19:39:47.944565Z","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-27T19:39:47.944721Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.132:2380","--initial-cluster=instance=https://199.19.213.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.132: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-27T19:39:47.944832Z","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-27T19:39:47.944849Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-05-27T19:39:47.944872Z","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-27T19:39:47.945532Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"]} {"level":"info","ts":"2026-05-27T19:39:47.945617Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.132: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-27T19:39:47.950017Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.123412ms"} {"level":"info","ts":"2026-05-27T19:39:47.958096Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"19a81b20c4b111c3","cluster-id":"42a0cab9df6b0eb"} {"level":"info","ts":"2026-05-27T19:39:47.958178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:39:47.958204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 0"} {"level":"info","ts":"2026-05-27T19:39:47.958222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 19a81b20c4b111c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:39:47.958234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 1"} {"level":"info","ts":"2026-05-27T19:39:47.95828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"warn","ts":"2026-05-27T19:39:47.976094Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:39:47.979038Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:39:47.980885Z","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-27T19:39:47.984363Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"19a81b20c4b111c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:39:47.984532Z","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-27T19:39:47.984635Z","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-27T19:39:47.984648Z","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-27T19:39:47.984677Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"19a81b20c4b111c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:39:47.985397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"info","ts":"2026-05-27T19:39:47.985521Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","added-peer-id":"19a81b20c4b111c3","added-peer-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-05-27T19:39:47.988523Z","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-27T19:39:47.988621Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-05-27T19:39:47.98869Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-05-27T19:39:47.988894Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"19a81b20c4b111c3","initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:39:47.98896Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:39:48.259484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:39:48.259532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:39:48.259571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgPreVoteResp from 19a81b20c4b111c3 at term 1"} {"level":"info","ts":"2026-05-27T19:39:48.259593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:39:48.259601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgVoteResp from 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-05-27T19:39:48.259611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became leader at term 2"} {"level":"info","ts":"2026-05-27T19:39:48.259621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 19a81b20c4b111c3 elected leader 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-05-27T19:39:48.260705Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:39:48.26149Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"19a81b20c4b111c3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.132:2379]}","request-path":"/0/members/19a81b20c4b111c3/attributes","cluster-id":"42a0cab9df6b0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:39:48.261666Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:39:48.261774Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:39:48.261881Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:39:48.261979Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:39:48.262012Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:39:48.265234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:39:48.265467Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:39:48.265535Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:39:48.266455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.132:2379"} {"level":"info","ts":"2026-05-27T19:40:09.726657Z","caller":"traceutil/trace.go:171","msg":"trace[1261209915] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"275.566539ms","start":"2026-05-27T19:40:09.451066Z","end":"2026-05-27T19:40:09.726633Z","steps":["trace[1261209915] 'process raft request' (duration: 275.443985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:26.662023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.74479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:26.662349Z","caller":"traceutil/trace.go:171","msg":"trace[1019025895] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"253.360153ms","start":"2026-05-27T19:40:26.40895Z","end":"2026-05-27T19:40:26.66231Z","steps":["trace[1019025895] 'process raft request' (duration: 90.605102ms)","trace[1019025895] 'compare' (duration: 161.581955ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:26.662417Z","caller":"traceutil/trace.go:171","msg":"trace[1181714162] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"234.651252ms","start":"2026-05-27T19:40:26.427746Z","end":"2026-05-27T19:40:26.662397Z","steps":["trace[1181714162] 'process raft request' (duration: 234.58629ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:26.662538Z","caller":"traceutil/trace.go:171","msg":"trace[1530104887] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"245.406048ms","start":"2026-05-27T19:40:26.417058Z","end":"2026-05-27T19:40:26.662464Z","steps":["trace[1530104887] 'process raft request' (duration: 245.13827ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:26.672683Z","caller":"traceutil/trace.go:171","msg":"trace[260694949] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"167.265883ms","start":"2026-05-27T19:40:26.50533Z","end":"2026-05-27T19:40:26.672596Z","steps":["trace[260694949] 'process raft request' (duration: 166.985784ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:32.955624Z","caller":"traceutil/trace.go:171","msg":"trace[1816889329] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"112.287618ms","start":"2026-05-27T19:40:32.843311Z","end":"2026-05-27T19:40:32.955599Z","steps":["trace[1816889329] 'read index received' (duration: 111.498305ms)","trace[1816889329] 'applied index is now lower than readState.Index' (duration: 788.303µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:40:32.955696Z","caller":"traceutil/trace.go:171","msg":"trace[1093230944] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"183.572632ms","start":"2026-05-27T19:40:32.772093Z","end":"2026-05-27T19:40:32.955666Z","steps":["trace[1093230944] 'process raft request' (duration: 182.982315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:32.955861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.534126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-47frl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-27T19:40:32.955905Z","caller":"traceutil/trace.go:171","msg":"trace[839503717] range","detail":"{range_begin:/registry/pods/kube-system/cilium-47frl; range_end:; response_count:1; response_revision:636; }","duration":"112.600677ms","start":"2026-05-27T19:40:32.843292Z","end":"2026-05-27T19:40:32.955893Z","steps":["trace[839503717] 'agreement among raft nodes before linearized reading' (duration: 112.489354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:33.285233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.982201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:40:33.285446Z","caller":"traceutil/trace.go:171","msg":"trace[882713538] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"263.16199ms","start":"2026-05-27T19:40:33.022265Z","end":"2026-05-27T19:40:33.285427Z","steps":["trace[882713538] 'process raft request' (duration: 263.089208ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:33.285495Z","caller":"traceutil/trace.go:171","msg":"trace[765932389] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"308.585944ms","start":"2026-05-27T19:40:32.97687Z","end":"2026-05-27T19:40:33.285456Z","steps":["trace[765932389] 'process raft request' (duration: 56.265553ms)","trace[765932389] 'compare' (duration: 251.833217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:33.285689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:32.976829Z","time spent":"308.767079ms","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:34.135172Z","caller":"traceutil/trace.go:171","msg":"trace[1375514825] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"246.432078ms","start":"2026-05-27T19:40:33.88867Z","end":"2026-05-27T19:40:34.135102Z","steps":["trace[1375514825] 'read index received' (duration: 238.416033ms)","trace[1375514825] 'applied index is now lower than readState.Index' (duration: 8.014755ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:40:34.135736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.451251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:40:34.135801Z","caller":"traceutil/trace.go:171","msg":"trace[1749839326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"211.576125ms","start":"2026-05-27T19:40:33.924208Z","end":"2026-05-27T19:40:34.135784Z","steps":["trace[1749839326] 'agreement among raft nodes before linearized reading' (duration: 211.448251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.135774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.065687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:40:34.135925Z","caller":"traceutil/trace.go:171","msg":"trace[1345494051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"247.277743ms","start":"2026-05-27T19:40:33.888619Z","end":"2026-05-27T19:40:34.135897Z","steps":["trace[1345494051] 'agreement among raft nodes before linearized reading' (duration: 246.917753ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:40:34.135837Z","caller":"traceutil/trace.go:171","msg":"trace[2041648963] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"455.637294ms","start":"2026-05-27T19:40:33.680168Z","end":"2026-05-27T19:40:34.135805Z","steps":["trace[2041648963] 'process raft request' (duration: 449.016558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:40:34.136083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:40:33.680119Z","time spent":"455.86422ms","remote":"127.0.0.1:41166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:40:36.170317Z","caller":"traceutil/trace.go:171","msg":"trace[19858718] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"107.692004ms","start":"2026-05-27T19:40:36.062596Z","end":"2026-05-27T19:40:36.170288Z","steps":["trace[19858718] 'process raft request' (duration: 107.253821ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:16.647499Z","caller":"traceutil/trace.go:171","msg":"trace[1928416874] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1039; }","duration":"144.641889ms","start":"2026-05-27T19:41:16.502841Z","end":"2026-05-27T19:41:16.647483Z","steps":["trace[1928416874] 'read index received' (duration: 10.68324ms)","trace[1928416874] 'applied index is now lower than readState.Index' (duration: 133.957959ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:16.647582Z","caller":"traceutil/trace.go:171","msg":"trace[385529075] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"180.646815ms","start":"2026-05-27T19:41:16.466928Z","end":"2026-05-27T19:41:16.647575Z","steps":["trace[385529075] 'process raft request' (duration: 180.522941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:16.647877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.018569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-mxqzv\" ","response":"range_response_count:1 size:2274"} {"level":"info","ts":"2026-05-27T19:41:16.64797Z","caller":"traceutil/trace.go:171","msg":"trace[1001888651] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-7c96b6546b-mxqzv; range_end:; response_count:1; response_revision:1010; }","duration":"145.147533ms","start":"2026-05-27T19:41:16.502799Z","end":"2026-05-27T19:41:16.647947Z","steps":["trace[1001888651] 'agreement among raft nodes before linearized reading' (duration: 144.874725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:16.648076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.043569ms","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-27T19:41:16.648172Z","caller":"traceutil/trace.go:171","msg":"trace[1187676942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"106.194262ms","start":"2026-05-27T19:41:16.541963Z","end":"2026-05-27T19:41:16.648157Z","steps":["trace[1187676942] 'agreement among raft nodes before linearized reading' (duration: 105.686778ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:16.648832Z","caller":"traceutil/trace.go:171","msg":"trace[282836859] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"180.85425ms","start":"2026-05-27T19:41:16.466699Z","end":"2026-05-27T19:41:16.647554Z","steps":["trace[282836859] 'process raft request' (duration: 180.653824ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:30.033818Z","caller":"traceutil/trace.go:171","msg":"trace[581234890] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"257.278938ms","start":"2026-05-27T19:41:29.776504Z","end":"2026-05-27T19:41:30.033783Z","steps":["trace[581234890] 'process raft request' (duration: 257.058462ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:41:51.599256Z","caller":"traceutil/trace.go:171","msg":"trace[1212833038] linearizableReadLoop","detail":"{readStateIndex:1538; appliedIndex:1537; }","duration":"119.213019ms","start":"2026-05-27T19:41:51.480018Z","end":"2026-05-27T19:41:51.599231Z","steps":["trace[1212833038] 'read index received' (duration: 118.973312ms)","trace[1212833038] 'applied index is now lower than readState.Index' (duration: 238.847µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:41:51.599405Z","caller":"traceutil/trace.go:171","msg":"trace[381950071] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"286.712241ms","start":"2026-05-27T19:41:51.312657Z","end":"2026-05-27T19:41:51.599369Z","steps":["trace[381950071] 'process raft request' (duration: 286.410213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:41:51.599464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.441395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-27T19:41:51.599505Z","caller":"traceutil/trace.go:171","msg":"trace[433294628] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1494; }","duration":"119.542619ms","start":"2026-05-27T19:41:51.479948Z","end":"2026-05-27T19:41:51.59949Z","steps":["trace[433294628] 'agreement among raft nodes before linearized reading' (duration: 119.391904ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:17.822858Z","caller":"traceutil/trace.go:171","msg":"trace[972153515] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"221.051337ms","start":"2026-05-27T19:42:17.60178Z","end":"2026-05-27T19:42:17.822831Z","steps":["trace[972153515] 'read index received' (duration: 220.913913ms)","trace[972153515] 'applied index is now lower than readState.Index' (duration: 136.324µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:42:17.822922Z","caller":"traceutil/trace.go:171","msg":"trace[973232377] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"437.003495ms","start":"2026-05-27T19:42:17.385886Z","end":"2026-05-27T19:42:17.822889Z","steps":["trace[973232377] 'process raft request' (duration: 436.794319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:17.823084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.275993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:42:17.823095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:17.385839Z","time spent":"437.1592ms","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:42:17.823126Z","caller":"traceutil/trace.go:171","msg":"trace[1674210615] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1914; }","duration":"221.385636ms","start":"2026-05-27T19:42:17.601728Z","end":"2026-05-27T19:42:17.823114Z","steps":["trace[1674210615] 'agreement among raft nodes before linearized reading' (duration: 221.230402ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:17.893843Z","caller":"traceutil/trace.go:171","msg":"trace[1049323922] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"177.155743ms","start":"2026-05-27T19:42:17.71666Z","end":"2026-05-27T19:42:17.893816Z","steps":["trace[1049323922] 'process raft request' (duration: 177.03161ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:17.893847Z","caller":"traceutil/trace.go:171","msg":"trace[1101911432] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"281.023979ms","start":"2026-05-27T19:42:17.612769Z","end":"2026-05-27T19:42:17.893793Z","steps":["trace[1101911432] 'process raft request' (duration: 221.14485ms)","trace[1101911432] 'compare' (duration: 59.57843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:42:17.894475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.009526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:42:17.894516Z","caller":"traceutil/trace.go:171","msg":"trace[869465029] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1916; }","duration":"200.646875ms","start":"2026-05-27T19:42:17.693859Z","end":"2026-05-27T19:42:17.894506Z","steps":["trace[869465029] 'agreement among raft nodes before linearized reading' (duration: 199.974056ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:18.249609Z","caller":"traceutil/trace.go:171","msg":"trace[243921612] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1988; }","duration":"324.367196ms","start":"2026-05-27T19:42:17.925207Z","end":"2026-05-27T19:42:18.249574Z","steps":["trace[243921612] 'read index received' (duration: 304.483239ms)","trace[243921612] 'applied index is now lower than readState.Index' (duration: 19.882037ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:42:18.249789Z","caller":"traceutil/trace.go:171","msg":"trace[1923069063] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"344.51284ms","start":"2026-05-27T19:42:17.905234Z","end":"2026-05-27T19:42:18.249747Z","steps":["trace[1923069063] 'process raft request' (duration: 324.430257ms)","trace[1923069063] 'compare' (duration: 19.782184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:42:18.249906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.654804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:42:18.249997Z","caller":"traceutil/trace.go:171","msg":"trace[923983420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"324.837989ms","start":"2026-05-27T19:42:17.925132Z","end":"2026-05-27T19:42:18.24997Z","steps":["trace[923983420] 'agreement among raft nodes before linearized reading' (duration: 324.659904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:18.249999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:17.905201Z","time spent":"344.682335ms","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10964,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:42:18.250058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:17.925102Z","time spent":"324.943673ms","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:42:18.274887Z","caller":"traceutil/trace.go:171","msg":"trace[632752601] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"346.539539ms","start":"2026-05-27T19:42:17.928299Z","end":"2026-05-27T19:42:18.274839Z","steps":["trace[632752601] 'process raft request' (duration: 346.191279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:18.274905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.021693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:42:18.274983Z","caller":"traceutil/trace.go:171","msg":"trace[557737481] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1918; }","duration":"122.182227ms","start":"2026-05-27T19:42:18.152783Z","end":"2026-05-27T19:42:18.274965Z","steps":["trace[557737481] 'agreement among raft nodes before linearized reading' (duration: 122.012972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:18.275068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:17.928271Z","time spent":"346.697194ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:42:26.772518Z","caller":"traceutil/trace.go:171","msg":"trace[2011567988] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"397.925286ms","start":"2026-05-27T19:42:26.37457Z","end":"2026-05-27T19:42:26.772496Z","steps":["trace[2011567988] 'process raft request' (duration: 322.147182ms)","trace[2011567988] 'compare' (duration: 75.694591ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:42:26.772642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:26.374499Z","time spent":"398.07877ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:42:26.9009Z","caller":"traceutil/trace.go:171","msg":"trace[761680803] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"421.837165ms","start":"2026-05-27T19:42:26.479036Z","end":"2026-05-27T19:42:26.900874Z","steps":["trace[761680803] 'process raft request' (duration: 421.698081ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:26.901065Z","caller":"traceutil/trace.go:171","msg":"trace[948879367] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"349.644825ms","start":"2026-05-27T19:42:26.551402Z","end":"2026-05-27T19:42:26.901047Z","steps":["trace[948879367] 'process raft request' (duration: 349.425458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:26.901072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:26.479008Z","time spent":"421.981049ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:42:26.901183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:26.551372Z","time spent":"349.744267ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:42:26.901315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.557034ms","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-27T19:42:26.901354Z","caller":"traceutil/trace.go:171","msg":"trace[1392311430] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"437.243948ms","start":"2026-05-27T19:42:26.464079Z","end":"2026-05-27T19:42:26.901323Z","steps":["trace[1392311430] 'process raft request' (duration: 434.738266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:26.901401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:42:26.464044Z","time spent":"437.336721ms","remote":"127.0.0.1:41246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7931,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:42:26.901397Z","caller":"traceutil/trace.go:171","msg":"trace[1027613628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2000; }","duration":"111.687668ms","start":"2026-05-27T19:42:26.78969Z","end":"2026-05-27T19:42:26.901378Z","steps":["trace[1027613628] 'agreement among raft nodes before linearized reading' (duration: 111.468772ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:26.901072Z","caller":"traceutil/trace.go:171","msg":"trace[1944415178] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2070; }","duration":"111.299557ms","start":"2026-05-27T19:42:26.789739Z","end":"2026-05-27T19:42:26.901039Z","steps":["trace[1944415178] 'read index received' (duration: 108.97938ms)","trace[1944415178] 'applied index is now lower than readState.Index' (duration: 2.318077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:42:26.90162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.598176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:42:26.901676Z","caller":"traceutil/trace.go:171","msg":"trace[1156452240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2000; }","duration":"111.686868ms","start":"2026-05-27T19:42:26.789975Z","end":"2026-05-27T19:42:26.901662Z","steps":["trace[1156452240] 'agreement among raft nodes before linearized reading' (duration: 111.536114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:26.901632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.784322ms","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-27T19:42:26.901877Z","caller":"traceutil/trace.go:171","msg":"trace[82318204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2000; }","duration":"111.07602ms","start":"2026-05-27T19:42:26.79079Z","end":"2026-05-27T19:42:26.901866Z","steps":["trace[82318204] 'agreement among raft nodes before linearized reading' (duration: 110.779351ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:42:30.840289Z","caller":"traceutil/trace.go:171","msg":"trace[628201863] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"127.199645ms","start":"2026-05-27T19:42:30.713048Z","end":"2026-05-27T19:42:30.840248Z","steps":["trace[628201863] 'process raft request' (duration: 126.916077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:31.496411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.783222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-q4zn9\" ","response":"range_response_count:1 size:6147"} {"level":"info","ts":"2026-05-27T19:42:31.496469Z","caller":"traceutil/trace.go:171","msg":"trace[1764928617] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-q4zn9; range_end:; response_count:1; response_revision:2032; }","duration":"202.884825ms","start":"2026-05-27T19:42:31.293571Z","end":"2026-05-27T19:42:31.496456Z","steps":["trace[1764928617] 'range keys from in-memory index tree' (duration: 202.613147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:42:31.496641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.947225ms","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":"info","ts":"2026-05-27T19:42:31.496705Z","caller":"traceutil/trace.go:171","msg":"trace[1063711022] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2032; }","duration":"104.045678ms","start":"2026-05-27T19:42:31.39264Z","end":"2026-05-27T19:42:31.496686Z","steps":["trace[1063711022] 'range keys from in-memory index tree' (duration: 103.804091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:03.29903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.255838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:43:03.299169Z","caller":"traceutil/trace.go:171","msg":"trace[49223385] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"188.586414ms","start":"2026-05-27T19:43:03.110564Z","end":"2026-05-27T19:43:03.29915Z","steps":["trace[49223385] 'process raft request' (duration: 54.12666ms)","trace[49223385] 'compare' (duration: 134.114484ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:03.478244Z","caller":"traceutil/trace.go:171","msg":"trace[1239323790] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"132.342294ms","start":"2026-05-27T19:43:03.345869Z","end":"2026-05-27T19:43:03.478211Z","steps":["trace[1239323790] 'process raft request' (duration: 131.012765ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:09.462917Z","caller":"traceutil/trace.go:171","msg":"trace[951091110] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"141.25923ms","start":"2026-05-27T19:43:09.321624Z","end":"2026-05-27T19:43:09.462883Z","steps":["trace[951091110] 'read index received' (duration: 70.81526ms)","trace[951091110] 'applied index is now lower than readState.Index' (duration: 70.4429ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:09.463134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.505186ms","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-27T19:43:09.463237Z","caller":"traceutil/trace.go:171","msg":"trace[1084016452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2249; }","duration":"141.659511ms","start":"2026-05-27T19:43:09.321567Z","end":"2026-05-27T19:43:09.463226Z","steps":["trace[1084016452] 'agreement among raft nodes before linearized reading' (duration: 141.419524ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:09.463495Z","caller":"traceutil/trace.go:171","msg":"trace[1073441175] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"266.973202ms","start":"2026-05-27T19:43:09.196169Z","end":"2026-05-27T19:43:09.463143Z","steps":["trace[1073441175] 'process raft request' (duration: 196.198243ms)","trace[1073441175] 'compare' (duration: 70.248894ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:10.257951Z","caller":"traceutil/trace.go:171","msg":"trace[738628033] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"172.333696ms","start":"2026-05-27T19:43:10.085587Z","end":"2026-05-27T19:43:10.25792Z","steps":["trace[738628033] 'process raft request' (duration: 172.13732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:10.663747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.418013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:43:10.663847Z","caller":"traceutil/trace.go:171","msg":"trace[280767555] linearizableReadLoop","detail":"{readStateIndex:2336; appliedIndex:2335; }","duration":"161.282887ms","start":"2026-05-27T19:43:10.502551Z","end":"2026-05-27T19:43:10.663834Z","steps":["trace[280767555] 'read index received' (duration: 57.242µs)","trace[280767555] 'applied index is now lower than readState.Index' (duration: 161.224435ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:10.66399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.447083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b38397bf22087d\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-27T19:43:10.664022Z","caller":"traceutil/trace.go:171","msg":"trace[1095683966] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b38397bf22087d; range_end:; response_count:1; response_revision:2253; }","duration":"161.510444ms","start":"2026-05-27T19:43:10.502498Z","end":"2026-05-27T19:43:10.664009Z","steps":["trace[1095683966] 'agreement among raft nodes before linearized reading' (duration: 161.36932ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:10.664187Z","caller":"traceutil/trace.go:171","msg":"trace[434301161] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"576.536432ms","start":"2026-05-27T19:43:10.087605Z","end":"2026-05-27T19:43:10.664142Z","steps":["trace[434301161] 'process raft request' (duration: 331.593914ms)","trace[434301161] 'compare' (duration: 244.31535ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:10.66457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:10.087581Z","time spent":"576.85502ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:43:10.668629Z","caller":"traceutil/trace.go:171","msg":"trace[874139724] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"133.855816ms","start":"2026-05-27T19:43:10.534734Z","end":"2026-05-27T19:43:10.66859Z","steps":["trace[874139724] 'process raft request' (duration: 133.63692ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:33.58911Z","caller":"traceutil/trace.go:171","msg":"trace[530566423] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"134.691797ms","start":"2026-05-27T19:44:33.454378Z","end":"2026-05-27T19:44:33.58907Z","steps":["trace[530566423] 'process raft request' (duration: 134.4383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:33.946022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.70087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:33.946272Z","caller":"traceutil/trace.go:171","msg":"trace[450651651] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"310.383234ms","start":"2026-05-27T19:44:33.635855Z","end":"2026-05-27T19:44:33.946239Z","steps":["trace[450651651] 'process raft request' (duration: 76.371305ms)","trace[450651651] 'compare' (duration: 233.394091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:33.946428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:33.635834Z","time spent":"310.512728ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:33.946812Z","caller":"traceutil/trace.go:171","msg":"trace[1511063900] transaction","detail":"{read_only:false; response_revision:2897; number_of_response:1; }","duration":"218.480208ms","start":"2026-05-27T19:44:33.728291Z","end":"2026-05-27T19:44:33.946771Z","steps":["trace[1511063900] 'process raft request' (duration: 217.87302ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:39.184994Z","caller":"traceutil/trace.go:171","msg":"trace[1098239466] transaction","detail":"{read_only:false; response_revision:2927; number_of_response:1; }","duration":"107.738246ms","start":"2026-05-27T19:44:39.077217Z","end":"2026-05-27T19:44:39.184955Z","steps":["trace[1098239466] 'process raft request' (duration: 107.567921ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:20.321363Z","caller":"traceutil/trace.go:171","msg":"trace[1044327615] transaction","detail":"{read_only:false; response_revision:3972; number_of_response:1; }","duration":"131.892168ms","start":"2026-05-27T19:47:20.18945Z","end":"2026-05-27T19:47:20.321342Z","steps":["trace[1044327615] 'process raft request' (duration: 131.778855ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:43.751554Z","caller":"traceutil/trace.go:171","msg":"trace[1792714993] linearizableReadLoop","detail":"{readStateIndex:5204; appliedIndex:5203; }","duration":"114.538323ms","start":"2026-05-27T19:48:43.636991Z","end":"2026-05-27T19:48:43.751529Z","steps":["trace[1792714993] 'read index received' (duration: 114.333737ms)","trace[1792714993] 'applied index is now lower than readState.Index' (duration: 200.846µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:48:43.751711Z","caller":"traceutil/trace.go:171","msg":"trace[675878739] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"115.292176ms","start":"2026-05-27T19:48:43.636374Z","end":"2026-05-27T19:48:43.751666Z","steps":["trace[675878739] 'process raft request' (duration: 115.019028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:48:43.751835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.825152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-27T19:48:43.751884Z","caller":"traceutil/trace.go:171","msg":"trace[1667250544] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5014; }","duration":"114.923484ms","start":"2026-05-27T19:48:43.636949Z","end":"2026-05-27T19:48:43.751872Z","steps":["trace[1667250544] 'agreement among raft nodes before linearized reading' (duration: 114.723358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:48:43.751972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.068349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-05-27T19:48:43.751834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.949376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-05-27T19:48:43.752047Z","caller":"traceutil/trace.go:171","msg":"trace[668788720] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5014; }","duration":"105.175092ms","start":"2026-05-27T19:48:43.64685Z","end":"2026-05-27T19:48:43.752025Z","steps":["trace[668788720] 'agreement among raft nodes before linearized reading' (duration: 105.025908ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:43.752115Z","caller":"traceutil/trace.go:171","msg":"trace[336556026] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5014; }","duration":"114.217733ms","start":"2026-05-27T19:48:43.637855Z","end":"2026-05-27T19:48:43.752073Z","steps":["trace[336556026] 'agreement among raft nodes before linearized reading' (duration: 113.851423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:48:48.366032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.179766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16181"} {"level":"info","ts":"2026-05-27T19:48:48.366124Z","caller":"traceutil/trace.go:171","msg":"trace[1176589090] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5173; }","duration":"112.286529ms","start":"2026-05-27T19:48:48.25382Z","end":"2026-05-27T19:48:48.366107Z","steps":["trace[1176589090] 'range keys from in-memory index tree' (duration: 112.000641ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:48.399412Z","caller":"traceutil/trace.go:171","msg":"trace[1430225586] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"143.208679ms","start":"2026-05-27T19:48:48.256169Z","end":"2026-05-27T19:48:48.399377Z","steps":["trace[1430225586] 'process raft request' (duration: 142.986902ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:48.551778Z","caller":"traceutil/trace.go:171","msg":"trace[1377617637] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"143.731194ms","start":"2026-05-27T19:48:48.408023Z","end":"2026-05-27T19:48:48.551754Z","steps":["trace[1377617637] 'process raft request' (duration: 118.467008ms)","trace[1377617637] 'compare' (duration: 24.926376ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:48:48.551937Z","caller":"traceutil/trace.go:171","msg":"trace[69354092] linearizableReadLoop","detail":"{readStateIndex:5369; appliedIndex:5368; }","duration":"142.769977ms","start":"2026-05-27T19:48:48.408915Z","end":"2026-05-27T19:48:48.551684Z","steps":["trace[69354092] 'read index received' (duration: 117.585914ms)","trace[69354092] 'applied index is now lower than readState.Index' (duration: 25.182883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:48:48.551986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.052845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62980"} {"level":"info","ts":"2026-05-27T19:48:48.552047Z","caller":"traceutil/trace.go:171","msg":"trace[2058507763] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5175; }","duration":"143.138297ms","start":"2026-05-27T19:48:48.408895Z","end":"2026-05-27T19:48:48.552033Z","steps":["trace[2058507763] 'agreement among raft nodes before linearized reading' (duration: 142.914631ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:48.557064Z","caller":"traceutil/trace.go:171","msg":"trace[774950282] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"147.251508ms","start":"2026-05-27T19:48:48.409781Z","end":"2026-05-27T19:48:48.557032Z","steps":["trace[774950282] 'process raft request' (duration: 147.141155ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:49:02.138489Z","caller":"traceutil/trace.go:171","msg":"trace[1609430088] linearizableReadLoop","detail":"{readStateIndex:5633; appliedIndex:5632; }","duration":"107.447458ms","start":"2026-05-27T19:49:02.03102Z","end":"2026-05-27T19:49:02.138467Z","steps":["trace[1609430088] 'read index received' (duration: 60.966584ms)","trace[1609430088] 'applied index is now lower than readState.Index' (duration: 46.479974ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:49:02.138533Z","caller":"traceutil/trace.go:171","msg":"trace[966229119] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"136.900326ms","start":"2026-05-27T19:49:02.001598Z","end":"2026-05-27T19:49:02.138499Z","steps":["trace[966229119] 'process raft request' (duration: 90.496515ms)","trace[966229119] 'compare' (duration: 46.201845ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:49:02.138893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.83973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-27T19:49:02.139027Z","caller":"traceutil/trace.go:171","msg":"trace[769098584] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5431; }","duration":"108.055475ms","start":"2026-05-27T19:49:02.030947Z","end":"2026-05-27T19:49:02.139002Z","steps":["trace[769098584] 'agreement among raft nodes before linearized reading' (duration: 107.625623ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:49:02.818236Z","caller":"traceutil/trace.go:171","msg":"trace[475060897] linearizableReadLoop","detail":"{readStateIndex:5638; appliedIndex:5637; }","duration":"197.676396ms","start":"2026-05-27T19:49:02.620533Z","end":"2026-05-27T19:49:02.818209Z","steps":["trace[475060897] 'read index received' (duration: 197.608804ms)","trace[475060897] 'applied index is now lower than readState.Index' (duration: 66.492µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:49:02.818332Z","caller":"traceutil/trace.go:171","msg":"trace[95656269] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"239.978547ms","start":"2026-05-27T19:49:02.578336Z","end":"2026-05-27T19:49:02.818315Z","steps":["trace[95656269] 'process raft request' (duration: 239.712839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:49:02.818495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.945943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnlnf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T19:49:02.818556Z","caller":"traceutil/trace.go:171","msg":"trace[1947694278] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnlnf; range_end:; response_count:1; response_revision:5436; }","duration":"198.054076ms","start":"2026-05-27T19:49:02.620485Z","end":"2026-05-27T19:49:02.818539Z","steps":["trace[1947694278] 'agreement among raft nodes before linearized reading' (duration: 197.83246ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:49:02.831897Z","caller":"traceutil/trace.go:171","msg":"trace[847205555] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"142.298393ms","start":"2026-05-27T19:49:02.689579Z","end":"2026-05-27T19:49:02.831877Z","steps":["trace[847205555] 'process raft request' (duration: 142.121458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:49:10.497538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.048866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T19:49:10.49758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.070782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:49:10.497684Z","caller":"traceutil/trace.go:171","msg":"trace[1861967900] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5627; }","duration":"141.276823ms","start":"2026-05-27T19:49:10.356381Z","end":"2026-05-27T19:49:10.497658Z","steps":["trace[1861967900] 'count revisions from in-memory index tree' (duration: 140.925482ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:49:10.497704Z","caller":"traceutil/trace.go:171","msg":"trace[863009671] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5627; }","duration":"169.254737ms","start":"2026-05-27T19:49:10.328419Z","end":"2026-05-27T19:49:10.497674Z","steps":["trace[863009671] 'count revisions from in-memory index tree' (duration: 168.943448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:49:10.497664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.222158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9616c2e-ca15-4f66-8397-97362b438d3c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-27T19:49:10.497786Z","caller":"traceutil/trace.go:171","msg":"trace[1375582871] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9616c2e-ca15-4f66-8397-97362b438d3c; range_end:; response_count:1; response_revision:5627; }","duration":"100.403403ms","start":"2026-05-27T19:49:10.397366Z","end":"2026-05-27T19:49:10.497769Z","steps":["trace[1375582871] 'range keys from in-memory index tree' (duration: 100.035633ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:49:10.824463Z","caller":"traceutil/trace.go:171","msg":"trace[115293882] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"146.256549ms","start":"2026-05-27T19:49:10.678181Z","end":"2026-05-27T19:49:10.824438Z","steps":["trace[115293882] 'process raft request' (duration: 146.069694ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:49:48.382607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-05-27T19:49:48.460992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"76.319032ms","hash":1003109593,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":32849920,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-27T19:49:48.461074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1003109593,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-05-27T19:50:15.339677Z","caller":"traceutil/trace.go:171","msg":"trace[1206865896] linearizableReadLoop","detail":"{readStateIndex:6414; appliedIndex:6413; }","duration":"103.823117ms","start":"2026-05-27T19:50:15.235793Z","end":"2026-05-27T19:50:15.339616Z","steps":["trace[1206865896] 'read index received' (duration: 103.535369ms)","trace[1206865896] 'applied index is now lower than readState.Index' (duration: 286.118µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:50:15.339728Z","caller":"traceutil/trace.go:171","msg":"trace[1779787798] transaction","detail":"{read_only:false; response_revision:6193; number_of_response:1; }","duration":"145.880445ms","start":"2026-05-27T19:50:15.19381Z","end":"2026-05-27T19:50:15.33969Z","steps":["trace[1779787798] 'process raft request' (duration: 145.520445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:15.339949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.134657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnlnf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T19:50:15.340201Z","caller":"traceutil/trace.go:171","msg":"trace[1409913737] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnlnf; range_end:; response_count:1; response_revision:6193; }","duration":"104.434876ms","start":"2026-05-27T19:50:15.235735Z","end":"2026-05-27T19:50:15.34017Z","steps":["trace[1409913737] 'agreement among raft nodes before linearized reading' (duration: 104.023413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:53:20.736618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.399695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-27T19:53:20.736704Z","caller":"traceutil/trace.go:171","msg":"trace[1971140833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7422; }","duration":"136.552159ms","start":"2026-05-27T19:53:20.600133Z","end":"2026-05-27T19:53:20.736685Z","steps":["trace[1971140833] 'range keys from in-memory index tree' (duration: 136.235481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:53:20.737498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.419499ms","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-27T19:53:20.737676Z","caller":"traceutil/trace.go:171","msg":"trace[754617748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7422; }","duration":"133.567533ms","start":"2026-05-27T19:53:20.604008Z","end":"2026-05-27T19:53:20.737575Z","steps":["trace[754617748] 'range keys from in-memory index tree' (duration: 133.258574ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:54:48.391613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5917} {"level":"info","ts":"2026-05-27T19:54:48.475418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5917,"took":"81.496237ms","hash":806605861,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":26259456,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-27T19:54:48.475506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":806605861,"revision":5917,"compact-revision":2978} {"level":"info","ts":"2026-05-27T19:55:21.355113Z","caller":"traceutil/trace.go:171","msg":"trace[1609003782] linearizableReadLoop","detail":"{readStateIndex:8735; appliedIndex:8734; }","duration":"146.524408ms","start":"2026-05-27T19:55:21.20857Z","end":"2026-05-27T19:55:21.355095Z","steps":["trace[1609003782] 'read index received' (duration: 146.420475ms)","trace[1609003782] 'applied index is now lower than readState.Index' (duration: 102.913µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:55:21.35528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.701093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnlnf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T19:55:21.35526Z","caller":"traceutil/trace.go:171","msg":"trace[360230228] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"240.331132ms","start":"2026-05-27T19:55:21.11489Z","end":"2026-05-27T19:55:21.355222Z","steps":["trace[360230228] 'process raft request' (duration: 240.076204ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:55:21.355317Z","caller":"traceutil/trace.go:171","msg":"trace[1298306156] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnlnf; range_end:; response_count:1; response_revision:8442; }","duration":"146.787975ms","start":"2026-05-27T19:55:21.208518Z","end":"2026-05-27T19:55:21.355306Z","steps":["trace[1298306156] 'agreement among raft nodes before linearized reading' (duration: 146.654811ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:55:21.52398Z","caller":"traceutil/trace.go:171","msg":"trace[509086821] linearizableReadLoop","detail":"{readStateIndex:8736; appliedIndex:8735; }","duration":"117.417316ms","start":"2026-05-27T19:55:21.406544Z","end":"2026-05-27T19:55:21.523962Z","steps":["trace[509086821] 'read index received' (duration: 117.270292ms)","trace[509086821] 'applied index is now lower than readState.Index' (duration: 146.384µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:55:21.524027Z","caller":"traceutil/trace.go:171","msg":"trace[571578317] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"150.904995ms","start":"2026-05-27T19:55:21.373091Z","end":"2026-05-27T19:55:21.523996Z","steps":["trace[571578317] 'process raft request' (duration: 150.745771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:55:21.524181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.626413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-27T19:55:21.524224Z","caller":"traceutil/trace.go:171","msg":"trace[1631455392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8443; }","duration":"117.722906ms","start":"2026-05-27T19:55:21.406492Z","end":"2026-05-27T19:55:21.524215Z","steps":["trace[1631455392] 'agreement among raft nodes before linearized reading' (duration: 117.568841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:55:21.524351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.233363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-27T19:55:21.524398Z","caller":"traceutil/trace.go:171","msg":"trace[299015953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8443; }","duration":"106.362596ms","start":"2026-05-27T19:55:21.418022Z","end":"2026-05-27T19:55:21.524385Z","steps":["trace[299015953] 'agreement among raft nodes before linearized reading' (duration: 106.182211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:55:21.524481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.303775ms","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-27T19:55:21.524538Z","caller":"traceutil/trace.go:171","msg":"trace[353625382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8443; }","duration":"106.414228ms","start":"2026-05-27T19:55:21.418109Z","end":"2026-05-27T19:55:21.524523Z","steps":["trace[353625382] 'agreement among raft nodes before linearized reading' (duration: 106.272504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:55:21.524554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.36168ms","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-27T19:55:21.524649Z","caller":"traceutil/trace.go:171","msg":"trace[881872122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8443; }","duration":"103.497473ms","start":"2026-05-27T19:55:21.421128Z","end":"2026-05-27T19:55:21.524626Z","steps":["trace[881872122] 'agreement among raft nodes before linearized reading' (duration: 103.147993ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:55:33.245335Z","caller":"traceutil/trace.go:171","msg":"trace[422211718] linearizableReadLoop","detail":"{readStateIndex:8800; appliedIndex:8799; }","duration":"154.771627ms","start":"2026-05-27T19:55:33.090537Z","end":"2026-05-27T19:55:33.245308Z","steps":["trace[422211718] 'read index received' (duration: 154.52377ms)","trace[422211718] 'applied index is now lower than readState.Index' (duration: 246.697µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:55:33.245554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.922241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:55:33.245612Z","caller":"traceutil/trace.go:171","msg":"trace[999405596] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8504; }","duration":"155.087486ms","start":"2026-05-27T19:55:33.090508Z","end":"2026-05-27T19:55:33.245596Z","steps":["trace[999405596] 'agreement among raft nodes before linearized reading' (duration: 154.910761ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:55:33.245711Z","caller":"traceutil/trace.go:171","msg":"trace[1789322641] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"256.317815ms","start":"2026-05-27T19:55:32.989283Z","end":"2026-05-27T19:55:33.245601Z","steps":["trace[1789322641] 'process raft request' (duration: 255.836992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:55:33.880558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.419294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:55:33.880936Z","caller":"traceutil/trace.go:171","msg":"trace[1444312360] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"269.602769ms","start":"2026-05-27T19:55:33.611285Z","end":"2026-05-27T19:55:33.880887Z","steps":["trace[1444312360] 'process raft request' (duration: 74.750372ms)","trace[1444312360] 'compare' (duration: 194.204388ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:55:33.881159Z","caller":"traceutil/trace.go:171","msg":"trace[2007964841] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"269.726033ms","start":"2026-05-27T19:55:33.611406Z","end":"2026-05-27T19:55:33.881132Z","steps":["trace[2007964841] 'process raft request' (duration: 269.566219ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:55:33.881053Z","caller":"traceutil/trace.go:171","msg":"trace[1244513067] transaction","detail":"{read_only:false; response_revision:8510; number_of_response:1; }","duration":"269.62449ms","start":"2026-05-27T19:55:33.611404Z","end":"2026-05-27T19:55:33.881029Z","steps":["trace[1244513067] 'process raft request' (duration: 269.279411ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:55:34.025767Z","caller":"traceutil/trace.go:171","msg":"trace[1224440963] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"358.14952ms","start":"2026-05-27T19:55:33.667592Z","end":"2026-05-27T19:55:34.025742Z","steps":["trace[1224440963] 'process raft request' (duration: 354.475954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:55:34.025883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:55:33.667569Z","time spent":"358.255242ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:56:01.695707Z","caller":"traceutil/trace.go:171","msg":"trace[1919768324] transaction","detail":"{read_only:false; response_revision:8709; number_of_response:1; }","duration":"344.070703ms","start":"2026-05-27T19:56:01.351612Z","end":"2026-05-27T19:56:01.695683Z","steps":["trace[1919768324] 'process raft request' (duration: 343.767083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:01.696036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:56:01.351596Z","time spent":"344.351721ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:56:01.857071Z","caller":"traceutil/trace.go:171","msg":"trace[959297079] linearizableReadLoop","detail":"{readStateIndex:9013; appliedIndex:9011; }","duration":"299.322099ms","start":"2026-05-27T19:56:01.557693Z","end":"2026-05-27T19:56:01.857015Z","steps":["trace[959297079] 'read index received' (duration: 137.694163ms)","trace[959297079] 'applied index is now lower than readState.Index' (duration: 161.626786ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:56:01.857137Z","caller":"traceutil/trace.go:171","msg":"trace[767233839] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"303.814598ms","start":"2026-05-27T19:56:01.553269Z","end":"2026-05-27T19:56:01.857083Z","steps":["trace[767233839] 'process raft request' (duration: 303.329774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:01.857359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:56:01.553232Z","time spent":"304.008973ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:56:01.857761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.598946ms","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-27T19:56:01.85784Z","caller":"traceutil/trace.go:171","msg":"trace[554784317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8710; }","duration":"300.189303ms","start":"2026-05-27T19:56:01.557631Z","end":"2026-05-27T19:56:01.85782Z","steps":["trace[554784317] 'agreement among raft nodes before linearized reading' (duration: 299.506243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:01.857883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:56:01.557602Z","time spent":"300.272545ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:56:01.858748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.014087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:56:01.858818Z","caller":"traceutil/trace.go:171","msg":"trace[1754075852] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8711; }","duration":"284.13915ms","start":"2026-05-27T19:56:01.574661Z","end":"2026-05-27T19:56:01.8588Z","steps":["trace[1754075852] 'agreement among raft nodes before linearized reading' (duration: 284.002156ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:56:01.859163Z","caller":"traceutil/trace.go:171","msg":"trace[198813039] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"155.125327ms","start":"2026-05-27T19:56:01.704026Z","end":"2026-05-27T19:56:01.859151Z","steps":["trace[198813039] 'process raft request' (duration: 154.421977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:01.859381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.72023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-27T19:56:01.859421Z","caller":"traceutil/trace.go:171","msg":"trace[723485548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8711; }","duration":"113.784212ms","start":"2026-05-27T19:56:01.745626Z","end":"2026-05-27T19:56:01.85941Z","steps":["trace[723485548] 'agreement among raft nodes before linearized reading' (duration: 113.661819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:01.85961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.203304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T19:56:01.859644Z","caller":"traceutil/trace.go:171","msg":"trace[2139119578] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:8711; }","duration":"114.280346ms","start":"2026-05-27T19:56:01.745355Z","end":"2026-05-27T19:56:01.859635Z","steps":["trace[2139119578] 'agreement among raft nodes before linearized reading' (duration: 114.193733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:01.8604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.541731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnlnf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T19:56:01.860473Z","caller":"traceutil/trace.go:171","msg":"trace[1258958902] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnlnf; range_end:; response_count:1; response_revision:8711; }","duration":"247.658514ms","start":"2026-05-27T19:56:01.612796Z","end":"2026-05-27T19:56:01.860455Z","steps":["trace[1258958902] 'agreement among raft nodes before linearized reading' (duration: 246.853311ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:56:06.180215Z","caller":"traceutil/trace.go:171","msg":"trace[1046485548] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"141.882984ms","start":"2026-05-27T19:56:06.038291Z","end":"2026-05-27T19:56:06.180174Z","steps":["trace[1046485548] 'process raft request' (duration: 141.668998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:06.40617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.982795ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:56:06.406316Z","caller":"traceutil/trace.go:171","msg":"trace[13893201] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8737; }","duration":"126.141499ms","start":"2026-05-27T19:56:06.280154Z","end":"2026-05-27T19:56:06.406295Z","steps":["trace[13893201] 'range keys from in-memory index tree' (duration: 125.963744ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:56:06.406615Z","caller":"traceutil/trace.go:171","msg":"trace[564839495] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"367.767188ms","start":"2026-05-27T19:56:06.038825Z","end":"2026-05-27T19:56:06.406592Z","steps":["trace[564839495] 'process raft request' (duration: 278.487535ms)","trace[564839495] 'compare' (duration: 88.817889ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:56:06.407026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:56:06.038776Z","time spent":"368.055856ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:56:06.407574Z","caller":"traceutil/trace.go:171","msg":"trace[1225568558] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"368.601372ms","start":"2026-05-27T19:56:06.038933Z","end":"2026-05-27T19:56:06.407534Z","steps":["trace[1225568558] 'process raft request' (duration: 368.365875ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:56:06.407655Z","caller":"traceutil/trace.go:171","msg":"trace[1020326548] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"266.87211ms","start":"2026-05-27T19:56:06.140765Z","end":"2026-05-27T19:56:06.407637Z","steps":["trace[1020326548] 'process raft request' (duration: 266.711056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:56:06.407809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:56:06.038912Z","time spent":"368.781358ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:56:14.766003Z","caller":"traceutil/trace.go:171","msg":"trace[519014443] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"125.585912ms","start":"2026-05-27T19:56:14.640364Z","end":"2026-05-27T19:56:14.76595Z","steps":["trace[519014443] 'process raft request' (duration: 125.274183ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:56:15.330419Z","caller":"traceutil/trace.go:171","msg":"trace[544315340] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"133.028838ms","start":"2026-05-27T19:56:15.197362Z","end":"2026-05-27T19:56:15.330391Z","steps":["trace[544315340] 'process raft request' (duration: 132.863654ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:56:31.780998Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000244c.wal"} {"level":"info","ts":"2026-05-27T19:57:36.849984Z","caller":"traceutil/trace.go:171","msg":"trace[1454983532] transaction","detail":"{read_only:false; response_revision:9606; number_of_response:1; }","duration":"166.456451ms","start":"2026-05-27T19:57:36.683495Z","end":"2026-05-27T19:57:36.849951Z","steps":["trace[1454983532] 'process raft request' (duration: 166.239055ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:57:36.90579Z","caller":"traceutil/trace.go:171","msg":"trace[2006593493] transaction","detail":"{read_only:false; response_revision:9607; number_of_response:1; }","duration":"164.523584ms","start":"2026-05-27T19:57:36.741219Z","end":"2026-05-27T19:57:36.905742Z","steps":["trace[2006593493] 'process raft request' (duration: 164.171244ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:57:36.916179Z","caller":"traceutil/trace.go:171","msg":"trace[1407614613] linearizableReadLoop","detail":"{readStateIndex:9938; appliedIndex:9936; }","duration":"135.946109ms","start":"2026-05-27T19:57:36.780194Z","end":"2026-05-27T19:57:36.91614Z","steps":["trace[1407614613] 'read index received' (duration: 69.575151ms)","trace[1407614613] 'applied index is now lower than readState.Index' (duration: 66.365848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:57:36.916519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.302579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:57:36.91662Z","caller":"traceutil/trace.go:171","msg":"trace[1217311407] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:9607; }","duration":"136.462344ms","start":"2026-05-27T19:57:36.780135Z","end":"2026-05-27T19:57:36.916598Z","steps":["trace[1217311407] 'agreement among raft nodes before linearized reading' (duration: 136.285729ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:57:44.454656Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"19a81b20c4b111c3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T19:57:44.460272Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-27T19:57:44.460433Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-27T19:59:48.398496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8094} {"level":"info","ts":"2026-05-27T19:59:48.459046Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8094,"took":"58.306843ms","hash":958994156,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":28594176,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T19:59:48.45911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":958994156,"revision":8094,"compact-revision":5917} {"level":"info","ts":"2026-05-27T20:02:54.758662Z","caller":"traceutil/trace.go:171","msg":"trace[562686771] transaction","detail":"{read_only:false; response_revision:11927; number_of_response:1; }","duration":"140.022808ms","start":"2026-05-27T20:02:54.618604Z","end":"2026-05-27T20:02:54.758627Z","steps":["trace[562686771] 'process raft request' (duration: 61.323359ms)","trace[562686771] 'compare' (duration: 78.513664ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:03:14.431347Z","caller":"traceutil/trace.go:171","msg":"trace[231511233] linearizableReadLoop","detail":"{readStateIndex:12443; appliedIndex:12442; }","duration":"194.51198ms","start":"2026-05-27T20:03:14.236796Z","end":"2026-05-27T20:03:14.431308Z","steps":["trace[231511233] 'read index received' (duration: 194.269923ms)","trace[231511233] 'applied index is now lower than readState.Index' (duration: 240.747µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:03:14.431817Z","caller":"traceutil/trace.go:171","msg":"trace[389476882] transaction","detail":"{read_only:false; response_revision:12033; number_of_response:1; }","duration":"246.295533ms","start":"2026-05-27T20:03:14.185484Z","end":"2026-05-27T20:03:14.431779Z","steps":["trace[389476882] 'process raft request' (duration: 245.610934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:03:14.432029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.20559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-27T20:03:14.432117Z","caller":"traceutil/trace.go:171","msg":"trace[1798009875] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12033; }","duration":"195.359165ms","start":"2026-05-27T20:03:14.236735Z","end":"2026-05-27T20:03:14.432095Z","steps":["trace[1798009875] 'agreement among raft nodes before linearized reading' (duration: 195.058736ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:03:14.439252Z","caller":"traceutil/trace.go:171","msg":"trace[1294120689] transaction","detail":"{read_only:false; response_revision:12034; number_of_response:1; }","duration":"108.257182ms","start":"2026-05-27T20:03:14.330955Z","end":"2026-05-27T20:03:14.439212Z","steps":["trace[1294120689] 'process raft request' (duration: 108.012275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:03:14.439318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.596405ms","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-27T20:03:14.439399Z","caller":"traceutil/trace.go:171","msg":"trace[1404274547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12034; }","duration":"184.74902ms","start":"2026-05-27T20:03:14.254628Z","end":"2026-05-27T20:03:14.439377Z","steps":["trace[1404274547] 'agreement among raft nodes before linearized reading' (duration: 184.542794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:03:14.439542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.246465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T20:03:14.439612Z","caller":"traceutil/trace.go:171","msg":"trace[1677782723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12034; }","duration":"166.366599ms","start":"2026-05-27T20:03:14.273225Z","end":"2026-05-27T20:03:14.439592Z","steps":["trace[1677782723] 'agreement among raft nodes before linearized reading' (duration: 166.131242ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:04:48.40519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10505} {"level":"info","ts":"2026-05-27T20:04:48.489957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10505,"took":"82.152249ms","hash":357233635,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":26030080,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-27T20:04:48.490058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":357233635,"revision":10505,"compact-revision":8094} {"level":"info","ts":"2026-05-27T20:09:16.883978Z","caller":"traceutil/trace.go:171","msg":"trace[1502729953] linearizableReadLoop","detail":"{readStateIndex:15012; appliedIndex:15011; }","duration":"148.582164ms","start":"2026-05-27T20:09:16.735361Z","end":"2026-05-27T20:09:16.883943Z","steps":["trace[1502729953] 'read index received' (duration: 148.343817ms)","trace[1502729953] 'applied index is now lower than readState.Index' (duration: 236.617µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:09:16.884042Z","caller":"traceutil/trace.go:171","msg":"trace[816815593] transaction","detail":"{read_only:false; response_revision:14517; number_of_response:1; }","duration":"150.556241ms","start":"2026-05-27T20:09:16.733437Z","end":"2026-05-27T20:09:16.883993Z","steps":["trace[816815593] 'process raft request' (duration: 150.291133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:09:16.88439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.965917ms","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-27T20:09:16.884503Z","caller":"traceutil/trace.go:171","msg":"trace[1226513493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14517; }","duration":"149.176612ms","start":"2026-05-27T20:09:16.735303Z","end":"2026-05-27T20:09:16.88448Z","steps":["trace[1226513493] 'agreement among raft nodes before linearized reading' (duration: 148.804772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:09:16.884789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.379119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T20:09:16.884919Z","caller":"traceutil/trace.go:171","msg":"trace[439486811] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:14518; }","duration":"132.588194ms","start":"2026-05-27T20:09:16.752303Z","end":"2026-05-27T20:09:16.884891Z","steps":["trace[439486811] 'agreement among raft nodes before linearized reading' (duration: 132.341307ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:09:16.884689Z","caller":"traceutil/trace.go:171","msg":"trace[1583242241] transaction","detail":"{read_only:false; response_revision:14518; number_of_response:1; }","duration":"147.067882ms","start":"2026-05-27T20:09:16.737604Z","end":"2026-05-27T20:09:16.884672Z","steps":["trace[1583242241] 'process raft request' (duration: 146.858126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:09:16.887134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.060643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-27T20:09:16.887206Z","caller":"traceutil/trace.go:171","msg":"trace[1531336993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14518; }","duration":"119.157717ms","start":"2026-05-27T20:09:16.768031Z","end":"2026-05-27T20:09:16.887189Z","steps":["trace[1531336993] 'agreement among raft nodes before linearized reading' (duration: 116.628344ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:09:48.415372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12663} {"level":"info","ts":"2026-05-27T20:09:48.498017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12663,"took":"79.377169ms","hash":1323843699,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":27250688,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-27T20:09:48.498095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1323843699,"revision":12663,"compact-revision":10505} {"level":"info","ts":"2026-05-27T20:09:58.500755Z","caller":"traceutil/trace.go:171","msg":"trace[33289782] transaction","detail":"{read_only:false; response_revision:14755; number_of_response:1; }","duration":"118.801445ms","start":"2026-05-27T20:09:58.381918Z","end":"2026-05-27T20:09:58.50072Z","steps":["trace[33289782] 'process raft request' (duration: 118.598429ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:22.94796Z","caller":"traceutil/trace.go:171","msg":"trace[1324752707] linearizableReadLoop","detail":"{readStateIndex:15592; appliedIndex:15591; }","duration":"171.827414ms","start":"2026-05-27T20:10:22.776104Z","end":"2026-05-27T20:10:22.947931Z","steps":["trace[1324752707] 'read index received' (duration: 171.544246ms)","trace[1324752707] 'applied index is now lower than readState.Index' (duration: 281.918µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:10:22.948022Z","caller":"traceutil/trace.go:171","msg":"trace[237908047] transaction","detail":"{read_only:false; response_revision:15073; number_of_response:1; }","duration":"246.21783ms","start":"2026-05-27T20:10:22.701761Z","end":"2026-05-27T20:10:22.947978Z","steps":["trace[237908047] 'process raft request' (duration: 245.986843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:10:22.948313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.181364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-27T20:10:22.948403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.9071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T20:10:22.948497Z","caller":"traceutil/trace.go:171","msg":"trace[1187150958] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:15073; }","duration":"152.084715ms","start":"2026-05-27T20:10:22.79639Z","end":"2026-05-27T20:10:22.948475Z","steps":["trace[1187150958] 'agreement among raft nodes before linearized reading' (duration: 151.882729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:10:22.948599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.388838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnlnf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T20:10:22.948646Z","caller":"traceutil/trace.go:171","msg":"trace[1615542995] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnlnf; range_end:; response_count:1; response_revision:15073; }","duration":"139.46643ms","start":"2026-05-27T20:10:22.809164Z","end":"2026-05-27T20:10:22.948631Z","steps":["trace[1615542995] 'agreement among raft nodes before linearized reading' (duration: 139.303415ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:22.948419Z","caller":"traceutil/trace.go:171","msg":"trace[1382369374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15073; }","duration":"172.355249ms","start":"2026-05-27T20:10:22.776039Z","end":"2026-05-27T20:10:22.948394Z","steps":["trace[1382369374] 'agreement among raft nodes before linearized reading' (duration: 172.081432ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:10:23.08938Z","caller":"traceutil/trace.go:171","msg":"trace[442775673] transaction","detail":"{read_only:false; response_revision:15074; number_of_response:1; }","duration":"136.947289ms","start":"2026-05-27T20:10:22.952407Z","end":"2026-05-27T20:10:23.089354Z","steps":["trace[442775673] 'process raft request' (duration: 122.823461ms)","trace[442775673] 'compare' (duration: 13.947032ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:10:23.089566Z","caller":"traceutil/trace.go:171","msg":"trace[739752337] linearizableReadLoop","detail":"{readStateIndex:15594; appliedIndex:15592; }","duration":"135.025924ms","start":"2026-05-27T20:10:22.954505Z","end":"2026-05-27T20:10:23.089531Z","steps":["trace[739752337] 'read index received' (duration: 120.68902ms)","trace[739752337] 'applied index is now lower than readState.Index' (duration: 14.334424ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T20:10:23.089687Z","caller":"traceutil/trace.go:171","msg":"trace[1814096889] transaction","detail":"{read_only:false; response_revision:15075; number_of_response:1; }","duration":"135.816027ms","start":"2026-05-27T20:10:22.953829Z","end":"2026-05-27T20:10:23.089645Z","steps":["trace[1814096889] 'process raft request' (duration: 135.485058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:10:23.090331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.843868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:637655"} {"level":"info","ts":"2026-05-27T20:10:23.090383Z","caller":"traceutil/trace.go:171","msg":"trace[135762954] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:15075; }","duration":"135.92618ms","start":"2026-05-27T20:10:22.954444Z","end":"2026-05-27T20:10:23.090371Z","steps":["trace[135762954] 'agreement among raft nodes before linearized reading' (duration: 135.254821ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:14:48.422603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14685} {"level":"info","ts":"2026-05-27T20:14:48.505824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14685,"took":"80.114147ms","hash":1979014432,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T20:14:48.505908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1979014432,"revision":14685,"compact-revision":12663} {"level":"info","ts":"2026-05-27T20:19:48.429293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17130} {"level":"info","ts":"2026-05-27T20:19:48.523053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17130,"took":"89.897155ms","hash":1563427045,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":29581312,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T20:19:48.523109Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1563427045,"revision":17130,"compact-revision":14685} {"level":"info","ts":"2026-05-27T20:20:26.554592Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"19a81b20c4b111c3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T20:20:26.561493Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-27T20:20:26.562404Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-27T20:21:03.556586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040901288416000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:21:04.05721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040901288416000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:21:04.558266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040901288416000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:21:05.058865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040901288416000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:21:05.559824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040901288416000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:21:05.924651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999904664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-27T20:21:05.924734Z","caller":"traceutil/trace.go:171","msg":"trace[534497665] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000043787s","start":"2026-05-27T20:21:03.924668Z","end":"2026-05-27T20:21:05.924712Z","steps":["trace[534497665] 'agreement among raft nodes before linearized reading' (duration: 1.999946185s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:05.92478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.924647Z","time spent":"2.000121709s","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/27 20:21:05 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-27T20:21:06.05997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040901288416000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:21:06.56054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280040901288416000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T20:21:06.834914Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.127149421s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T20:21:06.835293Z","caller":"traceutil/trace.go:171","msg":"trace[162700150] transaction","detail":"{read_only:false; response_revision:19522; number_of_response:1; }","duration":"3.780020681s","start":"2026-05-27T20:21:03.055255Z","end":"2026-05-27T20:21:06.835276Z","steps":["trace[162700150] 'process raft request' (duration: 3.779900638s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.835403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.055228Z","time spent":"3.780119583s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:21:06.841548Z","caller":"traceutil/trace.go:171","msg":"trace[676760239] transaction","detail":"{read_only:false; response_revision:19523; number_of_response:1; }","duration":"3.786134296s","start":"2026-05-27T20:21:03.055388Z","end":"2026-05-27T20:21:06.841523Z","steps":["trace[676760239] 'process raft request' (duration: 3.785855778s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.841689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.055365Z","time spent":"3.786245529s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:21:06.841962Z","caller":"traceutil/trace.go:171","msg":"trace[2121630194] transaction","detail":"{read_only:false; response_revision:19524; number_of_response:1; }","duration":"3.781656587s","start":"2026-05-27T20:21:03.060277Z","end":"2026-05-27T20:21:06.841933Z","steps":["trace[2121630194] 'process raft request' (duration: 3.781200734s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.842127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.060251Z","time spent":"3.781807341s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:21:06.842314Z","caller":"traceutil/trace.go:171","msg":"trace[323700342] transaction","detail":"{read_only:false; response_revision:19525; number_of_response:1; }","duration":"3.781156433s","start":"2026-05-27T20:21:03.061139Z","end":"2026-05-27T20:21:06.842296Z","steps":["trace[323700342] 'process raft request' (duration: 3.780605838s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.842461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.061116Z","time spent":"3.781237565s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:21:06.842682Z","caller":"traceutil/trace.go:171","msg":"trace[770230791] transaction","detail":"{read_only:false; response_revision:19526; number_of_response:1; }","duration":"3.781406079s","start":"2026-05-27T20:21:03.061234Z","end":"2026-05-27T20:21:06.84264Z","steps":["trace[770230791] 'process raft request' (duration: 3.780870994s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.842945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.061227Z","time spent":"3.781601394s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:21:06.843053Z","caller":"traceutil/trace.go:171","msg":"trace[639283336] transaction","detail":"{read_only:false; response_revision:19527; number_of_response:1; }","duration":"3.780640387s","start":"2026-05-27T20:21:03.062362Z","end":"2026-05-27T20:21:06.843002Z","steps":["trace[639283336] 'process raft request' (duration: 3.779828644s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:21:06.843061Z","caller":"traceutil/trace.go:171","msg":"trace[663777041] linearizableReadLoop","detail":"{readStateIndex:20203; appliedIndex:20194; }","duration":"3.787555937s","start":"2026-05-27T20:21:03.055461Z","end":"2026-05-27T20:21:06.843016Z","steps":["trace[663777041] 'read index received' (duration: 3.779691962s)","trace[663777041] 'applied index is now lower than readState.Index' (duration: 7.862155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T20:21:06.843475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.062343Z","time spent":"3.780893696s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:21:06.843463Z","caller":"traceutil/trace.go:171","msg":"trace[1699618907] transaction","detail":"{read_only:false; response_revision:19528; number_of_response:1; }","duration":"2.940196025s","start":"2026-05-27T20:21:03.9032Z","end":"2026-05-27T20:21:06.843396Z","steps":["trace[1699618907] 'process raft request' (duration: 2.9396708s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.843601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.788236997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-27T20:21:06.843612Z","caller":"traceutil/trace.go:171","msg":"trace[2000871282] transaction","detail":"{read_only:false; response_revision:19529; number_of_response:1; }","duration":"2.828546457s","start":"2026-05-27T20:21:04.015012Z","end":"2026-05-27T20:21:06.843558Z","steps":["trace[2000871282] 'process raft request' (duration: 2.827906978s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.844087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.700623368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:21:06.844112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.090752842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-hs2hr\" ","response":"range_response_count:1 size:14278"} {"level":"info","ts":"2026-05-27T20:21:06.844133Z","caller":"traceutil/trace.go:171","msg":"trace[1500765027] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:19530; }","duration":"3.700724601s","start":"2026-05-27T20:21:03.143399Z","end":"2026-05-27T20:21:06.844123Z","steps":["trace[1500765027] 'agreement among raft nodes before linearized reading' (duration: 3.700666039s)"],"step_count":1} {"level":"info","ts":"2026-05-27T20:21:06.844161Z","caller":"traceutil/trace.go:171","msg":"trace[1983207064] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-hs2hr; range_end:; response_count:1; response_revision:19530; }","duration":"3.090875225s","start":"2026-05-27T20:21:03.753266Z","end":"2026-05-27T20:21:06.844141Z","steps":["trace[1983207064] 'agreement among raft nodes before linearized reading' (duration: 3.090756201s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.844196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.070407017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-05-27T20:21:06.844208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.753228Z","time spent":"3.090972967s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14300,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-hs2hr\" "} {"level":"warn","ts":"2026-05-27T20:21:06.8442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:04.014979Z","time spent":"2.829138564s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T20:21:06.844242Z","caller":"traceutil/trace.go:171","msg":"trace[850307268] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:19530; }","duration":"3.070470799s","start":"2026-05-27T20:21:03.773757Z","end":"2026-05-27T20:21:06.844227Z","steps":["trace[850307268] 'agreement among raft nodes before linearized reading' (duration: 3.070378806s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.844289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.773745Z","time spent":"3.070537121s","remote":"127.0.0.1:41226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-05-27T20:21:06.843655Z","caller":"traceutil/trace.go:171","msg":"trace[313143127] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19530; }","duration":"3.788303308s","start":"2026-05-27T20:21:03.055337Z","end":"2026-05-27T20:21:06.84364Z","steps":["trace[313143127] 'agreement among raft nodes before linearized reading' (duration: 3.788197215s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.844391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.055327Z","time spent":"3.78905245s","remote":"127.0.0.1:41240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T20:21:06.844167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.143367Z","time spent":"3.700792073s","remote":"127.0.0.1:41220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:21:06.843183Z","caller":"traceutil/trace.go:171","msg":"trace[1819113496] transaction","detail":"{read_only:false; response_revision:19530; number_of_response:1; }","duration":"552.606922ms","start":"2026-05-27T20:21:06.290553Z","end":"2026-05-27T20:21:06.843159Z","steps":["trace[1819113496] 'process raft request' (duration: 552.401895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.844653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:06.290527Z","time spent":"554.059952ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:21:06.843671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.903153Z","time spent":"2.940418571s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T20:21:06.843495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.788215736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jmbdv\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-27T20:21:06.844855Z","caller":"traceutil/trace.go:171","msg":"trace[798427866] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jmbdv; range_end:; response_count:1; response_revision:19530; }","duration":"3.789570575s","start":"2026-05-27T20:21:03.055249Z","end":"2026-05-27T20:21:06.84482Z","steps":["trace[798427866] 'agreement among raft nodes before linearized reading' (duration: 3.788105223s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.844881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.782602574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:952342"} {"level":"warn","ts":"2026-05-27T20:21:06.844955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.055239Z","time spent":"3.789662157s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-jmbdv\" "} {"level":"info","ts":"2026-05-27T20:21:06.844959Z","caller":"traceutil/trace.go:171","msg":"trace[1121950424] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19530; }","duration":"3.782763518s","start":"2026-05-27T20:21:03.062178Z","end":"2026-05-27T20:21:06.844941Z","steps":["trace[1121950424] 'agreement among raft nodes before linearized reading' (duration: 3.781295107s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.843695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.453156858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T20:21:06.845019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.062159Z","time spent":"3.782843591s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":952364,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-27T20:21:06.845095Z","caller":"traceutil/trace.go:171","msg":"trace[1923901438] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:19530; }","duration":"2.454577159s","start":"2026-05-27T20:21:04.390477Z","end":"2026-05-27T20:21:06.845055Z","steps":["trace[1923901438] 'agreement among raft nodes before linearized reading' (duration: 2.453153429s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.843755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780967851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-05-27T20:21:06.845166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:04.390458Z","time spent":"2.454691742s","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-27T20:21:06.843796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454318534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:21:06.845209Z","caller":"traceutil/trace.go:171","msg":"trace[212879920] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19530; }","duration":"1.782427822s","start":"2026-05-27T20:21:05.062766Z","end":"2026-05-27T20:21:06.845193Z","steps":["trace[212879920] 'agreement among raft nodes before linearized reading' (duration: 1.78093398s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.844686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.787958459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:21:06.845286Z","caller":"traceutil/trace.go:171","msg":"trace[1981592285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19530; }","duration":"1.455826156s","start":"2026-05-27T20:21:05.389435Z","end":"2026-05-27T20:21:06.845261Z","steps":["trace[1981592285] 'agreement among raft nodes before linearized reading' (duration: 1.454340784s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.845336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.062758Z","time spent":"1.782558157s","remote":"127.0.0.1:41240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T20:21:06.84385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78196883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-27T20:21:06.846767Z","caller":"traceutil/trace.go:171","msg":"trace[289308486] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19530; }","duration":"1.784877174s","start":"2026-05-27T20:21:05.061874Z","end":"2026-05-27T20:21:06.846751Z","steps":["trace[289308486] 'agreement among raft nodes before linearized reading' (duration: 1.781859087s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.846824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.061841Z","time spent":"1.784965866s","remote":"127.0.0.1:41240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T20:21:06.843871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.967509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T20:21:06.846995Z","caller":"traceutil/trace.go:171","msg":"trace[2034348069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19530; }","duration":"922.12692ms","start":"2026-05-27T20:21:05.924855Z","end":"2026-05-27T20:21:06.846982Z","steps":["trace[2034348069] 'agreement among raft nodes before linearized reading' (duration: 919.00039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.847033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.924837Z","time spent":"922.186081ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T20:21:06.845368Z","caller":"traceutil/trace.go:171","msg":"trace[377410721] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:19530; }","duration":"3.789990907s","start":"2026-05-27T20:21:03.055364Z","end":"2026-05-27T20:21:06.845355Z","steps":["trace[377410721] 'agreement among raft nodes before linearized reading' (duration: 3.787959239s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.843856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130243211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T20:21:06.847178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.055344Z","time spent":"3.791813139s","remote":"127.0.0.1:41078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-05-27T20:21:06.847211Z","caller":"traceutil/trace.go:171","msg":"trace[725597791] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:19530; }","duration":"1.133637698s","start":"2026-05-27T20:21:05.713562Z","end":"2026-05-27T20:21:06.8472Z","steps":["trace[725597791] 'agreement among raft nodes before linearized reading' (duration: 1.130158379s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.847248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.713541Z","time spent":"1.13369538s","remote":"127.0.0.1:41366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":76,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-27T20:21:06.843858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.788556016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T20:21:06.847652Z","caller":"traceutil/trace.go:171","msg":"trace[286290604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19530; }","duration":"3.792369835s","start":"2026-05-27T20:21:03.055265Z","end":"2026-05-27T20:21:06.847635Z","steps":["trace[286290604] 'agreement among raft nodes before linearized reading' (duration: 3.788384051s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.847699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.055255Z","time spent":"3.792430827s","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T20:21:06.843933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.782254628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-27T20:21:06.847784Z","caller":"traceutil/trace.go:171","msg":"trace[2006218780] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19530; }","duration":"1.786119328s","start":"2026-05-27T20:21:05.061649Z","end":"2026-05-27T20:21:06.847769Z","steps":["trace[2006218780] 'agreement among raft nodes before linearized reading' (duration: 1.782222897s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.847828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.06164Z","time spent":"1.78617567s","remote":"127.0.0.1:41240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T20:21:06.843959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.961097516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jmbdv\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-27T20:21:06.847902Z","caller":"traceutil/trace.go:171","msg":"trace[82512921] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jmbdv; range_end:; response_count:1; response_revision:19530; }","duration":"2.9650856s","start":"2026-05-27T20:21:03.882801Z","end":"2026-05-27T20:21:06.847887Z","steps":["trace[82512921] 'agreement among raft nodes before linearized reading' (duration: 2.961099686s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.847954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.882771Z","time spent":"2.965168532s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-jmbdv\" "} {"level":"warn","ts":"2026-05-27T20:21:06.843984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.79033714s","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-27T20:21:06.848072Z","caller":"traceutil/trace.go:171","msg":"trace[1004747304] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:19530; }","duration":"1.794473889s","start":"2026-05-27T20:21:05.053584Z","end":"2026-05-27T20:21:06.848058Z","steps":["trace[1004747304] 'agreement among raft nodes before linearized reading' (duration: 1.790371641s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.848116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.053553Z","time spent":"1.794545731s","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-27T20:21:06.843998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.880635673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnlnf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-27T20:21:06.848203Z","caller":"traceutil/trace.go:171","msg":"trace[1864736539] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnlnf; range_end:; response_count:1; response_revision:19530; }","duration":"2.884879364s","start":"2026-05-27T20:21:03.963297Z","end":"2026-05-27T20:21:06.848177Z","steps":["trace[1864736539] 'agreement among raft nodes before linearized reading' (duration: 2.880617592s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.848247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.963271Z","time spent":"2.884961867s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-vnlnf\" "} {"level":"warn","ts":"2026-05-27T20:21:06.844035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.939532845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-v5v5m\" ","response":"range_response_count:1 size:18646"} {"level":"info","ts":"2026-05-27T20:21:06.848367Z","caller":"traceutil/trace.go:171","msg":"trace[1705817357] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-v5v5m; range_end:; response_count:1; response_revision:19530; }","duration":"2.943899771s","start":"2026-05-27T20:21:03.904446Z","end":"2026-05-27T20:21:06.848346Z","steps":["trace[1705817357] 'agreement among raft nodes before linearized reading' (duration: 2.939531066s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.848425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.904431Z","time spent":"2.943975333s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18668,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-v5v5m\" "} {"level":"warn","ts":"2026-05-27T20:21:06.844062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.783327289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-27T20:21:06.848494Z","caller":"traceutil/trace.go:171","msg":"trace[734561999] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19530; }","duration":"1.787791557s","start":"2026-05-27T20:21:05.060686Z","end":"2026-05-27T20:21:06.848478Z","steps":["trace[734561999] 'agreement among raft nodes before linearized reading' (duration: 1.783217516s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.848541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.060662Z","time spent":"1.787865609s","remote":"127.0.0.1:41240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T20:21:06.84409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.744989195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-n49p7\" ","response":"range_response_count:1 size:18672"} {"level":"info","ts":"2026-05-27T20:21:06.848676Z","caller":"traceutil/trace.go:171","msg":"trace[1964001611] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-n49p7; range_end:; response_count:1; response_revision:19530; }","duration":"2.749660459s","start":"2026-05-27T20:21:04.099008Z","end":"2026-05-27T20:21:06.848668Z","steps":["trace[1964001611] 'agreement among raft nodes before linearized reading' (duration: 2.745010476s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.848702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:04.098983Z","time spent":"2.749709572s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18694,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-n49p7\" "} {"level":"warn","ts":"2026-05-27T20:21:06.845506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:05.38941Z","time spent":"1.456046793s","remote":"127.0.0.1:41082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T20:21:06.845579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.783100258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:952342"} {"level":"info","ts":"2026-05-27T20:21:06.848965Z","caller":"traceutil/trace.go:171","msg":"trace[1874444226] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19530; }","duration":"3.786514687s","start":"2026-05-27T20:21:03.062441Z","end":"2026-05-27T20:21:06.848956Z","steps":["trace[1874444226] 'agreement among raft nodes before linearized reading' (duration: 3.781664688s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T20:21:06.849025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T20:21:03.06243Z","time spent":"3.786580089s","remote":"127.0.0.1:41252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":952364,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-27T20:24:48.435611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19143} {"level":"info","ts":"2026-05-27T20:24:48.525506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19143,"took":"87.457064ms","hash":1258612854,"current-db-size-bytes":56479744,"current-db-size":"56 MB","current-db-size-in-use-bytes":24367104,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-27T20:24:48.525584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1258612854,"revision":19143,"compact-revision":17130}