{"level":"warn","ts":"2026-05-28T15:22:19.007569Z","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-28T15:22:19.007693Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-28T15:22:19.007778Z","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-28T15:22:19.007793Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-28T15:22:19.007814Z","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-28T15:22:19.008688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-05-28T15:22:19.00879Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-28T15:22:19.013194Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.140641ms"} {"level":"info","ts":"2026-05-28T15:22:19.02487Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-05-28T15:22:19.024956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T15:22:19.024975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-05-28T15:22:19.024986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T15:22:19.024995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-05-28T15:22:19.025037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-05-28T15:22:19.031786Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T15:22:19.033865Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T15:22:19.035003Z","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-28T15:22:19.037042Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T15:22:19.03721Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T15:22:19.037301Z","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-28T15:22:19.037464Z","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-28T15:22:19.037496Z","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-28T15:22:19.03921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-05-28T15:22:19.039484Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-28T15:22:19.044219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-28T15:22:19.044254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-28T15:22:19.044115Z","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-28T15:22:19.044587Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T15:22:19.04463Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T15:22:19.125762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T15:22:19.125813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T15:22:19.125849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-05-28T15:22:19.125869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-05-28T15:22:19.125925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-28T15:22:19.125949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-05-28T15:22:19.125996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-28T15:22:19.126903Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T15:22:19.12774Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T15:22:19.12794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T15:22:19.127991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T15:22:19.12831Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T15:22:19.128357Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T15:22:19.128448Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T15:22:19.128461Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T15:22:19.128559Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T15:22:19.131289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-05-28T15:22:19.131877Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T15:22:45.24371Z","caller":"traceutil/trace.go:171","msg":"trace[977656920] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"163.010457ms","start":"2026-05-28T15:22:45.080662Z","end":"2026-05-28T15:22:45.243672Z","steps":["trace[977656920] 'process raft request' (duration: 162.67436ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:23:09.622967Z","caller":"traceutil/trace.go:171","msg":"trace[256714031] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"155.176312ms","start":"2026-05-28T15:23:09.467757Z","end":"2026-05-28T15:23:09.622933Z","steps":["trace[256714031] 'read index received' (duration: 154.978978ms)","trace[256714031] 'applied index is now lower than readState.Index' (duration: 195.384µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:23:09.623096Z","caller":"traceutil/trace.go:171","msg":"trace[1363993488] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"155.715322ms","start":"2026-05-28T15:23:09.467362Z","end":"2026-05-28T15:23:09.623077Z","steps":["trace[1363993488] 'process raft request' (duration: 155.308284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:23:09.623224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.439177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T15:23:09.623337Z","caller":"traceutil/trace.go:171","msg":"trace[729086545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"155.590639ms","start":"2026-05-28T15:23:09.467734Z","end":"2026-05-28T15:23:09.623325Z","steps":["trace[729086545] 'agreement among raft nodes before linearized reading' (duration: 155.320654ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:23:09.683006Z","caller":"traceutil/trace.go:171","msg":"trace[1101202043] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"113.772643ms","start":"2026-05-28T15:23:09.569188Z","end":"2026-05-28T15:23:09.682961Z","steps":["trace[1101202043] 'process raft request' (duration: 108.865578ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:23:10.133018Z","caller":"traceutil/trace.go:171","msg":"trace[824378098] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"430.639972ms","start":"2026-05-28T15:23:09.702334Z","end":"2026-05-28T15:23:10.132974Z","steps":["trace[824378098] 'process raft request' (duration: 425.728567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:23:10.133893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T15:23:09.702303Z","time spent":"430.808004ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T15:23:10.136876Z","caller":"traceutil/trace.go:171","msg":"trace[965099375] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"280.177161ms","start":"2026-05-28T15:23:09.856666Z","end":"2026-05-28T15:23:10.136843Z","steps":["trace[965099375] 'read index received' (duration: 271.408342ms)","trace[965099375] 'applied index is now lower than readState.Index' (duration: 8.767799ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:23:10.137033Z","caller":"traceutil/trace.go:171","msg":"trace[486204498] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"391.082318ms","start":"2026-05-28T15:23:09.745933Z","end":"2026-05-28T15:23:10.137016Z","steps":["trace[486204498] 'process raft request' (duration: 390.789983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:23:10.137105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T15:23:09.745909Z","time spent":"391.167069ms","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T15:23:10.137133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.449336ms","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-28T15:23:10.137179Z","caller":"traceutil/trace.go:171","msg":"trace[894263549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"280.553808ms","start":"2026-05-28T15:23:09.856611Z","end":"2026-05-28T15:23:10.137165Z","steps":["trace[894263549] 'agreement among raft nodes before linearized reading' (duration: 280.348034ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:24:38.677682Z","caller":"traceutil/trace.go:171","msg":"trace[1707268706] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"125.043135ms","start":"2026-05-28T15:24:38.552611Z","end":"2026-05-28T15:24:38.677654Z","steps":["trace[1707268706] 'process raft request' (duration: 124.908252ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:24:50.842543Z","caller":"traceutil/trace.go:171","msg":"trace[1871462170] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"117.831295ms","start":"2026-05-28T15:24:50.724686Z","end":"2026-05-28T15:24:50.842517Z","steps":["trace[1871462170] 'process raft request' (duration: 117.684272ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:25:29.519597Z","caller":"traceutil/trace.go:171","msg":"trace[1357219031] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"136.561428ms","start":"2026-05-28T15:25:29.383008Z","end":"2026-05-28T15:25:29.519569Z","steps":["trace[1357219031] 'process raft request' (duration: 136.400155ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:25:29.658833Z","caller":"traceutil/trace.go:171","msg":"trace[1362829357] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"135.60511ms","start":"2026-05-28T15:25:29.523193Z","end":"2026-05-28T15:25:29.658798Z","steps":["trace[1362829357] 'process raft request' (duration: 113.807507ms)","trace[1362829357] 'compare' (duration: 21.560789ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:29:06.700347Z","caller":"traceutil/trace.go:171","msg":"trace[148075956] linearizableReadLoop","detail":"{readStateIndex:3899; appliedIndex:3898; }","duration":"160.882373ms","start":"2026-05-28T15:29:06.539439Z","end":"2026-05-28T15:29:06.700322Z","steps":["trace[148075956] 'read index received' (duration: 160.803572ms)","trace[148075956] 'applied index is now lower than readState.Index' (duration: 77.811µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T15:29:06.700563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.114897ms","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-28T15:29:06.700602Z","caller":"traceutil/trace.go:171","msg":"trace[435440787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3758; }","duration":"161.218609ms","start":"2026-05-28T15:29:06.539373Z","end":"2026-05-28T15:29:06.700592Z","steps":["trace[435440787] 'agreement among raft nodes before linearized reading' (duration: 161.067676ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:29:06.700765Z","caller":"traceutil/trace.go:171","msg":"trace[2093520953] transaction","detail":"{read_only:false; response_revision:3758; number_of_response:1; }","duration":"206.646591ms","start":"2026-05-28T15:29:06.494073Z","end":"2026-05-28T15:29:06.700719Z","steps":["trace[2093520953] 'process raft request' (duration: 206.092721ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:29:06.763766Z","caller":"traceutil/trace.go:171","msg":"trace[387485134] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"100.786099ms","start":"2026-05-28T15:29:06.66295Z","end":"2026-05-28T15:29:06.763736Z","steps":["trace[387485134] 'process raft request' (duration: 100.671337ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:30:33.168639Z","caller":"traceutil/trace.go:171","msg":"trace[1832442892] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"126.060399ms","start":"2026-05-28T15:30:33.042545Z","end":"2026-05-28T15:30:33.168605Z","steps":["trace[1832442892] 'process raft request' (duration: 125.836985ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:30:33.1908Z","caller":"traceutil/trace.go:171","msg":"trace[229536494] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"108.677919ms","start":"2026-05-28T15:30:33.082104Z","end":"2026-05-28T15:30:33.190782Z","steps":["trace[229536494] 'process raft request' (duration: 108.21483ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:30:33.190938Z","caller":"traceutil/trace.go:171","msg":"trace[1469620600] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"105.995738ms","start":"2026-05-28T15:30:33.084935Z","end":"2026-05-28T15:30:33.190931Z","steps":["trace[1469620600] 'process raft request' (duration: 105.473157ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:30:50.16023Z","caller":"traceutil/trace.go:171","msg":"trace[1069146130] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"129.23004ms","start":"2026-05-28T15:30:50.030971Z","end":"2026-05-28T15:30:50.160201Z","steps":["trace[1069146130] 'process raft request' (duration: 129.065337ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:30:50.16021Z","caller":"traceutil/trace.go:171","msg":"trace[1909896271] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"130.841691ms","start":"2026-05-28T15:30:50.029308Z","end":"2026-05-28T15:30:50.160149Z","steps":["trace[1909896271] 'process raft request' (duration: 120.105246ms)","trace[1909896271] 'compare' (duration: 10.46353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T15:30:58.911595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.10401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T15:30:58.911706Z","caller":"traceutil/trace.go:171","msg":"trace[621803433] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5398; }","duration":"104.257963ms","start":"2026-05-28T15:30:58.807429Z","end":"2026-05-28T15:30:58.911687Z","steps":["trace[621803433] 'count revisions from in-memory index tree' (duration: 104.024558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:30:58.911845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.553236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b3c46ebea4f081\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-05-28T15:30:58.912041Z","caller":"traceutil/trace.go:171","msg":"trace[239618607] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18b3c46ebea4f081; range_end:; response_count:1; response_revision:5398; }","duration":"107.822981ms","start":"2026-05-28T15:30:58.804183Z","end":"2026-05-28T15:30:58.912006Z","steps":["trace[239618607] 'range keys from in-memory index tree' (duration: 107.314231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:30:59.150286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.664755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4791"} {"level":"info","ts":"2026-05-28T15:30:59.15038Z","caller":"traceutil/trace.go:171","msg":"trace[2083958315] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5399; }","duration":"132.834028ms","start":"2026-05-28T15:30:59.017526Z","end":"2026-05-28T15:30:59.15036Z","steps":["trace[2083958315] 'range keys from in-memory index tree' (duration: 132.460191ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:30:59.320901Z","caller":"traceutil/trace.go:171","msg":"trace[1665579791] linearizableReadLoop","detail":"{readStateIndex:5603; appliedIndex:5601; }","duration":"152.638717ms","start":"2026-05-28T15:30:59.16823Z","end":"2026-05-28T15:30:59.320869Z","steps":["trace[1665579791] 'read index received' (duration: 20.861548ms)","trace[1665579791] 'applied index is now lower than readState.Index' (duration: 131.775679ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:30:59.320957Z","caller":"traceutil/trace.go:171","msg":"trace[1788517832] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"160.411666ms","start":"2026-05-28T15:30:59.160509Z","end":"2026-05-28T15:30:59.320921Z","steps":["trace[1788517832] 'process raft request' (duration: 160.171391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:30:59.321168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.913352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-28T15:30:59.321225Z","caller":"traceutil/trace.go:171","msg":"trace[230936024] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5401; }","duration":"153.008894ms","start":"2026-05-28T15:30:59.168202Z","end":"2026-05-28T15:30:59.321211Z","steps":["trace[230936024] 'agreement among raft nodes before linearized reading' (duration: 152.8041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:30:59.34141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.887776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T15:30:59.341494Z","caller":"traceutil/trace.go:171","msg":"trace[104532461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5404; }","duration":"115.028358ms","start":"2026-05-28T15:30:59.226447Z","end":"2026-05-28T15:30:59.341475Z","steps":["trace[104532461] 'agreement among raft nodes before linearized reading' (duration: 114.890726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:30:59.341537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.083948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T15:30:59.341625Z","caller":"traceutil/trace.go:171","msg":"trace[1085511388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5404; }","duration":"107.22808ms","start":"2026-05-28T15:30:59.234378Z","end":"2026-05-28T15:30:59.341606Z","steps":["trace[1085511388] 'agreement among raft nodes before linearized reading' (duration: 106.897513ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:32:19.561288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-05-28T15:32:19.632571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"69.016404ms","hash":1508640404,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":34500608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T15:32:19.632671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1508640404,"revision":2998,"compact-revision":-1} {"level":"info","ts":"2026-05-28T15:37:08.034571Z","caller":"traceutil/trace.go:171","msg":"trace[920326994] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"261.365538ms","start":"2026-05-28T15:37:07.773176Z","end":"2026-05-28T15:37:08.034542Z","steps":["trace[920326994] 'process raft request' (duration: 261.186365ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:18.707368Z","caller":"traceutil/trace.go:171","msg":"trace[557501616] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"103.496119ms","start":"2026-05-28T15:37:18.603837Z","end":"2026-05-28T15:37:18.707333Z","steps":["trace[557501616] 'process raft request' (duration: 103.193613ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:18.944997Z","caller":"traceutil/trace.go:171","msg":"trace[83484677] transaction","detail":"{read_only:false; response_revision:8293; number_of_response:1; }","duration":"236.510704ms","start":"2026-05-28T15:37:18.708458Z","end":"2026-05-28T15:37:18.944969Z","steps":["trace[83484677] 'process raft request' (duration: 185.950531ms)","trace[83484677] 'compare' (duration: 50.376449ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:37:18.945314Z","caller":"traceutil/trace.go:171","msg":"trace[338239021] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"234.2617ms","start":"2026-05-28T15:37:18.711031Z","end":"2026-05-28T15:37:18.945292Z","steps":["trace[338239021] 'process raft request' (duration: 233.893373ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:18.945444Z","caller":"traceutil/trace.go:171","msg":"trace[432085572] transaction","detail":"{read_only:false; response_revision:8296; number_of_response:1; }","duration":"139.207814ms","start":"2026-05-28T15:37:18.8062Z","end":"2026-05-28T15:37:18.945407Z","steps":["trace[432085572] 'process raft request' (duration: 139.142203ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:18.945571Z","caller":"traceutil/trace.go:171","msg":"trace[731034206] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"203.529033ms","start":"2026-05-28T15:37:18.742017Z","end":"2026-05-28T15:37:18.945546Z","steps":["trace[731034206] 'process raft request' (duration: 203.208947ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:19.714734Z","caller":"traceutil/trace.go:171","msg":"trace[161003022] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"151.82992ms","start":"2026-05-28T15:37:19.56288Z","end":"2026-05-28T15:37:19.71471Z","steps":["trace[161003022] 'process raft request' (duration: 151.628886ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:19.803184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6134} {"level":"info","ts":"2026-05-28T15:37:19.847834Z","caller":"traceutil/trace.go:171","msg":"trace[302742245] transaction","detail":"{read_only:false; response_revision:8302; number_of_response:1; }","duration":"102.833219ms","start":"2026-05-28T15:37:19.744957Z","end":"2026-05-28T15:37:19.84779Z","steps":["trace[302742245] 'process raft request' (duration: 70.970719ms)","trace[302742245] 'compare' (duration: 18.410588ms)","trace[302742245] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:473; } (duration: 13.165205ms)"],"step_count":3} {"level":"info","ts":"2026-05-28T15:37:20.066105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6134,"took":"260.280705ms","hash":3804789988,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":23412736,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-28T15:37:20.066185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804789988,"revision":6134,"compact-revision":2998} {"level":"info","ts":"2026-05-28T15:37:46.929008Z","caller":"traceutil/trace.go:171","msg":"trace[2137058250] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"120.933648ms","start":"2026-05-28T15:37:46.808041Z","end":"2026-05-28T15:37:46.928975Z","steps":["trace[2137058250] 'process raft request' (duration: 120.699254ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:47.146696Z","caller":"traceutil/trace.go:171","msg":"trace[1038416350] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"111.913713ms","start":"2026-05-28T15:37:47.03476Z","end":"2026-05-28T15:37:47.146674Z","steps":["trace[1038416350] 'process raft request' (duration: 111.076778ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:47.154208Z","caller":"traceutil/trace.go:171","msg":"trace[549428041] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"115.620405ms","start":"2026-05-28T15:37:47.038553Z","end":"2026-05-28T15:37:47.154173Z","steps":["trace[549428041] 'process raft request' (duration: 115.264279ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:47.154273Z","caller":"traceutil/trace.go:171","msg":"trace[761050948] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"113.359012ms","start":"2026-05-28T15:37:47.040883Z","end":"2026-05-28T15:37:47.154242Z","steps":["trace[761050948] 'process raft request' (duration: 113.070536ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:47.154216Z","caller":"traceutil/trace.go:171","msg":"trace[1464702549] linearizableReadLoop","detail":"{readStateIndex:8805; appliedIndex:8802; }","duration":"102.384708ms","start":"2026-05-28T15:37:47.051807Z","end":"2026-05-28T15:37:47.154192Z","steps":["trace[1464702549] 'read index received' (duration: 94.098287ms)","trace[1464702549] 'applied index is now lower than readState.Index' (duration: 8.285071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T15:37:47.154401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.573082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-28T15:37:47.154458Z","caller":"traceutil/trace.go:171","msg":"trace[1276102615] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8503; }","duration":"102.686844ms","start":"2026-05-28T15:37:47.051761Z","end":"2026-05-28T15:37:47.154448Z","steps":["trace[1276102615] 'agreement among raft nodes before linearized reading' (duration: 102.51101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:37:59.363366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.253109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T15:37:59.363483Z","caller":"traceutil/trace.go:171","msg":"trace[2047913087] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"168.295299ms","start":"2026-05-28T15:37:59.195169Z","end":"2026-05-28T15:37:59.363465Z","steps":["trace[2047913087] 'process raft request' (duration: 35.723974ms)","trace[2047913087] 'compare' (duration: 132.096446ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:37:59.387502Z","caller":"traceutil/trace.go:171","msg":"trace[1927390676] linearizableReadLoop","detail":"{readStateIndex:8917; appliedIndex:8914; }","duration":"169.703687ms","start":"2026-05-28T15:37:59.217768Z","end":"2026-05-28T15:37:59.387472Z","steps":["trace[1927390676] 'read index received' (duration: 13.102115ms)","trace[1927390676] 'applied index is now lower than readState.Index' (duration: 156.600402ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:37:59.387546Z","caller":"traceutil/trace.go:171","msg":"trace[455862651] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"188.593973ms","start":"2026-05-28T15:37:59.198908Z","end":"2026-05-28T15:37:59.387502Z","steps":["trace[455862651] 'process raft request' (duration: 188.355259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:37:59.387771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.991442ms","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-28T15:37:59.38785Z","caller":"traceutil/trace.go:171","msg":"trace[147822668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8608; }","duration":"170.113665ms","start":"2026-05-28T15:37:59.217717Z","end":"2026-05-28T15:37:59.387831Z","steps":["trace[147822668] 'agreement among raft nodes before linearized reading' (duration: 169.87123ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:37:59.387854Z","caller":"traceutil/trace.go:171","msg":"trace[470238046] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"188.586643ms","start":"2026-05-28T15:37:59.199219Z","end":"2026-05-28T15:37:59.387805Z","steps":["trace[470238046] 'process raft request' (duration: 188.196726ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:38:18.328326Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002388.wal"} {"level":"info","ts":"2026-05-28T15:39:56.964887Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T15:39:56.972218Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-28T15:39:56.972327Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-28T15:42:19.810535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8300} {"level":"info","ts":"2026-05-28T15:42:19.891894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8300,"took":"78.911961ms","hash":410927887,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":25350144,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-28T15:42:19.89196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":410927887,"revision":8300,"compact-revision":6134} {"level":"info","ts":"2026-05-28T15:43:08.08903Z","caller":"traceutil/trace.go:171","msg":"trace[584653109] transaction","detail":"{read_only:false; response_revision:11247; number_of_response:1; }","duration":"189.717641ms","start":"2026-05-28T15:43:07.899258Z","end":"2026-05-28T15:43:08.088976Z","steps":["trace[584653109] 'process raft request' (duration: 189.583349ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:43:08.102029Z","caller":"traceutil/trace.go:171","msg":"trace[1248161645] transaction","detail":"{read_only:false; response_revision:11248; number_of_response:1; }","duration":"199.783281ms","start":"2026-05-28T15:43:07.902234Z","end":"2026-05-28T15:43:08.102017Z","steps":["trace[1248161645] 'process raft request' (duration: 199.611536ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:47:19.818228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10784} {"level":"info","ts":"2026-05-28T15:47:19.896441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10784,"took":"74.43565ms","hash":1025491538,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":27537408,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-28T15:47:19.8965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1025491538,"revision":10784,"compact-revision":8300} {"level":"info","ts":"2026-05-28T15:48:54.062507Z","caller":"traceutil/trace.go:171","msg":"trace[2090900850] transaction","detail":"{read_only:false; response_revision:13619; number_of_response:1; }","duration":"131.488179ms","start":"2026-05-28T15:48:53.930995Z","end":"2026-05-28T15:48:54.062483Z","steps":["trace[2090900850] 'process raft request' (duration: 131.357307ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:48:54.064245Z","caller":"traceutil/trace.go:171","msg":"trace[216803994] transaction","detail":"{read_only:false; response_revision:13620; number_of_response:1; }","duration":"132.865146ms","start":"2026-05-28T15:48:53.931361Z","end":"2026-05-28T15:48:54.064226Z","steps":["trace[216803994] 'process raft request' (duration: 132.795115ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:49:48.696739Z","caller":"traceutil/trace.go:171","msg":"trace[1127191842] linearizableReadLoop","detail":"{readStateIndex:14404; appliedIndex:14403; }","duration":"117.164137ms","start":"2026-05-28T15:49:48.579552Z","end":"2026-05-28T15:49:48.696717Z","steps":["trace[1127191842] 'read index received' (duration: 116.985322ms)","trace[1127191842] 'applied index is now lower than readState.Index' (duration: 178.005µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T15:49:48.696869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.298319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T15:49:48.696898Z","caller":"traceutil/trace.go:171","msg":"trace[2022864977] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:13920; }","duration":"117.374801ms","start":"2026-05-28T15:49:48.579513Z","end":"2026-05-28T15:49:48.696888Z","steps":["trace[2022864977] 'agreement among raft nodes before linearized reading' (duration: 117.30886ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:49:48.696935Z","caller":"traceutil/trace.go:171","msg":"trace[925766442] transaction","detail":"{read_only:false; response_revision:13920; number_of_response:1; }","duration":"163.354419ms","start":"2026-05-28T15:49:48.533416Z","end":"2026-05-28T15:49:48.696771Z","steps":["trace[925766442] 'process raft request' (duration: 163.172724ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:50:10.994093Z","caller":"traceutil/trace.go:171","msg":"trace[183699753] transaction","detail":"{read_only:false; response_revision:14231; number_of_response:1; }","duration":"251.014892ms","start":"2026-05-28T15:50:10.743048Z","end":"2026-05-28T15:50:10.994063Z","steps":["trace[183699753] 'process raft request' (duration: 250.800668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T15:51:24.075054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.557871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-05-28T15:51:24.075145Z","caller":"traceutil/trace.go:171","msg":"trace[949606314] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14974; }","duration":"290.723844ms","start":"2026-05-28T15:51:23.7844Z","end":"2026-05-28T15:51:24.075124Z","steps":["trace[949606314] 'range keys from in-memory index tree' (duration: 290.234435ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:51:24.239194Z","caller":"traceutil/trace.go:171","msg":"trace[1854257028] transaction","detail":"{read_only:false; response_revision:14975; number_of_response:1; }","duration":"103.026813ms","start":"2026-05-28T15:51:24.136142Z","end":"2026-05-28T15:51:24.239169Z","steps":["trace[1854257028] 'process raft request' (duration: 102.496364ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:52:19.82434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12967} {"level":"info","ts":"2026-05-28T15:52:19.903279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12967,"took":"75.685969ms","hash":2146623041,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":30027776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T15:52:19.903404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2146623041,"revision":12967,"compact-revision":10784} {"level":"info","ts":"2026-05-28T15:56:06.178319Z","caller":"traceutil/trace.go:171","msg":"trace[411682437] linearizableReadLoop","detail":"{readStateIndex:17820; appliedIndex:17819; }","duration":"176.484709ms","start":"2026-05-28T15:56:06.001801Z","end":"2026-05-28T15:56:06.178286Z","steps":["trace[411682437] 'read index received' (duration: 158.67988ms)","trace[411682437] 'applied index is now lower than readState.Index' (duration: 17.803659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T15:56:06.179607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.777782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-v4s7g\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-05-28T15:56:06.179707Z","caller":"traceutil/trace.go:171","msg":"trace[484720780] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-v4s7g; range_end:; response_count:1; response_revision:17231; }","duration":"177.940666ms","start":"2026-05-28T15:56:06.001747Z","end":"2026-05-28T15:56:06.179688Z","steps":["trace[484720780] 'agreement among raft nodes before linearized reading' (duration: 176.658602ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T15:56:06.180196Z","caller":"traceutil/trace.go:171","msg":"trace[113921622] transaction","detail":"{read_only:false; response_revision:17231; number_of_response:1; }","duration":"234.759339ms","start":"2026-05-28T15:56:05.943598Z","end":"2026-05-28T15:56:06.178357Z","steps":["trace[113921622] 'process raft request' (duration: 216.784576ms)","trace[113921622] 'compare' (duration: 17.746968ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T15:57:19.831916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15327} {"level":"info","ts":"2026-05-28T15:57:19.928213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15327,"took":"93.157985ms","hash":924321303,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":33230848,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-28T15:57:19.928278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":924321303,"revision":15327,"compact-revision":12967} {"level":"info","ts":"2026-05-28T16:00:17.951618Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T16:00:17.956936Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-28T16:00:17.957033Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-28T16:01:55.773149Z","caller":"traceutil/trace.go:171","msg":"trace[335684128] linearizableReadLoop","detail":"{readStateIndex:20667; appliedIndex:20666; }","duration":"107.590887ms","start":"2026-05-28T16:01:55.665532Z","end":"2026-05-28T16:01:55.773123Z","steps":["trace[335684128] 'read index received' (duration: 107.361652ms)","trace[335684128] 'applied index is now lower than readState.Index' (duration: 228.145µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:01:55.773339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.777261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T16:01:55.77342Z","caller":"traceutil/trace.go:171","msg":"trace[1430717303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19988; }","duration":"107.897842ms","start":"2026-05-28T16:01:55.665505Z","end":"2026-05-28T16:01:55.773403Z","steps":["trace[1430717303] 'agreement among raft nodes before linearized reading' (duration: 107.669788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:01:55.7735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.946174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-28T16:01:55.773574Z","caller":"traceutil/trace.go:171","msg":"trace[758938906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19988; }","duration":"108.069676ms","start":"2026-05-28T16:01:55.665483Z","end":"2026-05-28T16:01:55.773553Z","steps":["trace[758938906] 'agreement among raft nodes before linearized reading' (duration: 107.792371ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:01:55.77379Z","caller":"traceutil/trace.go:171","msg":"trace[1791738738] transaction","detail":"{read_only:false; response_revision:19988; number_of_response:1; }","duration":"110.521862ms","start":"2026-05-28T16:01:55.663251Z","end":"2026-05-28T16:01:55.773772Z","steps":["trace[1791738738] 'process raft request' (duration: 109.724946ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:01:56.098082Z","caller":"traceutil/trace.go:171","msg":"trace[701193194] linearizableReadLoop","detail":"{readStateIndex:20669; appliedIndex:20668; }","duration":"268.718661ms","start":"2026-05-28T16:01:55.829337Z","end":"2026-05-28T16:01:56.098056Z","steps":["trace[701193194] 'read index received' (duration: 215.334575ms)","trace[701193194] 'applied index is now lower than readState.Index' (duration: 53.382846ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:01:56.0981Z","caller":"traceutil/trace.go:171","msg":"trace[14285958] transaction","detail":"{read_only:false; response_revision:19990; number_of_response:1; }","duration":"320.123629ms","start":"2026-05-28T16:01:55.777933Z","end":"2026-05-28T16:01:56.098057Z","steps":["trace[14285958] 'process raft request' (duration: 266.917707ms)","trace[14285958] 'compare' (duration: 53.015698ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:01:56.098209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.858274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:01:56.098245Z","caller":"traceutil/trace.go:171","msg":"trace[1857599311] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:19990; }","duration":"268.948486ms","start":"2026-05-28T16:01:55.829283Z","end":"2026-05-28T16:01:56.098231Z","steps":["trace[1857599311] 'agreement among raft nodes before linearized reading' (duration: 268.864034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:01:56.098247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:01:55.777895Z","time spent":"320.275193ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:01:56.347628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.197373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:01:56.34773Z","caller":"traceutil/trace.go:171","msg":"trace[168596867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19990; }","duration":"113.353737ms","start":"2026-05-28T16:01:56.234352Z","end":"2026-05-28T16:01:56.347705Z","steps":["trace[168596867] 'range keys from in-memory index tree' (duration: 113.105952ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:01:56.709201Z","caller":"traceutil/trace.go:171","msg":"trace[1401983073] transaction","detail":"{read_only:false; response_revision:19991; number_of_response:1; }","duration":"193.027731ms","start":"2026-05-28T16:01:56.51607Z","end":"2026-05-28T16:01:56.709098Z","steps":["trace[1401983073] 'process raft request' (duration: 192.874658ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:01:56.744072Z","caller":"traceutil/trace.go:171","msg":"trace[211877378] linearizableReadLoop","detail":"{readStateIndex:20671; appliedIndex:20670; }","duration":"110.491961ms","start":"2026-05-28T16:01:56.633549Z","end":"2026-05-28T16:01:56.744041Z","steps":["trace[211877378] 'read index received' (duration: 75.720551ms)","trace[211877378] 'applied index is now lower than readState.Index' (duration: 34.7705ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:01:56.744194Z","caller":"traceutil/trace.go:171","msg":"trace[1125250292] transaction","detail":"{read_only:false; response_revision:19992; number_of_response:1; }","duration":"121.094693ms","start":"2026-05-28T16:01:56.623049Z","end":"2026-05-28T16:01:56.744143Z","steps":["trace[1125250292] 'process raft request' (duration: 120.867599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:01:56.74431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.750096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T16:01:56.74435Z","caller":"traceutil/trace.go:171","msg":"trace[1403126278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19992; }","duration":"110.846548ms","start":"2026-05-28T16:01:56.633491Z","end":"2026-05-28T16:01:56.744338Z","steps":["trace[1403126278] 'agreement among raft nodes before linearized reading' (duration: 110.686895ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:02:19.84021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18055} {"level":"info","ts":"2026-05-28T16:02:19.956085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18055,"took":"112.304196ms","hash":1382252105,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":29827072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T16:02:19.956156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1382252105,"revision":18055,"compact-revision":15327} {"level":"info","ts":"2026-05-28T16:05:44.997247Z","caller":"traceutil/trace.go:171","msg":"trace[1890422867] transaction","detail":"{read_only:false; response_revision:22426; number_of_response:1; }","duration":"100.027949ms","start":"2026-05-28T16:05:44.897183Z","end":"2026-05-28T16:05:44.997211Z","steps":["trace[1890422867] 'process raft request' (duration: 99.742463ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:05:45.132975Z","caller":"traceutil/trace.go:171","msg":"trace[2108589338] transaction","detail":"{read_only:false; response_revision:22427; number_of_response:1; }","duration":"131.157441ms","start":"2026-05-28T16:05:45.00178Z","end":"2026-05-28T16:05:45.132938Z","steps":["trace[2108589338] 'process raft request' (duration: 129.58096ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:07:01.620426Z","caller":"traceutil/trace.go:171","msg":"trace[891183970] transaction","detail":"{read_only:false; response_revision:23267; number_of_response:1; }","duration":"105.355941ms","start":"2026-05-28T16:07:01.515045Z","end":"2026-05-28T16:07:01.620401Z","steps":["trace[891183970] 'process raft request' (duration: 105.190018ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:07:06.54337Z","caller":"traceutil/trace.go:171","msg":"trace[1844653796] transaction","detail":"{read_only:false; response_revision:23304; number_of_response:1; }","duration":"110.031419ms","start":"2026-05-28T16:07:06.43331Z","end":"2026-05-28T16:07:06.543341Z","steps":["trace[1844653796] 'process raft request' (duration: 93.650748ms)","trace[1844653796] 'compare' (duration: 16.161937ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:07:08.038063Z","caller":"traceutil/trace.go:171","msg":"trace[1972723265] transaction","detail":"{read_only:false; response_revision:23315; number_of_response:1; }","duration":"108.988619ms","start":"2026-05-28T16:07:07.929058Z","end":"2026-05-28T16:07:08.038046Z","steps":["trace[1972723265] 'process raft request' (duration: 15.243989ms)","trace[1972723265] 'compare' (duration: 93.547306ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:07:12.201035Z","caller":"traceutil/trace.go:171","msg":"trace[803418835] transaction","detail":"{read_only:false; response_revision:23346; number_of_response:1; }","duration":"117.953721ms","start":"2026-05-28T16:07:12.083033Z","end":"2026-05-28T16:07:12.200987Z","steps":["trace[803418835] 'process raft request' (duration: 99.893797ms)","trace[803418835] 'compare' (duration: 17.856879ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:07:19.846553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20145} {"level":"info","ts":"2026-05-28T16:07:19.94892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20145,"took":"98.780816ms","hash":228479140,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":42000384,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-28T16:07:19.94899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":228479140,"revision":20145,"compact-revision":18055} {"level":"warn","ts":"2026-05-28T16:09:29.353926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.532634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T16:09:29.354028Z","caller":"traceutil/trace.go:171","msg":"trace[1109217008] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:24640; }","duration":"224.698017ms","start":"2026-05-28T16:09:29.129311Z","end":"2026-05-28T16:09:29.354009Z","steps":["trace[1109217008] 'count revisions from in-memory index tree' (duration: 224.457232ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:29.36435Z","caller":"traceutil/trace.go:171","msg":"trace[1845445714] linearizableReadLoop","detail":"{readStateIndex:25452; appliedIndex:25451; }","duration":"136.360988ms","start":"2026-05-28T16:09:29.227973Z","end":"2026-05-28T16:09:29.364334Z","steps":["trace[1845445714] 'read index received' (duration: 136.164694ms)","trace[1845445714] 'applied index is now lower than readState.Index' (duration: 195.404µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:29.364487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.293763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:29.364524Z","caller":"traceutil/trace.go:171","msg":"trace[758334308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24641; }","duration":"128.359273ms","start":"2026-05-28T16:09:29.236155Z","end":"2026-05-28T16:09:29.364515Z","steps":["trace[758334308] 'agreement among raft nodes before linearized reading' (duration: 128.292852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:29.364524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.520932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:29.364664Z","caller":"traceutil/trace.go:171","msg":"trace[1224593993] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24641; }","duration":"136.723315ms","start":"2026-05-28T16:09:29.227916Z","end":"2026-05-28T16:09:29.36464Z","steps":["trace[1224593993] 'agreement among raft nodes before linearized reading' (duration: 136.522501ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:29.365044Z","caller":"traceutil/trace.go:171","msg":"trace[82680674] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"150.95724ms","start":"2026-05-28T16:09:29.213427Z","end":"2026-05-28T16:09:29.364384Z","steps":["trace[82680674] 'process raft request' (duration: 150.759846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:29.663095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.511448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:29.663439Z","caller":"traceutil/trace.go:171","msg":"trace[412517584] linearizableReadLoop","detail":"{readStateIndex:25453; appliedIndex:25452; }","duration":"143.322363ms","start":"2026-05-28T16:09:29.520049Z","end":"2026-05-28T16:09:29.663371Z","steps":["trace[412517584] 'read index received' (duration: 2.369946ms)","trace[412517584] 'applied index is now lower than readState.Index' (duration: 140.947437ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:29.663471Z","caller":"traceutil/trace.go:171","msg":"trace[522829669] transaction","detail":"{read_only:false; response_revision:24642; number_of_response:1; }","duration":"294.63114ms","start":"2026-05-28T16:09:29.368787Z","end":"2026-05-28T16:09:29.663418Z","steps":["trace[522829669] 'process raft request' (duration: 153.695283ms)","trace[522829669] 'compare' (duration: 140.399606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:29.663758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.70552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-28T16:09:29.66384Z","caller":"traceutil/trace.go:171","msg":"trace[835892546] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24643; }","duration":"143.800032ms","start":"2026-05-28T16:09:29.519996Z","end":"2026-05-28T16:09:29.663796Z","steps":["trace[835892546] 'agreement among raft nodes before linearized reading' (duration: 143.574447ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:29.950132Z","caller":"traceutil/trace.go:171","msg":"trace[1451225721] linearizableReadLoop","detail":"{readStateIndex:25455; appliedIndex:25454; }","duration":"263.196522ms","start":"2026-05-28T16:09:29.686908Z","end":"2026-05-28T16:09:29.950105Z","steps":["trace[1451225721] 'read index received' (duration: 240.589275ms)","trace[1451225721] 'applied index is now lower than readState.Index' (duration: 22.605717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:29.95038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.449557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-28T16:09:29.95042Z","caller":"traceutil/trace.go:171","msg":"trace[1068453901] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24644; }","duration":"263.528758ms","start":"2026-05-28T16:09:29.686878Z","end":"2026-05-28T16:09:29.950407Z","steps":["trace[1068453901] 'agreement among raft nodes before linearized reading' (duration: 263.332544ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:29.950808Z","caller":"traceutil/trace.go:171","msg":"trace[1857208404] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"269.647076ms","start":"2026-05-28T16:09:29.681144Z","end":"2026-05-28T16:09:29.950791Z","steps":["trace[1857208404] 'process raft request' (duration: 246.430858ms)","trace[1857208404] 'compare' (duration: 21.729339ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:30.43089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.991475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:30.430992Z","caller":"traceutil/trace.go:171","msg":"trace[587931828] linearizableReadLoop","detail":"{readStateIndex:25457; appliedIndex:25455; }","duration":"317.766037ms","start":"2026-05-28T16:09:30.113218Z","end":"2026-05-28T16:09:30.430984Z","steps":["trace[587931828] 'read index received' (duration: 132.817159ms)","trace[587931828] 'applied index is now lower than readState.Index' (duration: 184.948248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:30.431085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.867449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-05-28T16:09:30.431104Z","caller":"traceutil/trace.go:171","msg":"trace[556324621] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24646; }","duration":"317.886649ms","start":"2026-05-28T16:09:30.113213Z","end":"2026-05-28T16:09:30.431099Z","steps":["trace[556324621] 'agreement among raft nodes before linearized reading' (duration: 317.795297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:30.431121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:30.113173Z","time spent":"317.9443ms","remote":"127.0.0.1:59140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11739,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-05-28T16:09:30.43126Z","caller":"traceutil/trace.go:171","msg":"trace[1130303788] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"473.693394ms","start":"2026-05-28T16:09:29.957558Z","end":"2026-05-28T16:09:30.431252Z","steps":["trace[1130303788] 'process raft request' (duration: 146.292831ms)","trace[1130303788] 'compare' (duration: 326.273361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:30.431305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:29.957538Z","time spent":"473.752205ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:30.431365Z","caller":"traceutil/trace.go:171","msg":"trace[1451006107] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"401.991346ms","start":"2026-05-28T16:09:30.02937Z","end":"2026-05-28T16:09:30.431361Z","steps":["trace[1451006107] 'process raft request' (duration: 401.585368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:30.431394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:30.02934Z","time spent":"402.042397ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:30.498991Z","caller":"traceutil/trace.go:171","msg":"trace[1399271225] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"258.43ms","start":"2026-05-28T16:09:30.240541Z","end":"2026-05-28T16:09:30.498971Z","steps":["trace[1399271225] 'process raft request' (duration: 257.94279ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:30.852722Z","caller":"traceutil/trace.go:171","msg":"trace[524132325] linearizableReadLoop","detail":"{readStateIndex:25459; appliedIndex:25458; }","duration":"257.352489ms","start":"2026-05-28T16:09:30.595336Z","end":"2026-05-28T16:09:30.852688Z","steps":["trace[524132325] 'read index received' (duration: 219.921035ms)","trace[524132325] 'applied index is now lower than readState.Index' (duration: 37.430354ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:30.852902Z","caller":"traceutil/trace.go:171","msg":"trace[391182462] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"335.350378ms","start":"2026-05-28T16:09:30.517519Z","end":"2026-05-28T16:09:30.852869Z","steps":["trace[391182462] 'process raft request' (duration: 297.725791ms)","trace[391182462] 'compare' (duration: 37.316851ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:30.853046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:30.517506Z","time spent":"335.46293ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:30.853097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.743016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T16:09:30.853359Z","caller":"traceutil/trace.go:171","msg":"trace[390224462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24648; }","duration":"257.857709ms","start":"2026-05-28T16:09:30.595306Z","end":"2026-05-28T16:09:30.853164Z","steps":["trace[390224462] 'agreement among raft nodes before linearized reading' (duration: 257.640685ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:31.030008Z","caller":"traceutil/trace.go:171","msg":"trace[286119228] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"376.899621ms","start":"2026-05-28T16:09:30.65308Z","end":"2026-05-28T16:09:31.02998Z","steps":["trace[286119228] 'process raft request' (duration: 376.489494ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:31.029913Z","caller":"traceutil/trace.go:171","msg":"trace[305530060] linearizableReadLoop","detail":"{readStateIndex:25460; appliedIndex:25459; }","duration":"177.104216ms","start":"2026-05-28T16:09:30.852786Z","end":"2026-05-28T16:09:31.029891Z","steps":["trace[305530060] 'read index received' (duration: 176.730019ms)","trace[305530060] 'applied index is now lower than readState.Index' (duration: 373.107µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:31.030154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:30.653058Z","time spent":"377.018353ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:31.030322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.933085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-28T16:09:31.030373Z","caller":"traceutil/trace.go:171","msg":"trace[1368277424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24649; }","duration":"266.056438ms","start":"2026-05-28T16:09:30.7643Z","end":"2026-05-28T16:09:31.030357Z","steps":["trace[1368277424] 'agreement among raft nodes before linearized reading' (duration: 265.850984ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:31.030714Z","caller":"traceutil/trace.go:171","msg":"trace[1201020540] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"173.519897ms","start":"2026-05-28T16:09:30.857168Z","end":"2026-05-28T16:09:31.030688Z","steps":["trace[1201020540] 'process raft request' (duration: 173.302653ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:31.030852Z","caller":"traceutil/trace.go:171","msg":"trace[1084320389] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"130.607207ms","start":"2026-05-28T16:09:30.900223Z","end":"2026-05-28T16:09:31.03083Z","steps":["trace[1084320389] 'process raft request' (duration: 130.410773ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:31.293911Z","caller":"traceutil/trace.go:171","msg":"trace[1027644268] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"258.308497ms","start":"2026-05-28T16:09:31.035563Z","end":"2026-05-28T16:09:31.293871Z","steps":["trace[1027644268] 'process raft request' (duration: 258.057053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:31.66502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.395663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:31.665232Z","caller":"traceutil/trace.go:171","msg":"trace[227720241] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"233.328924ms","start":"2026-05-28T16:09:31.431874Z","end":"2026-05-28T16:09:31.665203Z","steps":["trace[227720241] 'process raft request' (duration: 233.218872ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:31.665299Z","caller":"traceutil/trace.go:171","msg":"trace[1636803110] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"295.008957ms","start":"2026-05-28T16:09:31.370266Z","end":"2026-05-28T16:09:31.665275Z","steps":["trace[1636803110] 'process raft request' (duration: 118.277238ms)","trace[1636803110] 'compare' (duration: 176.163078ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:31.846615Z","caller":"traceutil/trace.go:171","msg":"trace[135550250] linearizableReadLoop","detail":"{readStateIndex:25469; appliedIndex:25468; }","duration":"154.771694ms","start":"2026-05-28T16:09:31.691789Z","end":"2026-05-28T16:09:31.846561Z","steps":["trace[135550250] 'read index received' (duration: 121.083213ms)","trace[135550250] 'applied index is now lower than readState.Index' (duration: 33.687151ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:31.846723Z","caller":"traceutil/trace.go:171","msg":"trace[339517185] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"173.66598ms","start":"2026-05-28T16:09:31.673025Z","end":"2026-05-28T16:09:31.846691Z","steps":["trace[339517185] 'process raft request' (duration: 139.867526ms)","trace[339517185] 'compare' (duration: 33.545419ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:31.846831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.02889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-28T16:09:31.846876Z","caller":"traceutil/trace.go:171","msg":"trace[1949878897] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24658; }","duration":"155.133651ms","start":"2026-05-28T16:09:31.691731Z","end":"2026-05-28T16:09:31.846864Z","steps":["trace[1949878897] 'agreement among raft nodes before linearized reading' (duration: 154.953198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:32.166763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.939401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:32.166837Z","caller":"traceutil/trace.go:171","msg":"trace[955859192] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24659; }","duration":"103.061254ms","start":"2026-05-28T16:09:32.063758Z","end":"2026-05-28T16:09:32.166819Z","steps":["trace[955859192] 'count revisions from in-memory index tree' (duration: 102.819229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:32.16676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.0018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T16:09:32.167031Z","caller":"traceutil/trace.go:171","msg":"trace[339503680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24659; }","duration":"213.325477ms","start":"2026-05-28T16:09:31.953696Z","end":"2026-05-28T16:09:32.167022Z","steps":["trace[339503680] 'range keys from in-memory index tree' (duration: 212.829788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:32.735434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422820524856984,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T16:09:32.858526Z","caller":"traceutil/trace.go:171","msg":"trace[974873226] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"687.249845ms","start":"2026-05-28T16:09:32.171245Z","end":"2026-05-28T16:09:32.858495Z","steps":["trace[974873226] 'process raft request' (duration: 687.079242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:32.858754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.171216Z","time spent":"687.45434ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:33.199667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.835228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:33.20008Z","caller":"traceutil/trace.go:171","msg":"trace[219893892] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"842.836456ms","start":"2026-05-28T16:09:32.357207Z","end":"2026-05-28T16:09:33.200044Z","steps":["trace[219893892] 'process raft request' (duration: 523.528699ms)","trace[219893892] 'compare' (duration: 318.476551ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:33.200202Z","caller":"traceutil/trace.go:171","msg":"trace[1090436003] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"763.904179ms","start":"2026-05-28T16:09:32.436253Z","end":"2026-05-28T16:09:33.200158Z","steps":["trace[1090436003] 'process raft request' (duration: 763.534552ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.200252Z","caller":"traceutil/trace.go:171","msg":"trace[1549499274] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"692.554069ms","start":"2026-05-28T16:09:32.507671Z","end":"2026-05-28T16:09:33.200225Z","steps":["trace[1549499274] 'process raft request' (duration: 692.386535ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.200257Z","caller":"traceutil/trace.go:171","msg":"trace[722335466] linearizableReadLoop","detail":"{readStateIndex:25474; appliedIndex:25470; }","duration":"965.280265ms","start":"2026-05-28T16:09:32.234951Z","end":"2026-05-28T16:09:33.200231Z","steps":["trace[722335466] 'read index received' (duration: 623.404691ms)","trace[722335466] 'applied index is now lower than readState.Index' (duration: 341.873054ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:33.200393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.507656Z","time spent":"692.65946ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:33.200412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.436236Z","time spent":"764.071102ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:33.200437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.472178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:09:33.200388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.357184Z","time spent":"842.992489ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:33.200516Z","caller":"traceutil/trace.go:171","msg":"trace[1943103430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24663; }","duration":"965.584641ms","start":"2026-05-28T16:09:32.234913Z","end":"2026-05-28T16:09:33.200498Z","steps":["trace[1943103430] 'agreement among raft nodes before linearized reading' (duration: 965.439548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.200562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.234899Z","time spent":"965.655762ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T16:09:33.20378Z","caller":"traceutil/trace.go:171","msg":"trace[1590554768] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"169.370787ms","start":"2026-05-28T16:09:33.03418Z","end":"2026-05-28T16:09:33.203551Z","steps":["trace[1590554768] 'process raft request' (duration: 169.216114ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.320077Z","caller":"traceutil/trace.go:171","msg":"trace[773253106] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"283.24565ms","start":"2026-05-28T16:09:33.0368Z","end":"2026-05-28T16:09:33.320045Z","steps":["trace[773253106] 'process raft request' (duration: 283.103917ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.32007Z","caller":"traceutil/trace.go:171","msg":"trace[1648224309] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"283.341102ms","start":"2026-05-28T16:09:33.036664Z","end":"2026-05-28T16:09:33.320005Z","steps":["trace[1648224309] 'process raft request' (duration: 281.384104ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.320272Z","caller":"traceutil/trace.go:171","msg":"trace[1543850450] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"283.458024ms","start":"2026-05-28T16:09:33.036798Z","end":"2026-05-28T16:09:33.320256Z","steps":["trace[1543850450] 'process raft request' (duration: 283.190899ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.320419Z","caller":"traceutil/trace.go:171","msg":"trace[286658488] linearizableReadLoop","detail":"{readStateIndex:25479; appliedIndex:25474; }","duration":"120.036712ms","start":"2026-05-28T16:09:33.200355Z","end":"2026-05-28T16:09:33.320392Z","steps":["trace[286658488] 'read index received' (duration: 3.07118ms)","trace[286658488] 'applied index is now lower than readState.Index' (duration: 116.964162ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:33.320444Z","caller":"traceutil/trace.go:171","msg":"trace[1928560800] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"280.048678ms","start":"2026-05-28T16:09:33.040359Z","end":"2026-05-28T16:09:33.320408Z","steps":["trace[1928560800] 'process raft request' (duration: 279.850614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.320529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.55787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:33.320559Z","caller":"traceutil/trace.go:171","msg":"trace[1925590300] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:24668; }","duration":"291.619561ms","start":"2026-05-28T16:09:33.02893Z","end":"2026-05-28T16:09:33.32055Z","steps":["trace[1925590300] 'agreement among raft nodes before linearized reading' (duration: 291.57465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.320579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.87362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:09:33.320608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.11645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-28T16:09:33.320649Z","caller":"traceutil/trace.go:171","msg":"trace[75532287] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24668; }","duration":"409.016983ms","start":"2026-05-28T16:09:32.911613Z","end":"2026-05-28T16:09:33.32063Z","steps":["trace[75532287] 'agreement among raft nodes before linearized reading' (duration: 408.785968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.320666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.045055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-28T16:09:33.32065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.442873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T16:09:33.320692Z","caller":"traceutil/trace.go:171","msg":"trace[383208199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24668; }","duration":"462.213602ms","start":"2026-05-28T16:09:32.858459Z","end":"2026-05-28T16:09:33.320673Z","steps":["trace[383208199] 'agreement among raft nodes before linearized reading' (duration: 461.969437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.320695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.911591Z","time spent":"409.092344ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-05-28T16:09:33.32073Z","caller":"traceutil/trace.go:171","msg":"trace[1079457629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24668; }","duration":"285.563594ms","start":"2026-05-28T16:09:33.035145Z","end":"2026-05-28T16:09:33.320709Z","steps":["trace[1079457629] 'agreement among raft nodes before linearized reading' (duration: 285.33446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.320744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.858407Z","time spent":"462.328694ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T16:09:33.320755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.08145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:33.320705Z","caller":"traceutil/trace.go:171","msg":"trace[1469353984] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:24668; }","duration":"636.135037ms","start":"2026-05-28T16:09:32.68456Z","end":"2026-05-28T16:09:33.320695Z","steps":["trace[1469353984] 'agreement among raft nodes before linearized reading' (duration: 635.979994ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.320787Z","caller":"traceutil/trace.go:171","msg":"trace[1773858677] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24668; }","duration":"767.145622ms","start":"2026-05-28T16:09:32.553631Z","end":"2026-05-28T16:09:33.320777Z","steps":["trace[1773858677] 'agreement among raft nodes before linearized reading' (duration: 767.095671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.320477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.248678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:09:33.320826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.553617Z","time spent":"767.200722ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-28T16:09:33.320829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.153075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:09:33.320868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.684537Z","time spent":"636.29729ms","remote":"127.0.0.1:58732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-05-28T16:09:33.320912Z","caller":"traceutil/trace.go:171","msg":"trace[2060343764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24668; }","duration":"119.278518ms","start":"2026-05-28T16:09:33.20161Z","end":"2026-05-28T16:09:33.320889Z","steps":["trace[2060343764] 'agreement among raft nodes before linearized reading' (duration: 119.146685ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:33.320937Z","caller":"traceutil/trace.go:171","msg":"trace[1016604860] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24668; }","duration":"592.715728ms","start":"2026-05-28T16:09:32.728162Z","end":"2026-05-28T16:09:33.320878Z","steps":["trace[1016604860] 'agreement among raft nodes before linearized reading' (duration: 592.244559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.321161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:32.728144Z","time spent":"592.922761ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-05-28T16:09:33.711314Z","caller":"traceutil/trace.go:171","msg":"trace[1344733798] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"385.200081ms","start":"2026-05-28T16:09:33.326085Z","end":"2026-05-28T16:09:33.711285Z","steps":["trace[1344733798] 'process raft request' (duration: 385.038978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.711446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:33.326064Z","time spent":"385.311754ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:33.711679Z","caller":"traceutil/trace.go:171","msg":"trace[1864526367] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"385.454196ms","start":"2026-05-28T16:09:33.326203Z","end":"2026-05-28T16:09:33.711657Z","steps":["trace[1864526367] 'process raft request' (duration: 385.049708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.711813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:33.326195Z","time spent":"385.549358ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:33.711846Z","caller":"traceutil/trace.go:171","msg":"trace[1756502159] linearizableReadLoop","detail":"{readStateIndex:25483; appliedIndex:25479; }","duration":"384.116032ms","start":"2026-05-28T16:09:33.32772Z","end":"2026-05-28T16:09:33.711836Z","steps":["trace[1756502159] 'read index received' (duration: 28.060502ms)","trace[1756502159] 'applied index is now lower than readState.Index' (duration: 356.05481ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:33.711935Z","caller":"traceutil/trace.go:171","msg":"trace[908282192] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"385.230113ms","start":"2026-05-28T16:09:33.326699Z","end":"2026-05-28T16:09:33.711929Z","steps":["trace[908282192] 'process raft request' (duration: 384.871625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.711968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:33.326683Z","time spent":"385.264574ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:33.712038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.316615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:33.712056Z","caller":"traceutil/trace.go:171","msg":"trace[992225274] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24671; }","duration":"384.346377ms","start":"2026-05-28T16:09:33.327703Z","end":"2026-05-28T16:09:33.71205Z","steps":["trace[992225274] 'agreement among raft nodes before linearized reading' (duration: 384.309456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.712076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:33.327696Z","time spent":"384.376517ms","remote":"127.0.0.1:58874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-05-28T16:09:33.760679Z","caller":"traceutil/trace.go:171","msg":"trace[285873272] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"403.803722ms","start":"2026-05-28T16:09:33.356826Z","end":"2026-05-28T16:09:33.76063Z","steps":["trace[285873272] 'process raft request' (duration: 403.515167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.760954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:33.356807Z","time spent":"403.969365ms","remote":"127.0.0.1:58732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:33.760946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.045128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:33.76107Z","caller":"traceutil/trace.go:171","msg":"trace[1204056067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24673; }","duration":"433.202511ms","start":"2026-05-28T16:09:33.327835Z","end":"2026-05-28T16:09:33.761038Z","steps":["trace[1204056067] 'agreement among raft nodes before linearized reading' (duration: 432.923656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:33.761139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:33.32779Z","time spent":"433.339844ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T16:09:34.334983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.758008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:34.335065Z","caller":"traceutil/trace.go:171","msg":"trace[1765597445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24674; }","duration":"101.896131ms","start":"2026-05-28T16:09:34.233149Z","end":"2026-05-28T16:09:34.335045Z","steps":["trace[1765597445] 'range keys from in-memory index tree' (duration: 101.634366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:34.33524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.93122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:34.33564Z","caller":"traceutil/trace.go:171","msg":"trace[1541582093] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24674; }","duration":"289.394589ms","start":"2026-05-28T16:09:34.046223Z","end":"2026-05-28T16:09:34.335617Z","steps":["trace[1541582093] 'range keys from in-memory index tree' (duration: 288.808788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.552404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.387416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:35.552735Z","caller":"traceutil/trace.go:171","msg":"trace[471892925] linearizableReadLoop","detail":"{readStateIndex:25490; appliedIndex:25488; }","duration":"318.512183ms","start":"2026-05-28T16:09:35.23421Z","end":"2026-05-28T16:09:35.552722Z","steps":["trace[471892925] 'read index received' (duration: 146.78319ms)","trace[471892925] 'applied index is now lower than readState.Index' (duration: 171.728153ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:35.552799Z","caller":"traceutil/trace.go:171","msg":"trace[1583258910] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"344.589607ms","start":"2026-05-28T16:09:35.208199Z","end":"2026-05-28T16:09:35.552789Z","steps":["trace[1583258910] 'process raft request' (duration: 172.67989ms)","trace[1583258910] 'compare' (duration: 171.164612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:35.55286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.208192Z","time spent":"344.634568ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:35.553151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.91553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:35.553222Z","caller":"traceutil/trace.go:171","msg":"trace[105983773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24678; }","duration":"319.027352ms","start":"2026-05-28T16:09:35.234177Z","end":"2026-05-28T16:09:35.553205Z","steps":["trace[105983773] 'agreement among raft nodes before linearized reading' (duration: 318.892759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.55326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.234165Z","time spent":"319.085983ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T16:09:35.553464Z","caller":"traceutil/trace.go:171","msg":"trace[538786367] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"343.931185ms","start":"2026-05-28T16:09:35.209519Z","end":"2026-05-28T16:09:35.55345Z","steps":["trace[538786367] 'process raft request' (duration: 343.108719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.553547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.209504Z","time spent":"343.991965ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:35.653017Z","caller":"traceutil/trace.go:171","msg":"trace[1372562194] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"326.331073ms","start":"2026-05-28T16:09:35.326654Z","end":"2026-05-28T16:09:35.652985Z","steps":["trace[1372562194] 'process raft request' (duration: 325.961326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.653179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.326623Z","time spent":"326.475136ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:35.653329Z","caller":"traceutil/trace.go:171","msg":"trace[1773892560] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"326.472047ms","start":"2026-05-28T16:09:35.326776Z","end":"2026-05-28T16:09:35.653248Z","steps":["trace[1773892560] 'process raft request' (duration: 326.16368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.653457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.326744Z","time spent":"326.647149ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:35.855647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.381846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:35.855935Z","caller":"traceutil/trace.go:171","msg":"trace[1665673141] linearizableReadLoop","detail":"{readStateIndex:25494; appliedIndex:25490; }","duration":"303.153695ms","start":"2026-05-28T16:09:35.552761Z","end":"2026-05-28T16:09:35.855915Z","steps":["trace[1665673141] 'read index received' (duration: 99.75396ms)","trace[1665673141] 'applied index is now lower than readState.Index' (duration: 203.398455ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:35.856188Z","caller":"traceutil/trace.go:171","msg":"trace[1759698112] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"529.28983ms","start":"2026-05-28T16:09:35.326863Z","end":"2026-05-28T16:09:35.856153Z","steps":["trace[1759698112] 'process raft request' (duration: 326.286892ms)","trace[1759698112] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:507; } (duration: 201.718473ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:35.85604Z","caller":"traceutil/trace.go:171","msg":"trace[1181833850] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"529.128397ms","start":"2026-05-28T16:09:35.326896Z","end":"2026-05-28T16:09:35.856024Z","steps":["trace[1181833850] 'process raft request' (duration: 528.902423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.856338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.285059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:09:35.856401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.326869Z","time spent":"529.469353ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:35.856427Z","caller":"traceutil/trace.go:171","msg":"trace[1202746211] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24682; }","duration":"345.430922ms","start":"2026-05-28T16:09:35.510977Z","end":"2026-05-28T16:09:35.856408Z","steps":["trace[1202746211] 'agreement among raft nodes before linearized reading' (duration: 345.236359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.856473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.510956Z","time spent":"345.505744ms","remote":"127.0.0.1:51422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-28T16:09:35.856751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.326854Z","time spent":"529.464763ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:35.890115Z","caller":"traceutil/trace.go:171","msg":"trace[1477473177] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"123.585671ms","start":"2026-05-28T16:09:35.766507Z","end":"2026-05-28T16:09:35.890093Z","steps":["trace[1477473177] 'process raft request' (duration: 123.371147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.890198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.394704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-28T16:09:35.890252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.650599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-28T16:09:35.890276Z","caller":"traceutil/trace.go:171","msg":"trace[236734381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24683; }","duration":"173.496886ms","start":"2026-05-28T16:09:35.716762Z","end":"2026-05-28T16:09:35.890259Z","steps":["trace[236734381] 'agreement among raft nodes before linearized reading' (duration: 173.270242ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:35.890297Z","caller":"traceutil/trace.go:171","msg":"trace[866957608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24683; }","duration":"174.731049ms","start":"2026-05-28T16:09:35.715552Z","end":"2026-05-28T16:09:35.890283Z","steps":["trace[866957608] 'agreement among raft nodes before linearized reading' (duration: 174.595017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.890082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.578982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:35.890424Z","caller":"traceutil/trace.go:171","msg":"trace[1186382311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24683; }","duration":"335.966669ms","start":"2026-05-28T16:09:35.554448Z","end":"2026-05-28T16:09:35.890415Z","steps":["trace[1186382311] 'agreement among raft nodes before linearized reading' (duration: 335.562001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:35.890435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.916124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-28T16:09:35.890464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:35.554429Z","time spent":"336.027051ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T16:09:35.89049Z","caller":"traceutil/trace.go:171","msg":"trace[1951734880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24683; }","duration":"174.015846ms","start":"2026-05-28T16:09:35.716457Z","end":"2026-05-28T16:09:35.890473Z","steps":["trace[1951734880] 'agreement among raft nodes before linearized reading' (duration: 173.68564ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:36.052605Z","caller":"traceutil/trace.go:171","msg":"trace[252188726] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"158.070987ms","start":"2026-05-28T16:09:35.89449Z","end":"2026-05-28T16:09:36.052561Z","steps":["trace[252188726] 'process raft request' (duration: 101.807309ms)","trace[252188726] 'compare' (duration: 55.85505ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:36.052667Z","caller":"traceutil/trace.go:171","msg":"trace[1386587292] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"157.660629ms","start":"2026-05-28T16:09:35.894982Z","end":"2026-05-28T16:09:36.052642Z","steps":["trace[1386587292] 'process raft request' (duration: 157.493746ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:36.052666Z","caller":"traceutil/trace.go:171","msg":"trace[2018259946] linearizableReadLoop","detail":"{readStateIndex:25498; appliedIndex:25495; }","duration":"115.456743ms","start":"2026-05-28T16:09:35.937184Z","end":"2026-05-28T16:09:36.052641Z","steps":["trace[2018259946] 'read index received' (duration: 59.103553ms)","trace[2018259946] 'applied index is now lower than readState.Index' (duration: 56.35183ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:36.052696Z","caller":"traceutil/trace.go:171","msg":"trace[505977776] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"157.65778ms","start":"2026-05-28T16:09:35.895003Z","end":"2026-05-28T16:09:36.052661Z","steps":["trace[505977776] 'process raft request' (duration: 157.597609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:36.052927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.716918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T16:09:36.052971Z","caller":"traceutil/trace.go:171","msg":"trace[468246067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24686; }","duration":"115.827011ms","start":"2026-05-28T16:09:35.937132Z","end":"2026-05-28T16:09:36.052959Z","steps":["trace[468246067] 'agreement among raft nodes before linearized reading' (duration: 115.645697ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:37.09584Z","caller":"traceutil/trace.go:171","msg":"trace[975586756] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"417.025307ms","start":"2026-05-28T16:09:36.678758Z","end":"2026-05-28T16:09:37.095783Z","steps":["trace[975586756] 'process raft request' (duration: 416.754362ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:37.095868Z","caller":"traceutil/trace.go:171","msg":"trace[1541711524] linearizableReadLoop","detail":"{readStateIndex:25500; appliedIndex:25499; }","duration":"205.435094ms","start":"2026-05-28T16:09:36.890397Z","end":"2026-05-28T16:09:37.095832Z","steps":["trace[1541711524] 'read index received' (duration: 205.191539ms)","trace[1541711524] 'applied index is now lower than readState.Index' (duration: 239.965µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:37.096095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:36.678726Z","time spent":"417.246742ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:37.096333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.911214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T16:09:37.096605Z","caller":"traceutil/trace.go:171","msg":"trace[1170352391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24688; }","duration":"206.099148ms","start":"2026-05-28T16:09:36.890348Z","end":"2026-05-28T16:09:37.096447Z","steps":["trace[1170352391] 'agreement among raft nodes before linearized reading' (duration: 205.586868ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:41.537016Z","caller":"traceutil/trace.go:171","msg":"trace[1278707112] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"282.894593ms","start":"2026-05-28T16:09:41.25409Z","end":"2026-05-28T16:09:41.536985Z","steps":["trace[1278707112] 'process raft request' (duration: 258.606193ms)","trace[1278707112] 'compare' (duration: 23.972604ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:42.093798Z","caller":"traceutil/trace.go:171","msg":"trace[1362104600] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25530; }","duration":"632.400775ms","start":"2026-05-28T16:09:41.461367Z","end":"2026-05-28T16:09:42.093768Z","steps":["trace[1362104600] 'read index received' (duration: 51.444925ms)","trace[1362104600] 'applied index is now lower than readState.Index' (duration: 580.95461ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:42.09403Z","caller":"traceutil/trace.go:171","msg":"trace[1026249102] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"783.928226ms","start":"2026-05-28T16:09:41.310062Z","end":"2026-05-28T16:09:42.09399Z","steps":["trace[1026249102] 'process raft request' (duration: 773.878651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.094105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.71666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:09:42.094199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.310031Z","time spent":"784.074189ms","remote":"127.0.0.1:35540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418382,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:42.094223Z","caller":"traceutil/trace.go:171","msg":"trace[161171627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24719; }","duration":"632.868413ms","start":"2026-05-28T16:09:41.461309Z","end":"2026-05-28T16:09:42.094177Z","steps":["trace[161171627] 'agreement among raft nodes before linearized reading' (duration: 632.68276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.094288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.461288Z","time spent":"632.989375ms","remote":"127.0.0.1:58708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T16:09:42.369909Z","caller":"traceutil/trace.go:171","msg":"trace[1336848147] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"796.801235ms","start":"2026-05-28T16:09:41.573082Z","end":"2026-05-28T16:09:42.369883Z","steps":["trace[1336848147] 'process raft request' (duration: 793.787827ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:42.369945Z","caller":"traceutil/trace.go:171","msg":"trace[1280709178] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"699.587375ms","start":"2026-05-28T16:09:41.670333Z","end":"2026-05-28T16:09:42.369921Z","steps":["trace[1280709178] 'process raft request' (duration: 699.507203ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:42.370005Z","caller":"traceutil/trace.go:171","msg":"trace[234763049] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"796.03774ms","start":"2026-05-28T16:09:41.573918Z","end":"2026-05-28T16:09:42.369956Z","steps":["trace[234763049] 'process raft request' (duration: 795.829256ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:42.370087Z","caller":"traceutil/trace.go:171","msg":"trace[1340216449] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"697.8184ms","start":"2026-05-28T16:09:41.672253Z","end":"2026-05-28T16:09:42.370071Z","steps":["trace[1340216449] 'process raft request' (duration: 697.629566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.370187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.672241Z","time spent":"697.885362ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:42.370249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.573907Z","time spent":"796.203683ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:42.370051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.57306Z","time spent":"796.917857ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:42.370478Z","caller":"traceutil/trace.go:171","msg":"trace[1241651102] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"495.69078ms","start":"2026-05-28T16:09:41.874759Z","end":"2026-05-28T16:09:42.370449Z","steps":["trace[1241651102] 'process raft request' (duration: 495.259742ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:42.370685Z","caller":"traceutil/trace.go:171","msg":"trace[1429248329] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"495.481285ms","start":"2026-05-28T16:09:41.875188Z","end":"2026-05-28T16:09:42.370669Z","steps":["trace[1429248329] 'process raft request' (duration: 495.170849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.370679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.874731Z","time spent":"495.831963ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:42.37075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.875158Z","time spent":"495.562346ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:42.371187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.670306Z","time spent":"699.704886ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:42.371261Z","caller":"traceutil/trace.go:171","msg":"trace[640784192] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"452.77705ms","start":"2026-05-28T16:09:41.918465Z","end":"2026-05-28T16:09:42.371242Z","steps":["trace[640784192] 'process raft request' (duration: 452.108396ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:42.371273Z","caller":"traceutil/trace.go:171","msg":"trace[1610103855] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"270.597535ms","start":"2026-05-28T16:09:42.100654Z","end":"2026-05-28T16:09:42.371252Z","steps":["trace[1610103855] 'process raft request' (duration: 270.503223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.371315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.91844Z","time spent":"452.846512ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:42.371384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.446305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:09:42.371581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.608838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-28T16:09:42.371298Z","caller":"traceutil/trace.go:171","msg":"trace[101566111] linearizableReadLoop","detail":"{readStateIndex:25539; appliedIndex:25532; }","duration":"277.429608ms","start":"2026-05-28T16:09:42.093856Z","end":"2026-05-28T16:09:42.371286Z","steps":["trace[101566111] 'read index received' (duration: 272.982961ms)","trace[101566111] 'applied index is now lower than readState.Index' (duration: 4.445207ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:42.371666Z","caller":"traceutil/trace.go:171","msg":"trace[63075559] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24727; }","duration":"632.68963ms","start":"2026-05-28T16:09:41.738882Z","end":"2026-05-28T16:09:42.371571Z","steps":["trace[63075559] 'agreement among raft nodes before linearized reading' (duration: 632.436395ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:42.371713Z","caller":"traceutil/trace.go:171","msg":"trace[834576215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24727; }","duration":"296.766141ms","start":"2026-05-28T16:09:42.074926Z","end":"2026-05-28T16:09:42.371692Z","steps":["trace[834576215] 'agreement among raft nodes before linearized reading' (duration: 296.427215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.371761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:41.738858Z","time spent":"632.861804ms","remote":"127.0.0.1:44070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-28T16:09:42.371517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.668261ms","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-28T16:09:42.371875Z","caller":"traceutil/trace.go:171","msg":"trace[1507886280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24727; }","duration":"295.018987ms","start":"2026-05-28T16:09:42.076831Z","end":"2026-05-28T16:09:42.37185Z","steps":["trace[1507886280] 'agreement among raft nodes before linearized reading' (duration: 294.599439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.371508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.7038ms","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-28T16:09:42.372131Z","caller":"traceutil/trace.go:171","msg":"trace[509862425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24727; }","duration":"299.372932ms","start":"2026-05-28T16:09:42.072741Z","end":"2026-05-28T16:09:42.372114Z","steps":["trace[509862425] 'agreement among raft nodes before linearized reading' (duration: 298.660229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.372203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.730388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-28T16:09:42.372281Z","caller":"traceutil/trace.go:171","msg":"trace[605176358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24727; }","duration":"193.83699ms","start":"2026-05-28T16:09:42.178424Z","end":"2026-05-28T16:09:42.372261Z","steps":["trace[605176358] 'agreement among raft nodes before linearized reading' (duration: 193.559955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:42.37229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.341136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:42.372452Z","caller":"traceutil/trace.go:171","msg":"trace[1616998861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24727; }","duration":"139.519249ms","start":"2026-05-28T16:09:42.232853Z","end":"2026-05-28T16:09:42.372372Z","steps":["trace[1616998861] 'agreement among raft nodes before linearized reading' (duration: 139.267885ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:48.5842Z","caller":"traceutil/trace.go:171","msg":"trace[1204061546] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"188.130659ms","start":"2026-05-28T16:09:48.396046Z","end":"2026-05-28T16:09:48.584177Z","steps":["trace[1204061546] 'process raft request' (duration: 187.931765ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:48.584499Z","caller":"traceutil/trace.go:171","msg":"trace[1819629358] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"187.066429ms","start":"2026-05-28T16:09:48.397413Z","end":"2026-05-28T16:09:48.584479Z","steps":["trace[1819629358] 'process raft request' (duration: 186.720061ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:48.584759Z","caller":"traceutil/trace.go:171","msg":"trace[1887263687] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"186.353625ms","start":"2026-05-28T16:09:48.39839Z","end":"2026-05-28T16:09:48.584744Z","steps":["trace[1887263687] 'process raft request' (duration: 186.134051ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:48.584596Z","caller":"traceutil/trace.go:171","msg":"trace[1194463294] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"187.11016ms","start":"2026-05-28T16:09:48.397461Z","end":"2026-05-28T16:09:48.584571Z","steps":["trace[1194463294] 'process raft request' (duration: 186.908666ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:48.584808Z","caller":"traceutil/trace.go:171","msg":"trace[664106040] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"186.356156ms","start":"2026-05-28T16:09:48.398437Z","end":"2026-05-28T16:09:48.584793Z","steps":["trace[664106040] 'process raft request' (duration: 186.208963ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:48.58491Z","caller":"traceutil/trace.go:171","msg":"trace[1266224285] linearizableReadLoop","detail":"{readStateIndex:25587; appliedIndex:25581; }","duration":"182.47244ms","start":"2026-05-28T16:09:48.402422Z","end":"2026-05-28T16:09:48.584895Z","steps":["trace[1266224285] 'read index received' (duration: 20.980115ms)","trace[1266224285] 'applied index is now lower than readState.Index' (duration: 161.491205ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:48.584976Z","caller":"traceutil/trace.go:171","msg":"trace[1152031721] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"186.266924ms","start":"2026-05-28T16:09:48.398691Z","end":"2026-05-28T16:09:48.584958Z","steps":["trace[1152031721] 'process raft request' (duration: 186.06366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:48.585109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.667783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T16:09:48.585173Z","caller":"traceutil/trace.go:171","msg":"trace[740047364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24773; }","duration":"182.765075ms","start":"2026-05-28T16:09:48.402397Z","end":"2026-05-28T16:09:48.585163Z","steps":["trace[740047364] 'agreement among raft nodes before linearized reading' (duration: 182.575061ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:48.716638Z","caller":"traceutil/trace.go:171","msg":"trace[1178132835] linearizableReadLoop","detail":"{readStateIndex:25589; appliedIndex:25587; }","duration":"131.659107ms","start":"2026-05-28T16:09:48.58495Z","end":"2026-05-28T16:09:48.716609Z","steps":["trace[1178132835] 'read index received' (duration: 48.868155ms)","trace[1178132835] 'applied index is now lower than readState.Index' (duration: 82.788572ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:48.716664Z","caller":"traceutil/trace.go:171","msg":"trace[188753382] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"225.524533ms","start":"2026-05-28T16:09:48.491095Z","end":"2026-05-28T16:09:48.71662Z","steps":["trace[188753382] 'process raft request' (duration: 225.183067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:48.794484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.190518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-28T16:09:48.794579Z","caller":"traceutil/trace.go:171","msg":"trace[2125533896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24774; }","duration":"391.31453ms","start":"2026-05-28T16:09:48.403241Z","end":"2026-05-28T16:09:48.794556Z","steps":["trace[2125533896] 'agreement among raft nodes before linearized reading' (duration: 313.512915ms)","trace[2125533896] 'range keys from in-memory index tree' (duration: 77.57347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:48.79466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:48.403235Z","time spent":"391.414133ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T16:09:48.79465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.459254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-28T16:09:48.794723Z","caller":"traceutil/trace.go:171","msg":"trace[1828095463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24774; }","duration":"391.572376ms","start":"2026-05-28T16:09:48.403133Z","end":"2026-05-28T16:09:48.794705Z","steps":["trace[1828095463] 'agreement among raft nodes before linearized reading' (duration: 313.577557ms)","trace[1828095463] 'range keys from in-memory index tree' (duration: 77.623522ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:48.79477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:48.403121Z","time spent":"391.639817ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T16:09:48.794805Z","caller":"traceutil/trace.go:171","msg":"trace[599499285] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"205.421385ms","start":"2026-05-28T16:09:48.589344Z","end":"2026-05-28T16:09:48.794765Z","steps":["trace[599499285] 'process raft request' (duration: 151.167885ms)","trace[599499285] 'compare' (duration: 54.002346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:48.794908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.392252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-28T16:09:48.794993Z","caller":"traceutil/trace.go:171","msg":"trace[1798759072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24774; }","duration":"391.505254ms","start":"2026-05-28T16:09:48.403468Z","end":"2026-05-28T16:09:48.794973Z","steps":["trace[1798759072] 'agreement among raft nodes before linearized reading' (duration: 313.277731ms)","trace[1798759072] 'range keys from in-memory index tree' (duration: 77.58237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:48.795041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:48.403459Z","time spent":"391.573345ms","remote":"127.0.0.1:58944","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-28T16:09:49.311805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.336654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:49.312014Z","caller":"traceutil/trace.go:171","msg":"trace[425545040] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"512.76765ms","start":"2026-05-28T16:09:48.799216Z","end":"2026-05-28T16:09:49.311984Z","steps":["trace[425545040] 'process raft request' (duration: 310.14947ms)","trace[425545040] 'compare' (duration: 201.979318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:49.312158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:48.799164Z","time spent":"512.920283ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:09:49.312299Z","caller":"traceutil/trace.go:171","msg":"trace[65588626] linearizableReadLoop","detail":"{readStateIndex:25593; appliedIndex:25591; }","duration":"177.587655ms","start":"2026-05-28T16:09:49.13469Z","end":"2026-05-28T16:09:49.312278Z","steps":["trace[65588626] 'read index received' (duration: 44.921µs)","trace[65588626] 'applied index is now lower than readState.Index' (duration: 177.541484ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:49.312449Z","caller":"traceutil/trace.go:171","msg":"trace[226822165] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"512.842421ms","start":"2026-05-28T16:09:48.799584Z","end":"2026-05-28T16:09:49.312426Z","steps":["trace[226822165] 'process raft request' (duration: 512.321791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:49.312525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.328843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T16:09:49.31257Z","caller":"traceutil/trace.go:171","msg":"trace[1595655256] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:24778; }","duration":"173.410034ms","start":"2026-05-28T16:09:49.139149Z","end":"2026-05-28T16:09:49.312559Z","steps":["trace[1595655256] 'agreement among raft nodes before linearized reading' (duration: 173.349822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:49.31257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:48.799567Z","time spent":"512.935824ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:49.312468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.776669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:49.312721Z","caller":"traceutil/trace.go:171","msg":"trace[652708775] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24778; }","duration":"178.044235ms","start":"2026-05-28T16:09:49.134644Z","end":"2026-05-28T16:09:49.312689Z","steps":["trace[652708775] 'agreement among raft nodes before linearized reading' (duration: 177.783159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:49.312854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.090986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T16:09:49.312899Z","caller":"traceutil/trace.go:171","msg":"trace[2061174382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24778; }","duration":"132.181668ms","start":"2026-05-28T16:09:49.180706Z","end":"2026-05-28T16:09:49.312888Z","steps":["trace[2061174382] 'agreement among raft nodes before linearized reading' (duration: 132.044805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:53.985638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.348273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:53.985718Z","caller":"traceutil/trace.go:171","msg":"trace[1266862338] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24867; }","duration":"175.497716ms","start":"2026-05-28T16:09:53.810204Z","end":"2026-05-28T16:09:53.985702Z","steps":["trace[1266862338] 'range keys from in-memory index tree' (duration: 175.233881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:53.985742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.44001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:09:53.985811Z","caller":"traceutil/trace.go:171","msg":"trace[85729869] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:24867; }","duration":"168.717425ms","start":"2026-05-28T16:09:53.817076Z","end":"2026-05-28T16:09:53.985794Z","steps":["trace[85729869] 'count revisions from in-memory index tree' (duration: 168.330337ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:54.442043Z","caller":"traceutil/trace.go:171","msg":"trace[52443144] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"167.4621ms","start":"2026-05-28T16:09:54.274548Z","end":"2026-05-28T16:09:54.44201Z","steps":["trace[52443144] 'process raft request' (duration: 167.243795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:54.807594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.227251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:09:54.807704Z","caller":"traceutil/trace.go:171","msg":"trace[887438336] linearizableReadLoop","detail":"{readStateIndex:25687; appliedIndex:25686; }","duration":"285.563215ms","start":"2026-05-28T16:09:54.522122Z","end":"2026-05-28T16:09:54.807685Z","steps":["trace[887438336] 'read index received' (duration: 126.152921ms)","trace[887438336] 'applied index is now lower than readState.Index' (duration: 159.408784ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:54.808068Z","caller":"traceutil/trace.go:171","msg":"trace[261633767] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"289.073092ms","start":"2026-05-28T16:09:54.518908Z","end":"2026-05-28T16:09:54.807981Z","steps":["trace[261633767] 'process raft request' (duration: 129.353372ms)","trace[261633767] 'compare' (duration: 158.878985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:09:54.808152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.007783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-28T16:09:54.808236Z","caller":"traceutil/trace.go:171","msg":"trace[459311914] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24870; }","duration":"286.106115ms","start":"2026-05-28T16:09:54.522108Z","end":"2026-05-28T16:09:54.808214Z","steps":["trace[459311914] 'agreement among raft nodes before linearized reading' (duration: 285.877141ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:54.849978Z","caller":"traceutil/trace.go:171","msg":"trace[418574587] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"247.789434ms","start":"2026-05-28T16:09:54.60217Z","end":"2026-05-28T16:09:54.849959Z","steps":["trace[418574587] 'process raft request' (duration: 247.694022ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:54.850417Z","caller":"traceutil/trace.go:171","msg":"trace[1383492529] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"247.464548ms","start":"2026-05-28T16:09:54.602934Z","end":"2026-05-28T16:09:54.850399Z","steps":["trace[1383492529] 'process raft request' (duration: 246.975089ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:54.850657Z","caller":"traceutil/trace.go:171","msg":"trace[637746861] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"248.861594ms","start":"2026-05-28T16:09:54.601713Z","end":"2026-05-28T16:09:54.850574Z","steps":["trace[637746861] 'process raft request' (duration: 248.045489ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:54.851141Z","caller":"traceutil/trace.go:171","msg":"trace[17556322] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"246.971669ms","start":"2026-05-28T16:09:54.604111Z","end":"2026-05-28T16:09:54.851083Z","steps":["trace[17556322] 'process raft request' (duration: 246.03489ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:54.851159Z","caller":"traceutil/trace.go:171","msg":"trace[1163303736] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"242.831958ms","start":"2026-05-28T16:09:54.608309Z","end":"2026-05-28T16:09:54.851141Z","steps":["trace[1163303736] 'process raft request' (duration: 242.44588ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:55.001765Z","caller":"traceutil/trace.go:171","msg":"trace[1547682994] linearizableReadLoop","detail":"{readStateIndex:25693; appliedIndex:25687; }","duration":"193.81995ms","start":"2026-05-28T16:09:54.807917Z","end":"2026-05-28T16:09:55.001737Z","steps":["trace[1547682994] 'read index received' (duration: 41.912991ms)","trace[1547682994] 'applied index is now lower than readState.Index' (duration: 151.905659ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:09:55.001815Z","caller":"traceutil/trace.go:171","msg":"trace[1708375399] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"344.81457ms","start":"2026-05-28T16:09:54.65696Z","end":"2026-05-28T16:09:55.001774Z","steps":["trace[1708375399] 'process raft request' (duration: 344.622477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:55.001993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:54.656938Z","time spent":"344.957594ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:09:55.002088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.552738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-28T16:09:55.002146Z","caller":"traceutil/trace.go:171","msg":"trace[1927147040] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24876; }","duration":"309.65997ms","start":"2026-05-28T16:09:54.692472Z","end":"2026-05-28T16:09:55.002132Z","steps":["trace[1927147040] 'agreement among raft nodes before linearized reading' (duration: 309.440546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:55.002182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:54.692452Z","time spent":"309.721342ms","remote":"127.0.0.1:58864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-28T16:09:55.002501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.392504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-28T16:09:55.002607Z","caller":"traceutil/trace.go:171","msg":"trace[871642007] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24876; }","duration":"190.523625ms","start":"2026-05-28T16:09:54.812046Z","end":"2026-05-28T16:09:55.00257Z","steps":["trace[871642007] 'agreement among raft nodes before linearized reading' (duration: 190.317241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:55.002634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.061925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-05-28T16:09:55.002672Z","caller":"traceutil/trace.go:171","msg":"trace[1251988381] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:24876; }","duration":"406.136827ms","start":"2026-05-28T16:09:54.596521Z","end":"2026-05-28T16:09:55.002658Z","steps":["trace[1251988381] 'agreement among raft nodes before linearized reading' (duration: 406.025495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:55.002714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:09:54.5965Z","time spent":"406.207149ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"warn","ts":"2026-05-28T16:09:55.002764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.657789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b3c68e5b1f28ba\" ","response":"range_response_count:1 size:736"} {"level":"warn","ts":"2026-05-28T16:09:55.002521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.743711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-28T16:09:55.002816Z","caller":"traceutil/trace.go:171","msg":"trace[859679429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24876; }","duration":"114.072647ms","start":"2026-05-28T16:09:54.888735Z","end":"2026-05-28T16:09:55.002807Z","steps":["trace[859679429] 'agreement among raft nodes before linearized reading' (duration: 113.650999ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:55.002817Z","caller":"traceutil/trace.go:171","msg":"trace[321179455] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b3c68e5b1f28ba; range_end:; response_count:1; response_revision:24876; }","duration":"190.73458ms","start":"2026-05-28T16:09:54.812068Z","end":"2026-05-28T16:09:55.002802Z","steps":["trace[321179455] 'agreement among raft nodes before linearized reading' (duration: 190.608377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:09:55.003125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.274504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-28T16:09:55.003176Z","caller":"traceutil/trace.go:171","msg":"trace[335537277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24876; }","duration":"187.325765ms","start":"2026-05-28T16:09:54.815839Z","end":"2026-05-28T16:09:55.003164Z","steps":["trace[335537277] 'agreement among raft nodes before linearized reading' (duration: 187.203262ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:09:57.684431Z","caller":"traceutil/trace.go:171","msg":"trace[2026513033] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"119.658245ms","start":"2026-05-28T16:09:57.564731Z","end":"2026-05-28T16:09:57.684389Z","steps":["trace[2026513033] 'process raft request' (duration: 116.550225ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:03.487677Z","caller":"traceutil/trace.go:171","msg":"trace[333120987] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"189.14412ms","start":"2026-05-28T16:10:03.298479Z","end":"2026-05-28T16:10:03.487623Z","steps":["trace[333120987] 'process raft request' (duration: 100.465544ms)","trace[333120987] 'compare' (duration: 87.546213ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:03.654649Z","caller":"traceutil/trace.go:171","msg":"trace[1008394917] linearizableReadLoop","detail":"{readStateIndex:25805; appliedIndex:25802; }","duration":"130.522345ms","start":"2026-05-28T16:10:03.524092Z","end":"2026-05-28T16:10:03.654614Z","steps":["trace[1008394917] 'read index received' (duration: 25.496833ms)","trace[1008394917] 'applied index is now lower than readState.Index' (duration: 105.021852ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:03.654673Z","caller":"traceutil/trace.go:171","msg":"trace[1178107246] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"162.80388ms","start":"2026-05-28T16:10:03.491832Z","end":"2026-05-28T16:10:03.654635Z","steps":["trace[1178107246] 'process raft request' (duration: 154.051421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:03.654922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.79865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T16:10:03.654981Z","caller":"traceutil/trace.go:171","msg":"trace[1759932143] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24986; }","duration":"130.888481ms","start":"2026-05-28T16:10:03.524076Z","end":"2026-05-28T16:10:03.654964Z","steps":["trace[1759932143] 'agreement among raft nodes before linearized reading' (duration: 130.655617ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:03.655495Z","caller":"traceutil/trace.go:171","msg":"trace[769543051] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"163.617925ms","start":"2026-05-28T16:10:03.491852Z","end":"2026-05-28T16:10:03.65547Z","steps":["trace[769543051] 'process raft request' (duration: 162.585485ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:09.250283Z","caller":"traceutil/trace.go:171","msg":"trace[1449751038] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"337.067121ms","start":"2026-05-28T16:10:08.913191Z","end":"2026-05-28T16:10:09.250258Z","steps":["trace[1449751038] 'process raft request' (duration: 337.01143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:09.250417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:08.913146Z","time spent":"337.200094ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:10:09.250425Z","caller":"traceutil/trace.go:171","msg":"trace[377952932] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"337.452839ms","start":"2026-05-28T16:10:08.912907Z","end":"2026-05-28T16:10:09.25036Z","steps":["trace[377952932] 'process raft request' (duration: 337.264476ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:09.250523Z","caller":"traceutil/trace.go:171","msg":"trace[1207780646] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"334.806638ms","start":"2026-05-28T16:10:08.915657Z","end":"2026-05-28T16:10:09.250463Z","steps":["trace[1207780646] 'process raft request' (duration: 334.572514ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:09.2503Z","caller":"traceutil/trace.go:171","msg":"trace[906127888] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"337.354327ms","start":"2026-05-28T16:10:08.912889Z","end":"2026-05-28T16:10:09.250243Z","steps":["trace[906127888] 'process raft request' (duration: 337.159283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:09.25078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:08.91288Z","time spent":"337.743044ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:10:09.25078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:08.915643Z","time spent":"334.980661ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T16:10:09.251022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:08.912853Z","time spent":"337.942349ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:10:09.285877Z","caller":"traceutil/trace.go:171","msg":"trace[259303026] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"145.544575ms","start":"2026-05-28T16:10:09.140261Z","end":"2026-05-28T16:10:09.285805Z","steps":["trace[259303026] 'process raft request' (duration: 145.346312ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:09.58576Z","caller":"traceutil/trace.go:171","msg":"trace[1904817546] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"260.588581ms","start":"2026-05-28T16:10:09.325131Z","end":"2026-05-28T16:10:09.58572Z","steps":["trace[1904817546] 'process raft request' (duration: 260.277216ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:09.633896Z","caller":"traceutil/trace.go:171","msg":"trace[484390835] linearizableReadLoop","detail":"{readStateIndex:25851; appliedIndex:25849; }","duration":"124.883787ms","start":"2026-05-28T16:10:09.508985Z","end":"2026-05-28T16:10:09.633869Z","steps":["trace[484390835] 'read index received' (duration: 76.434619ms)","trace[484390835] 'applied index is now lower than readState.Index' (duration: 48.448058ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:09.634253Z","caller":"traceutil/trace.go:171","msg":"trace[860453841] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"307.571351ms","start":"2026-05-28T16:10:09.326663Z","end":"2026-05-28T16:10:09.634234Z","steps":["trace[860453841] 'process raft request' (duration: 307.063071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:09.635437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.458116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-28T16:10:09.635498Z","caller":"traceutil/trace.go:171","msg":"trace[740891462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25030; }","duration":"126.560498ms","start":"2026-05-28T16:10:09.50892Z","end":"2026-05-28T16:10:09.635481Z","steps":["trace[740891462] 'agreement among raft nodes before linearized reading' (duration: 126.428796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:09.636536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:09.326611Z","time spent":"307.689083ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:10:11.84124Z","caller":"traceutil/trace.go:171","msg":"trace[750776625] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"102.588796ms","start":"2026-05-28T16:10:11.738626Z","end":"2026-05-28T16:10:11.841215Z","steps":["trace[750776625] 'process raft request' (duration: 102.452792ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:24.795255Z","caller":"traceutil/trace.go:171","msg":"trace[637530312] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"132.197927ms","start":"2026-05-28T16:10:24.663042Z","end":"2026-05-28T16:10:24.79524Z","steps":["trace[637530312] 'process raft request' (duration: 132.102605ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:25.141821Z","caller":"traceutil/trace.go:171","msg":"trace[315630579] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"127.351703ms","start":"2026-05-28T16:10:25.014443Z","end":"2026-05-28T16:10:25.141794Z","steps":["trace[315630579] 'process raft request' (duration: 126.939665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:25.283866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.731904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-28T16:10:25.283959Z","caller":"traceutil/trace.go:171","msg":"trace[2005772647] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25166; }","duration":"106.843117ms","start":"2026-05-28T16:10:25.17709Z","end":"2026-05-28T16:10:25.283933Z","steps":["trace[2005772647] 'range keys from in-memory index tree' (duration: 106.627542ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:25.468563Z","caller":"traceutil/trace.go:171","msg":"trace[954284985] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"158.984526ms","start":"2026-05-28T16:10:25.309461Z","end":"2026-05-28T16:10:25.468445Z","steps":["trace[954284985] 'process raft request' (duration: 158.821033ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:25.650707Z","caller":"traceutil/trace.go:171","msg":"trace[73577493] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"341.111489ms","start":"2026-05-28T16:10:25.309555Z","end":"2026-05-28T16:10:25.650667Z","steps":["trace[73577493] 'process raft request' (duration: 268.042385ms)","trace[73577493] 'compare' (duration: 72.86474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:10:25.650858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:25.30952Z","time spent":"341.258282ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:10:25.650897Z","caller":"traceutil/trace.go:171","msg":"trace[1540451229] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"340.963986ms","start":"2026-05-28T16:10:25.30991Z","end":"2026-05-28T16:10:25.650874Z","steps":["trace[1540451229] 'process raft request' (duration: 340.702271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:25.651036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:25.309813Z","time spent":"341.14695ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:10:25.651077Z","caller":"traceutil/trace.go:171","msg":"trace[1920661737] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"332.950662ms","start":"2026-05-28T16:10:25.318108Z","end":"2026-05-28T16:10:25.651059Z","steps":["trace[1920661737] 'process raft request' (duration: 332.684977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:25.651135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:25.318099Z","time spent":"333.005743ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:10:25.651124Z","caller":"traceutil/trace.go:171","msg":"trace[1428600773] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"299.590155ms","start":"2026-05-28T16:10:25.351513Z","end":"2026-05-28T16:10:25.651103Z","steps":["trace[1428600773] 'process raft request' (duration: 299.479333ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:25.826491Z","caller":"traceutil/trace.go:171","msg":"trace[1044584315] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"105.588943ms","start":"2026-05-28T16:10:25.720877Z","end":"2026-05-28T16:10:25.826466Z","steps":["trace[1044584315] 'process raft request' (duration: 70.942172ms)","trace[1044584315] 'compare' (duration: 34.292215ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:26.030368Z","caller":"traceutil/trace.go:171","msg":"trace[1784319304] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25995; }","duration":"307.681223ms","start":"2026-05-28T16:10:25.722662Z","end":"2026-05-28T16:10:26.030344Z","steps":["trace[1784319304] 'read index received' (duration: 69.188228ms)","trace[1784319304] 'applied index is now lower than readState.Index' (duration: 238.491795ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:26.030431Z","caller":"traceutil/trace.go:171","msg":"trace[378089170] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"308.0672ms","start":"2026-05-28T16:10:25.722333Z","end":"2026-05-28T16:10:26.030401Z","steps":["trace[378089170] 'process raft request' (duration: 307.863036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:26.030574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.899087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-28T16:10:26.030608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:25.722313Z","time spent":"308.184252ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T16:10:26.030639Z","caller":"traceutil/trace.go:171","msg":"trace[1835437997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25173; }","duration":"307.996568ms","start":"2026-05-28T16:10:25.722631Z","end":"2026-05-28T16:10:26.030627Z","steps":["trace[1835437997] 'agreement among raft nodes before linearized reading' (duration: 307.831225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:26.030674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:25.722614Z","time spent":"308.0514ms","remote":"127.0.0.1:58944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T16:10:26.037595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.052107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T16:10:26.037668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.235606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T16:10:26.037681Z","caller":"traceutil/trace.go:171","msg":"trace[355750140] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25174; }","duration":"312.19944ms","start":"2026-05-28T16:10:25.725463Z","end":"2026-05-28T16:10:26.037662Z","steps":["trace[355750140] 'agreement among raft nodes before linearized reading' (duration: 312.045097ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:26.03772Z","caller":"traceutil/trace.go:171","msg":"trace[729412551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25174; }","duration":"195.329748ms","start":"2026-05-28T16:10:25.842377Z","end":"2026-05-28T16:10:26.037707Z","steps":["trace[729412551] 'agreement among raft nodes before linearized reading' (duration: 195.169895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:26.037726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:25.725439Z","time spent":"312.277881ms","remote":"127.0.0.1:59140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-28T16:10:26.037794Z","caller":"traceutil/trace.go:171","msg":"trace[550556020] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"140.825214ms","start":"2026-05-28T16:10:25.896927Z","end":"2026-05-28T16:10:26.037752Z","steps":["trace[550556020] 'process raft request' (duration: 140.435107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:26.037843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.272789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:10:26.03788Z","caller":"traceutil/trace.go:171","msg":"trace[1726514920] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25174; }","duration":"300.349171ms","start":"2026-05-28T16:10:25.737518Z","end":"2026-05-28T16:10:26.037867Z","steps":["trace[1726514920] 'agreement among raft nodes before linearized reading' (duration: 300.288809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:26.037904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T16:10:25.737501Z","time spent":"300.396171ms","remote":"127.0.0.1:51460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"info","ts":"2026-05-28T16:10:28.939433Z","caller":"traceutil/trace.go:171","msg":"trace[1558919497] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"134.254507ms","start":"2026-05-28T16:10:28.805156Z","end":"2026-05-28T16:10:28.939411Z","steps":["trace[1558919497] 'process raft request' (duration: 134.103164ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:29.846942Z","caller":"traceutil/trace.go:171","msg":"trace[566683794] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"185.230144ms","start":"2026-05-28T16:10:29.66168Z","end":"2026-05-28T16:10:29.84691Z","steps":["trace[566683794] 'process raft request' (duration: 122.968579ms)","trace[566683794] 'compare' (duration: 62.152633ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:29.861399Z","caller":"traceutil/trace.go:171","msg":"trace[527421989] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"197.689534ms","start":"2026-05-28T16:10:29.663679Z","end":"2026-05-28T16:10:29.861369Z","steps":["trace[527421989] 'process raft request' (duration: 197.369148ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:29.861661Z","caller":"traceutil/trace.go:171","msg":"trace[1753576295] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"197.190314ms","start":"2026-05-28T16:10:29.664454Z","end":"2026-05-28T16:10:29.861644Z","steps":["trace[1753576295] 'process raft request' (duration: 196.798547ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:29.861903Z","caller":"traceutil/trace.go:171","msg":"trace[1527166742] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"197.090332ms","start":"2026-05-28T16:10:29.664801Z","end":"2026-05-28T16:10:29.861892Z","steps":["trace[1527166742] 'process raft request' (duration: 196.779556ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:29.86198Z","caller":"traceutil/trace.go:171","msg":"trace[1872122658] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"135.514301ms","start":"2026-05-28T16:10:29.726419Z","end":"2026-05-28T16:10:29.861933Z","steps":["trace[1872122658] 'process raft request' (duration: 135.412899ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:30.022715Z","caller":"traceutil/trace.go:171","msg":"trace[799970526] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"157.306843ms","start":"2026-05-28T16:10:29.865374Z","end":"2026-05-28T16:10:30.022681Z","steps":["trace[799970526] 'process raft request' (duration: 108.064871ms)","trace[799970526] 'compare' (duration: 49.078699ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:10:30.246387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.759179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:10:30.246472Z","caller":"traceutil/trace.go:171","msg":"trace[1764691704] linearizableReadLoop","detail":"{readStateIndex:26027; appliedIndex:26025; }","duration":"196.346199ms","start":"2026-05-28T16:10:30.050112Z","end":"2026-05-28T16:10:30.246459Z","steps":["trace[1764691704] 'read index received' (duration: 17.638191ms)","trace[1764691704] 'applied index is now lower than readState.Index' (duration: 178.706998ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:30.246534Z","caller":"traceutil/trace.go:171","msg":"trace[1903766141] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"197.532522ms","start":"2026-05-28T16:10:30.048993Z","end":"2026-05-28T16:10:30.246526Z","steps":["trace[1903766141] 'process raft request' (duration: 95.561759ms)","trace[1903766141] 'compare' (duration: 101.597676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:10:30.246823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.455734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T16:10:30.246908Z","caller":"traceutil/trace.go:171","msg":"trace[1319493604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25202; }","duration":"192.560276ms","start":"2026-05-28T16:10:30.054328Z","end":"2026-05-28T16:10:30.246889Z","steps":["trace[1319493604] 'agreement among raft nodes before linearized reading' (duration: 192.341332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:30.246967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.855508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-28T16:10:30.247005Z","caller":"traceutil/trace.go:171","msg":"trace[268923386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25202; }","duration":"196.893219ms","start":"2026-05-28T16:10:30.050103Z","end":"2026-05-28T16:10:30.246997Z","steps":["trace[268923386] 'agreement among raft nodes before linearized reading' (duration: 196.796627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:30.247224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.27703ms","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-28T16:10:30.247296Z","caller":"traceutil/trace.go:171","msg":"trace[1367175763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25202; }","duration":"192.336711ms","start":"2026-05-28T16:10:30.054921Z","end":"2026-05-28T16:10:30.247258Z","steps":["trace[1367175763] 'agreement among raft nodes before linearized reading' (duration: 192.209869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:30.546946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.547504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T16:10:30.547131Z","caller":"traceutil/trace.go:171","msg":"trace[1658098192] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"295.715371ms","start":"2026-05-28T16:10:30.251401Z","end":"2026-05-28T16:10:30.547116Z","steps":["trace[1658098192] 'process raft request' (duration: 295.64211ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:30.547123Z","caller":"traceutil/trace.go:171","msg":"trace[982466788] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"295.731152ms","start":"2026-05-28T16:10:30.251358Z","end":"2026-05-28T16:10:30.54709Z","steps":["trace[982466788] 'process raft request' (duration: 161.964333ms)","trace[982466788] 'compare' (duration: 133.429452ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:30.553229Z","caller":"traceutil/trace.go:171","msg":"trace[1259930678] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"188.071238ms","start":"2026-05-28T16:10:30.365143Z","end":"2026-05-28T16:10:30.553214Z","steps":["trace[1259930678] 'process raft request' (duration: 187.798053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T16:10:31.114433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.434607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T16:10:31.114635Z","caller":"traceutil/trace.go:171","msg":"trace[1848586281] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25208; }","duration":"109.675042ms","start":"2026-05-28T16:10:31.004886Z","end":"2026-05-28T16:10:31.114561Z","steps":["trace[1848586281] 'count revisions from in-memory index tree' (duration: 109.347395ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:41.160417Z","caller":"traceutil/trace.go:171","msg":"trace[1000665930] linearizableReadLoop","detail":"{readStateIndex:26118; appliedIndex:26117; }","duration":"132.142497ms","start":"2026-05-28T16:10:41.028248Z","end":"2026-05-28T16:10:41.160391Z","steps":["trace[1000665930] 'read index received' (duration: 96.836864ms)","trace[1000665930] 'applied index is now lower than readState.Index' (duration: 35.304603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:10:41.160699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.429313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T16:10:41.160778Z","caller":"traceutil/trace.go:171","msg":"trace[176576383] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25291; }","duration":"132.558055ms","start":"2026-05-28T16:10:41.028202Z","end":"2026-05-28T16:10:41.16076Z","steps":["trace[176576383] 'agreement among raft nodes before linearized reading' (duration: 132.407023ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:41.160698Z","caller":"traceutil/trace.go:171","msg":"trace[2043734915] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"149.744628ms","start":"2026-05-28T16:10:41.010916Z","end":"2026-05-28T16:10:41.16066Z","steps":["trace[2043734915] 'process raft request' (duration: 114.156449ms)","trace[2043734915] 'compare' (duration: 34.68384ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:41.418983Z","caller":"traceutil/trace.go:171","msg":"trace[1067836162] linearizableReadLoop","detail":"{readStateIndex:26119; appliedIndex:26118; }","duration":"125.774003ms","start":"2026-05-28T16:10:41.29318Z","end":"2026-05-28T16:10:41.418954Z","steps":["trace[1067836162] 'read index received' (duration: 47.297245ms)","trace[1067836162] 'applied index is now lower than readState.Index' (duration: 78.475488ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:41.419105Z","caller":"traceutil/trace.go:171","msg":"trace[641453656] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"140.771343ms","start":"2026-05-28T16:10:41.278317Z","end":"2026-05-28T16:10:41.419088Z","steps":["trace[641453656] 'process raft request' (duration: 62.095191ms)","trace[641453656] 'compare' (duration: 78.138342ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:10:41.419309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.117019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T16:10:41.419352Z","caller":"traceutil/trace.go:171","msg":"trace[41887763] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25292; }","duration":"126.247593ms","start":"2026-05-28T16:10:41.293094Z","end":"2026-05-28T16:10:41.419341Z","steps":["trace[41887763] 'agreement among raft nodes before linearized reading' (duration: 126.139181ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:41.587083Z","caller":"traceutil/trace.go:171","msg":"trace[774400131] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"256.767717ms","start":"2026-05-28T16:10:41.330282Z","end":"2026-05-28T16:10:41.58705Z","steps":["trace[774400131] 'process raft request' (duration: 205.372414ms)","trace[774400131] 'compare' (duration: 51.048467ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:10:41.587481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.193071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-28T16:10:41.588172Z","caller":"traceutil/trace.go:171","msg":"trace[1652465994] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26119; }","duration":"167.916989ms","start":"2026-05-28T16:10:41.41905Z","end":"2026-05-28T16:10:41.586967Z","steps":["trace[1652465994] 'read index received' (duration: 116.535464ms)","trace[1652465994] 'applied index is now lower than readState.Index' (duration: 51.380675ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:41.587904Z","caller":"traceutil/trace.go:171","msg":"trace[1326646746] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25293; }","duration":"173.66888ms","start":"2026-05-28T16:10:41.414214Z","end":"2026-05-28T16:10:41.587883Z","steps":["trace[1326646746] 'agreement among raft nodes before linearized reading' (duration: 173.087979ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:41.867467Z","caller":"traceutil/trace.go:171","msg":"trace[873164559] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"230.494869ms","start":"2026-05-28T16:10:41.636957Z","end":"2026-05-28T16:10:41.867452Z","steps":["trace[873164559] 'process raft request' (duration: 144.393793ms)","trace[873164559] 'compare' (duration: 85.77595ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T16:10:41.867426Z","caller":"traceutil/trace.go:171","msg":"trace[1673929967] linearizableReadLoop","detail":"{readStateIndex:26121; appliedIndex:26120; }","duration":"188.270062ms","start":"2026-05-28T16:10:41.67913Z","end":"2026-05-28T16:10:41.8674Z","steps":["trace[1673929967] 'read index received' (duration: 102.111625ms)","trace[1673929967] 'applied index is now lower than readState.Index' (duration: 86.157387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T16:10:41.867723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.581968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T16:10:41.867758Z","caller":"traceutil/trace.go:171","msg":"trace[1407721542] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25294; }","duration":"188.659519ms","start":"2026-05-28T16:10:41.679089Z","end":"2026-05-28T16:10:41.867749Z","steps":["trace[1407721542] 'agreement among raft nodes before linearized reading' (duration: 188.585548ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:42.075985Z","caller":"traceutil/trace.go:171","msg":"trace[881555330] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"119.240447ms","start":"2026-05-28T16:10:41.956721Z","end":"2026-05-28T16:10:42.075961Z","steps":["trace[881555330] 'process raft request' (duration: 119.119604ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:42.105084Z","caller":"traceutil/trace.go:171","msg":"trace[1686141505] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"123.352896ms","start":"2026-05-28T16:10:41.981703Z","end":"2026-05-28T16:10:42.105056Z","steps":["trace[1686141505] 'process raft request' (duration: 123.178813ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:42.105318Z","caller":"traceutil/trace.go:171","msg":"trace[2141762256] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"121.91872ms","start":"2026-05-28T16:10:41.983377Z","end":"2026-05-28T16:10:42.105296Z","steps":["trace[2141762256] 'process raft request' (duration: 121.634804ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:10:42.105527Z","caller":"traceutil/trace.go:171","msg":"trace[1341908012] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"119.793128ms","start":"2026-05-28T16:10:41.985688Z","end":"2026-05-28T16:10:42.105481Z","steps":["trace[1341908012] 'process raft request' (duration: 119.540003ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:12:19.853076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23401} {"level":"info","ts":"2026-05-28T16:12:19.99151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23401,"took":"132.33512ms","hash":2606684199,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":36376576,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-28T16:12:19.991567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2606684199,"revision":23401,"compact-revision":20145} {"level":"info","ts":"2026-05-28T16:12:41.462124Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006aa6.wal"} {"level":"info","ts":"2026-05-28T16:12:41.463166Z","caller":"traceutil/trace.go:171","msg":"trace[1698533398] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"133.065404ms","start":"2026-05-28T16:12:41.330067Z","end":"2026-05-28T16:12:41.463133Z","steps":["trace[1698533398] 'process raft request' (duration: 132.624396ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T16:17:19.859495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26246} {"level":"info","ts":"2026-05-28T16:17:20.001058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26246,"took":"137.345987ms","hash":157435122,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":34201600,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T16:17:20.001165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":157435122,"revision":26246,"compact-revision":23401} {"level":"info","ts":"2026-05-28T16:17:46.548617Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T16:17:46.553783Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-28T16:17:46.55393Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-28T16:22:19.865007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28883} {"level":"info","ts":"2026-05-28T16:22:19.997399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28883,"took":"128.389938ms","hash":842194213,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T16:22:19.997464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":842194213,"revision":28883,"compact-revision":26246} {"level":"info","ts":"2026-05-28T16:27:19.870968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31102} {"level":"info","ts":"2026-05-28T16:27:19.983215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31102,"took":"108.016562ms","hash":3793492313,"current-db-size-bytes":58949632,"current-db-size":"59 MB","current-db-size-in-use-bytes":31313920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T16:27:19.983286Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3793492313,"revision":31102,"compact-revision":28883} {"level":"info","ts":"2026-05-28T16:32:19.879874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33370} {"level":"info","ts":"2026-05-28T16:32:20.034344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33370,"took":"149.848213ms","hash":2599646913,"current-db-size-bytes":59994112,"current-db-size":"60 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T16:32:20.034425Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2599646913,"revision":33370,"compact-revision":31102} {"level":"info","ts":"2026-05-28T16:37:19.886111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35661} {"level":"info","ts":"2026-05-28T16:37:20.025147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35661,"took":"134.98081ms","hash":6549220,"current-db-size-bytes":60678144,"current-db-size":"61 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T16:37:20.0253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":6549220,"revision":35661,"compact-revision":33370} {"level":"info","ts":"2026-05-28T16:39:15.215664Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T16:39:15.22018Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-28T16:39:15.220297Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}