{"level":"warn","ts":"2026-08-09T12:59:51.150452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-09T12:59:51.150553Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-08-09T12:59:51.150616Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-09T12:59:51.150624Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-09T12:59:51.150642Z","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-08-09T12:59:51.151228Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-08-09T12:59:51.151308Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-08-09T12:59:51.154406Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.894555ms"} {"level":"info","ts":"2026-08-09T12:59:51.162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-08-09T12:59:51.162109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-08-09T12:59:51.162135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-08-09T12:59:51.162145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-09T12:59:51.162161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-08-09T12:59:51.162199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-08-09T12:59:51.172547Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-09T12:59:51.17518Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-09T12:59:51.177897Z","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-08-09T12:59:51.184657Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-09T12:59:51.18482Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-09T12:59:51.184959Z","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-08-09T12:59:51.185016Z","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-08-09T12:59:51.185028Z","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-08-09T12:59:51.185958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-08-09T12:59:51.186183Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-08-09T12:59:51.187108Z","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-08-09T12:59:51.187261Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-09T12:59:51.187335Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-08-09T12:59:51.187375Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-09T12:59:51.187405Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-09T12:59:51.563453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-08-09T12:59:51.563575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-09T12:59:51.563607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-08-09T12:59:51.563631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-08-09T12:59:51.563648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-09T12:59:51.563666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-08-09T12:59:51.563693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-08-09T12:59:51.564433Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:59:51.565286Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-08-09T12:59:51.565293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-09T12:59:51.565367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-09T12:59:51.56579Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:59:51.580096Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:59:51.580231Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:59:51.580326Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-09T12:59:51.580368Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-09T12:59:51.583932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-09T12:59:51.584819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-08-09T13:00:09.253951Z","caller":"traceutil/trace.go:171","msg":"trace[1295485355] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"198.141203ms","start":"2026-08-09T13:00:09.055781Z","end":"2026-08-09T13:00:09.253922Z","steps":["trace[1295485355] 'process raft request' (duration: 197.968077ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:14.673679Z","caller":"traceutil/trace.go:171","msg":"trace[1586843585] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"173.402605ms","start":"2026-08-09T13:00:14.499206Z","end":"2026-08-09T13:00:14.672609Z","steps":["trace[1586843585] 'process raft request' (duration: 173.210019ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:14.85635Z","caller":"traceutil/trace.go:171","msg":"trace[2147207218] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"168.425412ms","start":"2026-08-09T13:00:14.687895Z","end":"2026-08-09T13:00:14.856321Z","steps":["trace[2147207218] 'read index received' (duration: 157.32844ms)","trace[2147207218] 'applied index is now lower than readState.Index' (duration: 11.095432ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:00:14.856513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.582817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:00:14.856574Z","caller":"traceutil/trace.go:171","msg":"trace[1301905814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"168.693611ms","start":"2026-08-09T13:00:14.687869Z","end":"2026-08-09T13:00:14.856562Z","steps":["trace[1301905814] 'agreement among raft nodes before linearized reading' (duration: 168.574257ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.003365Z","caller":"traceutil/trace.go:171","msg":"trace[449389739] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"201.283085ms","start":"2026-08-09T13:00:32.802046Z","end":"2026-08-09T13:00:33.003329Z","steps":["trace[449389739] 'process raft request' (duration: 201.062718ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.008504Z","caller":"traceutil/trace.go:171","msg":"trace[1868990936] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"123.817235ms","start":"2026-08-09T13:00:32.884671Z","end":"2026-08-09T13:00:33.008489Z","steps":["trace[1868990936] 'process raft request' (duration: 123.599538ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.008597Z","caller":"traceutil/trace.go:171","msg":"trace[1353185198] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"118.091608ms","start":"2026-08-09T13:00:32.890497Z","end":"2026-08-09T13:00:33.008589Z","steps":["trace[1353185198] 'process raft request' (duration: 117.935123ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.008603Z","caller":"traceutil/trace.go:171","msg":"trace[864396037] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"118.43595ms","start":"2026-08-09T13:00:32.890121Z","end":"2026-08-09T13:00:33.008557Z","steps":["trace[864396037] 'process raft request' (duration: 118.276414ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.008683Z","caller":"traceutil/trace.go:171","msg":"trace[346115498] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"107.301386ms","start":"2026-08-09T13:00:32.901359Z","end":"2026-08-09T13:00:33.00866Z","steps":["trace[346115498] 'process raft request' (duration: 107.227524ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.008731Z","caller":"traceutil/trace.go:171","msg":"trace[1449866376] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"107.265775ms","start":"2026-08-09T13:00:32.901455Z","end":"2026-08-09T13:00:33.008721Z","steps":["trace[1449866376] 'process raft request' (duration: 107.165522ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.008719Z","caller":"traceutil/trace.go:171","msg":"trace[2073259731] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"107.373918ms","start":"2026-08-09T13:00:32.901293Z","end":"2026-08-09T13:00:33.008667Z","steps":["trace[2073259731] 'process raft request' (duration: 107.246984ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:33.008855Z","caller":"traceutil/trace.go:171","msg":"trace[320804496] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"107.291526ms","start":"2026-08-09T13:00:32.901511Z","end":"2026-08-09T13:00:33.008803Z","steps":["trace[320804496] 'process raft request' (duration: 107.147911ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:38.938301Z","caller":"traceutil/trace.go:171","msg":"trace[252577544] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"220.894817ms","start":"2026-08-09T13:00:38.717375Z","end":"2026-08-09T13:00:38.93827Z","steps":["trace[252577544] 'process raft request' (duration: 220.617828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:00:39.175215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.620452ms","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-08-09T13:00:39.175305Z","caller":"traceutil/trace.go:171","msg":"trace[756917801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"143.773367ms","start":"2026-08-09T13:00:39.031512Z","end":"2026-08-09T13:00:39.175285Z","steps":["trace[756917801] 'range keys from in-memory index tree' (duration: 143.424936ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:39.491298Z","caller":"traceutil/trace.go:171","msg":"trace[1397985475] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"431.176577ms","start":"2026-08-09T13:00:39.060079Z","end":"2026-08-09T13:00:39.491256Z","steps":["trace[1397985475] 'process raft request' (duration: 429.523103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:00:39.492033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:00:39.060027Z","time spent":"431.381243ms","remote":"127.0.0.1:33018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:00:39.508883Z","caller":"traceutil/trace.go:171","msg":"trace[994227433] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"118.887974ms","start":"2026-08-09T13:00:39.389967Z","end":"2026-08-09T13:00:39.508855Z","steps":["trace[994227433] 'read index received' (duration: 99.338385ms)","trace[994227433] 'applied index is now lower than readState.Index' (duration: 19.548219ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:00:39.509205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.227285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-09T13:00:39.509244Z","caller":"traceutil/trace.go:171","msg":"trace[2125630949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"119.336259ms","start":"2026-08-09T13:00:39.389896Z","end":"2026-08-09T13:00:39.509233Z","steps":["trace[2125630949] 'agreement among raft nodes before linearized reading' (duration: 119.05617ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:00:39.509221Z","caller":"traceutil/trace.go:171","msg":"trace[2089134064] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"328.676448ms","start":"2026-08-09T13:00:39.180516Z","end":"2026-08-09T13:00:39.509193Z","steps":["trace[2089134064] 'process raft request' (duration: 328.223743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:00:39.509352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:00:39.18049Z","time spent":"328.794432ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:00:40.327233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.742266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-09T13:00:40.327344Z","caller":"traceutil/trace.go:171","msg":"trace[1652022566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"664.931902ms","start":"2026-08-09T13:00:39.662387Z","end":"2026-08-09T13:00:40.327319Z","steps":["trace[1652022566] 'range keys from in-memory index tree' (duration: 664.470787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:00:40.327211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.921754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:00:40.327396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:00:39.662354Z","time spent":"665.031875ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-09T13:00:40.327475Z","caller":"traceutil/trace.go:171","msg":"trace[276272469] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:607; }","duration":"748.280006ms","start":"2026-08-09T13:00:39.579145Z","end":"2026-08-09T13:00:40.327425Z","steps":["trace[276272469] 'range keys from in-memory index tree' (duration: 747.731058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:00:40.327595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:00:39.579123Z","time spent":"748.43598ms","remote":"127.0.0.1:33178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-09T13:00:40.333135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.347575ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-09T13:00:40.3333Z","caller":"traceutil/trace.go:171","msg":"trace[1588008953] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"605.728729ms","start":"2026-08-09T13:00:39.727543Z","end":"2026-08-09T13:00:40.333272Z","steps":["trace[1588008953] 'read index received' (duration: 288.982981ms)","trace[1588008953] 'applied index is now lower than readState.Index' (duration: 316.743238ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:00:40.333527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.976816ms","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-08-09T13:00:40.333585Z","caller":"traceutil/trace.go:171","msg":"trace[666991616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"606.09595ms","start":"2026-08-09T13:00:39.727473Z","end":"2026-08-09T13:00:40.333569Z","steps":["trace[666991616] 'agreement among raft nodes before linearized reading' (duration: 605.876923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:00:40.333638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:00:39.727446Z","time spent":"606.178673ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-09T13:00:41.994652Z","caller":"traceutil/trace.go:171","msg":"trace[141241244] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"104.827775ms","start":"2026-08-09T13:00:41.889797Z","end":"2026-08-09T13:00:41.994625Z","steps":["trace[141241244] 'process raft request' (duration: 104.68447ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:01:27.291093Z","caller":"traceutil/trace.go:171","msg":"trace[561649087] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"271.897405ms","start":"2026-08-09T13:01:27.019169Z","end":"2026-08-09T13:01:27.291066Z","steps":["trace[561649087] 'process raft request' (duration: 271.7532ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:12.429709Z","caller":"traceutil/trace.go:171","msg":"trace[504298496] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"335.445536ms","start":"2026-08-09T13:02:12.09424Z","end":"2026-08-09T13:02:12.429686Z","steps":["trace[504298496] 'process raft request' (duration: 335.242549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:12.429855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.094221Z","time spent":"335.556939ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:12.595017Z","caller":"traceutil/trace.go:171","msg":"trace[571827085] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"498.679979ms","start":"2026-08-09T13:02:12.096312Z","end":"2026-08-09T13:02:12.594992Z","steps":["trace[571827085] 'process raft request' (duration: 480.769331ms)","trace[571827085] 'compare' (duration: 17.748843ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:12.59515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.09629Z","time spent":"498.790612ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:12.595307Z","caller":"traceutil/trace.go:171","msg":"trace[1218517492] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"498.689129ms","start":"2026-08-09T13:02:12.096587Z","end":"2026-08-09T13:02:12.595276Z","steps":["trace[1218517492] 'process raft request' (duration: 498.368138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:12.595463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.09658Z","time spent":"498.806854ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:12.595544Z","caller":"traceutil/trace.go:171","msg":"trace[993764970] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"453.734717ms","start":"2026-08-09T13:02:12.141784Z","end":"2026-08-09T13:02:12.595519Z","steps":["trace[993764970] 'process raft request' (duration: 453.397606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:12.595655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.14175Z","time spent":"453.851952ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:12.595513Z","caller":"traceutil/trace.go:171","msg":"trace[483883958] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1911; }","duration":"392.965458ms","start":"2026-08-09T13:02:12.202515Z","end":"2026-08-09T13:02:12.59548Z","steps":["trace[483883958] 'read index received' (duration: 227.063116ms)","trace[483883958] 'applied index is now lower than readState.Index' (duration: 165.901312ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:12.595758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.230357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-09T13:02:12.595829Z","caller":"traceutil/trace.go:171","msg":"trace[1307019285] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a; range_end:; response_count:1; response_revision:1843; }","duration":"393.369071ms","start":"2026-08-09T13:02:12.202448Z","end":"2026-08-09T13:02:12.595817Z","steps":["trace[1307019285] 'agreement among raft nodes before linearized reading' (duration: 393.132913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:12.595885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.202427Z","time spent":"393.442724ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a\" "} {"level":"info","ts":"2026-08-09T13:02:12.641521Z","caller":"traceutil/trace.go:171","msg":"trace[1792017275] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"170.249977ms","start":"2026-08-09T13:02:12.471253Z","end":"2026-08-09T13:02:12.641503Z","steps":["trace[1792017275] 'process raft request' (duration: 170.157794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:12.641623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.644816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:02:12.6417Z","caller":"traceutil/trace.go:171","msg":"trace[674340109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1844; }","duration":"383.812762ms","start":"2026-08-09T13:02:12.257868Z","end":"2026-08-09T13:02:12.641681Z","steps":["trace[674340109] 'agreement among raft nodes before linearized reading' (duration: 383.633496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:12.64173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.603418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-09T13:02:12.641826Z","caller":"traceutil/trace.go:171","msg":"trace[981113079] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a; range_end:; response_count:1; response_revision:1844; }","duration":"243.737232ms","start":"2026-08-09T13:02:12.398069Z","end":"2026-08-09T13:02:12.641806Z","steps":["trace[981113079] 'agreement among raft nodes before linearized reading' (duration: 243.446533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:12.641754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.257834Z","time spent":"383.908855ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:02:12.863722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.999974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:02:12.864018Z","caller":"traceutil/trace.go:171","msg":"trace[876514263] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"261.266708ms","start":"2026-08-09T13:02:12.602719Z","end":"2026-08-09T13:02:12.863985Z","steps":["trace[876514263] 'process raft request' (duration: 103.906182ms)","trace[876514263] 'compare' (duration: 156.832539ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:12.864145Z","caller":"traceutil/trace.go:171","msg":"trace[1987933516] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1916; }","duration":"149.053619ms","start":"2026-08-09T13:02:12.715009Z","end":"2026-08-09T13:02:12.864063Z","steps":["trace[1987933516] 'read index received' (duration: 92.543µs)","trace[1987933516] 'applied index is now lower than readState.Index' (duration: 148.959106ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:12.864595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.563667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T13:02:12.864681Z","caller":"traceutil/trace.go:171","msg":"trace[1374525212] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"149.697681ms","start":"2026-08-09T13:02:12.714962Z","end":"2026-08-09T13:02:12.86466Z","steps":["trace[1374525212] 'agreement among raft nodes before linearized reading' (duration: 149.337199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.146986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204185289754797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-09T13:02:14.607408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274958621s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:02:14.607525Z","caller":"traceutil/trace.go:171","msg":"trace[105617008] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"1.732108982s","start":"2026-08-09T13:02:12.875396Z","end":"2026-08-09T13:02:14.607505Z","steps":["trace[105617008] 'process raft request' (duration: 456.908642ms)","trace[105617008] 'compare' (duration: 1.274844148s)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:14.607588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.875363Z","time spent":"1.732195414s","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:14.644057Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003316477s","expected-duration":"1s"} {"level":"info","ts":"2026-08-09T13:02:14.644691Z","caller":"traceutil/trace.go:171","msg":"trace[133202685] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"1.766057956s","start":"2026-08-09T13:02:12.878613Z","end":"2026-08-09T13:02:14.644671Z","steps":["trace[133202685] 'process raft request' (duration: 1.76586529s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.644852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.878594Z","time spent":"1.76615603s","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:14.645404Z","caller":"traceutil/trace.go:171","msg":"trace[1301293904] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"1.764620809s","start":"2026-08-09T13:02:12.880748Z","end":"2026-08-09T13:02:14.645369Z","steps":["trace[1301293904] 'process raft request' (duration: 1.764538856s)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:14.645508Z","caller":"traceutil/trace.go:171","msg":"trace[223832468] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"1.765734426s","start":"2026-08-09T13:02:12.879733Z","end":"2026-08-09T13:02:14.645467Z","steps":["trace[223832468] 'process raft request' (duration: 1.764834246s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.645578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.880738Z","time spent":"1.764761993s","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:14.645671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:12.879704Z","time spent":"1.765875531s","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:14.66369Z","caller":"traceutil/trace.go:171","msg":"trace[1349441560] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"1.085446191s","start":"2026-08-09T13:02:13.578215Z","end":"2026-08-09T13:02:14.663661Z","steps":["trace[1349441560] 'process raft request' (duration: 1.085262195s)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:14.663848Z","caller":"traceutil/trace.go:171","msg":"trace[750383874] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1918; }","duration":"1.016920441s","start":"2026-08-09T13:02:13.646905Z","end":"2026-08-09T13:02:14.663825Z","steps":["trace[750383874] 'read index received' (duration: 997.551014ms)","trace[750383874] 'applied index is now lower than readState.Index' (duration: 19.367887ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:14.663976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:13.578173Z","time spent":"1.085658918s","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:14.664038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017133049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-08-09T13:02:14.664107Z","caller":"traceutil/trace.go:171","msg":"trace[770215002] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a; range_end:; response_count:1; response_revision:1851; }","duration":"1.017354516s","start":"2026-08-09T13:02:13.646736Z","end":"2026-08-09T13:02:14.664091Z","steps":["trace[770215002] 'agreement among raft nodes before linearized reading' (duration: 1.01717318s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.664151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:13.646722Z","time spent":"1.017418428s","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a\" "} {"level":"info","ts":"2026-08-09T13:02:14.888595Z","caller":"traceutil/trace.go:171","msg":"trace[520542147] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"241.003771ms","start":"2026-08-09T13:02:14.647537Z","end":"2026-08-09T13:02:14.888541Z","steps":["trace[520542147] 'process raft request' (duration: 240.721472ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:14.888625Z","caller":"traceutil/trace.go:171","msg":"trace[950911752] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"237.6787ms","start":"2026-08-09T13:02:14.650914Z","end":"2026-08-09T13:02:14.888593Z","steps":["trace[950911752] 'process raft request' (duration: 237.537395ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:14.888673Z","caller":"traceutil/trace.go:171","msg":"trace[822736783] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"237.541865ms","start":"2026-08-09T13:02:14.651097Z","end":"2026-08-09T13:02:14.888638Z","steps":["trace[822736783] 'process raft request' (duration: 237.419441ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:14.888959Z","caller":"traceutil/trace.go:171","msg":"trace[1578796560] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"237.252496ms","start":"2026-08-09T13:02:14.651644Z","end":"2026-08-09T13:02:14.888897Z","steps":["trace[1578796560] 'process raft request' (duration: 236.913345ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:14.888963Z","caller":"traceutil/trace.go:171","msg":"trace[860403302] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1923; }","duration":"225.007506ms","start":"2026-08-09T13:02:14.663895Z","end":"2026-08-09T13:02:14.888903Z","steps":["trace[860403302] 'read index received' (duration: 224.456508ms)","trace[860403302] 'applied index is now lower than readState.Index' (duration: 550.178µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:14.889068Z","caller":"traceutil/trace.go:171","msg":"trace[1657658285] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"237.020898ms","start":"2026-08-09T13:02:14.652034Z","end":"2026-08-09T13:02:14.889055Z","steps":["trace[1657658285] 'process raft request' (duration: 236.719018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.889217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.376674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-09T13:02:14.889244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199257772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:02:14.889289Z","caller":"traceutil/trace.go:171","msg":"trace[1149891649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1856; }","duration":"288.480146ms","start":"2026-08-09T13:02:14.60079Z","end":"2026-08-09T13:02:14.88927Z","steps":["trace[1149891649] 'agreement among raft nodes before linearized reading' (duration: 288.152765ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:14.8893Z","caller":"traceutil/trace.go:171","msg":"trace[1668646431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"1.199462199s","start":"2026-08-09T13:02:13.689826Z","end":"2026-08-09T13:02:14.889288Z","steps":["trace[1668646431] 'agreement among raft nodes before linearized reading' (duration: 1.19917745s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.889383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.962023ms","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-08-09T13:02:14.889437Z","caller":"traceutil/trace.go:171","msg":"trace[353972431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1856; }","duration":"289.050026ms","start":"2026-08-09T13:02:14.600375Z","end":"2026-08-09T13:02:14.889425Z","steps":["trace[353972431] 'agreement among raft nodes before linearized reading' (duration: 288.914791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.889389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01686223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-08-09T13:02:14.889558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152713918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:02:14.889557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146091197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:02:14.889561Z","caller":"traceutil/trace.go:171","msg":"trace[1566303679] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"1.017102387s","start":"2026-08-09T13:02:13.872436Z","end":"2026-08-09T13:02:14.889538Z","steps":["trace[1566303679] 'agreement among raft nodes before linearized reading' (duration: 1.016827328s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.889668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.062128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18ca24ad9fb3a1db\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-08-09T13:02:14.889555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.215898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-09T13:02:14.889735Z","caller":"traceutil/trace.go:171","msg":"trace[1489211871] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18ca24ad9fb3a1db; range_end:; response_count:1; response_revision:1856; }","duration":"266.164021ms","start":"2026-08-09T13:02:14.623549Z","end":"2026-08-09T13:02:14.889713Z","steps":["trace[1489211871] 'agreement among raft nodes before linearized reading' (duration: 266.003636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.889678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:13.872403Z","time spent":"1.017251782s","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-09T13:02:14.889617Z","caller":"traceutil/trace.go:171","msg":"trace[1273530769] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1856; }","duration":"1.152775s","start":"2026-08-09T13:02:13.736811Z","end":"2026-08-09T13:02:14.889586Z","steps":["trace[1273530769] 'agreement among raft nodes before linearized reading' (duration: 1.152712498s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.889951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:13.736799Z","time spent":"1.15308194s","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-09T13:02:14.889754Z","caller":"traceutil/trace.go:171","msg":"trace[860423203] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:1856; }","duration":"241.457906ms","start":"2026-08-09T13:02:14.648285Z","end":"2026-08-09T13:02:14.889743Z","steps":["trace[860423203] 'agreement among raft nodes before linearized reading' (duration: 241.191317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.889492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.676549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-09T13:02:14.890194Z","caller":"traceutil/trace.go:171","msg":"trace[619732588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1856; }","duration":"455.411973ms","start":"2026-08-09T13:02:14.434757Z","end":"2026-08-09T13:02:14.890169Z","steps":["trace[619732588] 'agreement among raft nodes before linearized reading' (duration: 454.657368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.890292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.434732Z","time spent":"455.530408ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-09T13:02:14.889608Z","caller":"traceutil/trace.go:171","msg":"trace[935482419] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1856; }","duration":"1.146174339s","start":"2026-08-09T13:02:13.743422Z","end":"2026-08-09T13:02:14.889596Z","steps":["trace[935482419] 'agreement among raft nodes before linearized reading' (duration: 1.146098967s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:14.890461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:13.743408Z","time spent":"1.147030687s","remote":"127.0.0.1:46196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:02:14.889462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:13.689786Z","time spent":"1.199598634s","remote":"127.0.0.1:32908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-09T13:02:15.398017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204185289754811,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T13:02:15.757294Z","caller":"traceutil/trace.go:171","msg":"trace[619723504] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"997.602046ms","start":"2026-08-09T13:02:14.759662Z","end":"2026-08-09T13:02:15.757264Z","steps":["trace[619723504] 'process raft request' (duration: 930.817395ms)","trace[619723504] 'compare' (duration: 66.668607ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:15.757447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.759623Z","time spent":"997.743271ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:15.879993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.890939Z","time spent":"989.03827ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-09T13:02:15.880535Z","caller":"traceutil/trace.go:171","msg":"trace[839224041] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"986.307099ms","start":"2026-08-09T13:02:14.894193Z","end":"2026-08-09T13:02:15.8805Z","steps":["trace[839224041] 'process raft request' (duration: 985.899495ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:15.880618Z","caller":"traceutil/trace.go:171","msg":"trace[1077264674] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"985.953377ms","start":"2026-08-09T13:02:14.894635Z","end":"2026-08-09T13:02:15.880588Z","steps":["trace[1077264674] 'process raft request' (duration: 985.73993ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:15.880272Z","caller":"traceutil/trace.go:171","msg":"trace[591942465] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"987.585522ms","start":"2026-08-09T13:02:14.892647Z","end":"2026-08-09T13:02:15.880232Z","steps":["trace[591942465] 'process raft request' (duration: 987.23153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:15.88081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.894625Z","time spent":"986.085791ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:15.880849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.892607Z","time spent":"988.119939ms","remote":"127.0.0.1:32994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:15.880666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.894171Z","time spent":"986.412253ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:15.881259Z","caller":"traceutil/trace.go:171","msg":"trace[14939218] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"986.518295ms","start":"2026-08-09T13:02:14.894722Z","end":"2026-08-09T13:02:15.88124Z","steps":["trace[14939218] 'process raft request' (duration: 985.803312ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:15.881527Z","caller":"traceutil/trace.go:171","msg":"trace[12988556] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"828.970493ms","start":"2026-08-09T13:02:15.052529Z","end":"2026-08-09T13:02:15.881499Z","steps":["trace[12988556] 'process raft request' (duration: 828.600331ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:15.881623Z","caller":"traceutil/trace.go:171","msg":"trace[532568706] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"103.25ms","start":"2026-08-09T13:02:15.778351Z","end":"2026-08-09T13:02:15.881601Z","steps":["trace[532568706] 'process raft request' (duration: 103.150486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:15.881698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:15.052505Z","time spent":"829.109437ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:15.881778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.198948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:02:15.881635Z","caller":"traceutil/trace.go:171","msg":"trace[1788391157] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1928; }","duration":"984.033303ms","start":"2026-08-09T13:02:14.897577Z","end":"2026-08-09T13:02:15.88161Z","steps":["trace[1788391157] 'read index received' (duration: 792.996691ms)","trace[1788391157] 'applied index is now lower than readState.Index' (duration: 191.034052ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:15.881841Z","caller":"traceutil/trace.go:171","msg":"trace[2026470656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"984.296131ms","start":"2026-08-09T13:02:14.897531Z","end":"2026-08-09T13:02:15.881827Z","steps":["trace[2026470656] 'agreement among raft nodes before linearized reading' (duration: 984.142346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:15.881889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.897517Z","time spent":"984.361154ms","remote":"127.0.0.1:32898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-09T13:02:15.882568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:14.894697Z","time spent":"986.624969ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:02:15.882697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.902901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T13:02:15.882806Z","caller":"traceutil/trace.go:171","msg":"trace[777139863] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1864; }","duration":"624.076488ms","start":"2026-08-09T13:02:15.258704Z","end":"2026-08-09T13:02:15.88278Z","steps":["trace[777139863] 'agreement among raft nodes before linearized reading' (duration: 623.861581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:15.88286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:15.258685Z","time spent":"624.16457ms","remote":"127.0.0.1:60496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:02:15.883271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.751583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:02:15.883339Z","caller":"traceutil/trace.go:171","msg":"trace[426828435] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1864; }","duration":"531.867748ms","start":"2026-08-09T13:02:15.351449Z","end":"2026-08-09T13:02:15.883317Z","steps":["trace[426828435] 'agreement among raft nodes before linearized reading' (duration: 531.753824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:15.883391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:15.351425Z","time spent":"531.949361ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:02:15.883524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.179505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-08-09T13:02:15.883599Z","caller":"traceutil/trace.go:171","msg":"trace[2406400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-223b64a1-1ba0-4f32-9e56-701dbb3e176a; range_end:; response_count:1; response_revision:1864; }","duration":"214.3104ms","start":"2026-08-09T13:02:15.669268Z","end":"2026-08-09T13:02:15.883579Z","steps":["trace[2406400] 'agreement among raft nodes before linearized reading' (duration: 214.03604ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:27.993902Z","caller":"traceutil/trace.go:171","msg":"trace[1661568192] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"266.446771ms","start":"2026-08-09T13:02:27.727418Z","end":"2026-08-09T13:02:27.993865Z","steps":["trace[1661568192] 'process raft request' (duration: 266.258695ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:28.181761Z","caller":"traceutil/trace.go:171","msg":"trace[1561214380] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"183.094137ms","start":"2026-08-09T13:02:27.998617Z","end":"2026-08-09T13:02:28.181711Z","steps":["trace[1561214380] 'process raft request' (duration: 134.238285ms)","trace[1561214380] 'compare' (duration: 48.582463ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:28.217238Z","caller":"traceutil/trace.go:171","msg":"trace[469482373] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"218.371345ms","start":"2026-08-09T13:02:27.998822Z","end":"2026-08-09T13:02:28.217193Z","steps":["trace[469482373] 'process raft request' (duration: 218.119737ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:28.21727Z","caller":"traceutil/trace.go:171","msg":"trace[2070509186] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"216.941897ms","start":"2026-08-09T13:02:28.000293Z","end":"2026-08-09T13:02:28.217235Z","steps":["trace[2070509186] 'process raft request' (duration: 216.787882ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:28.217279Z","caller":"traceutil/trace.go:171","msg":"trace[1416336849] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2036; }","duration":"201.425529ms","start":"2026-08-09T13:02:28.015823Z","end":"2026-08-09T13:02:28.217249Z","steps":["trace[1416336849] 'read index received' (duration: 117.011109ms)","trace[1416336849] 'applied index is now lower than readState.Index' (duration: 84.41318ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:28.217295Z","caller":"traceutil/trace.go:171","msg":"trace[411962301] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"216.13055ms","start":"2026-08-09T13:02:28.001137Z","end":"2026-08-09T13:02:28.217268Z","steps":["trace[411962301] 'process raft request' (duration: 215.992425ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:28.217964Z","caller":"traceutil/trace.go:171","msg":"trace[605791492] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"214.712883ms","start":"2026-08-09T13:02:28.002707Z","end":"2026-08-09T13:02:28.21742Z","steps":["trace[605791492] 'process raft request' (duration: 214.475935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:28.218727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.875937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-z9n76\" ","response":"range_response_count:1 size:6147"} {"level":"info","ts":"2026-08-09T13:02:28.218843Z","caller":"traceutil/trace.go:171","msg":"trace[2134891181] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-z9n76; range_end:; response_count:1; response_revision:1966; }","duration":"203.046673ms","start":"2026-08-09T13:02:28.015743Z","end":"2026-08-09T13:02:28.21879Z","steps":["trace[2134891181] 'agreement among raft nodes before linearized reading' (duration: 201.658286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:28.53949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.620238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:02:28.539738Z","caller":"traceutil/trace.go:171","msg":"trace[461059207] linearizableReadLoop","detail":"{readStateIndex:2043; appliedIndex:2041; }","duration":"161.185135ms","start":"2026-08-09T13:02:28.378534Z","end":"2026-08-09T13:02:28.539719Z","steps":["trace[461059207] 'read index received' (duration: 118.754µs)","trace[461059207] 'applied index is now lower than readState.Index' (duration: 161.065221ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:28.539792Z","caller":"traceutil/trace.go:171","msg":"trace[145000008] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"310.330647ms","start":"2026-08-09T13:02:28.229401Z","end":"2026-08-09T13:02:28.539731Z","steps":["trace[145000008] 'process raft request' (duration: 72.343607ms)","trace[145000008] 'compare' (duration: 237.473433ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:28.539824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.296699ms","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:5"} {"level":"info","ts":"2026-08-09T13:02:28.539865Z","caller":"traceutil/trace.go:171","msg":"trace[66182862] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1968; }","duration":"161.390971ms","start":"2026-08-09T13:02:28.378459Z","end":"2026-08-09T13:02:28.53985Z","steps":["trace[66182862] 'agreement among raft nodes before linearized reading' (duration: 161.321199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:28.539918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:28.229378Z","time spent":"310.486543ms","remote":"127.0.0.1:33402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5868,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:28.539886Z","caller":"traceutil/trace.go:171","msg":"trace[1449770128] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"310.344357ms","start":"2026-08-09T13:02:28.229416Z","end":"2026-08-09T13:02:28.53976Z","steps":["trace[1449770128] 'process raft request' (duration: 310.191272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:28.54077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:28.229399Z","time spent":"311.266978ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5918,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:28.804456Z","caller":"traceutil/trace.go:171","msg":"trace[2130515569] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"192.302284ms","start":"2026-08-09T13:02:28.612116Z","end":"2026-08-09T13:02:28.804418Z","steps":["trace[2130515569] 'process raft request' (duration: 184.951179ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:28.804656Z","caller":"traceutil/trace.go:171","msg":"trace[1285517835] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"112.738066ms","start":"2026-08-09T13:02:28.691891Z","end":"2026-08-09T13:02:28.804629Z","steps":["trace[1285517835] 'process raft request' (duration: 112.460787ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:28.804771Z","caller":"traceutil/trace.go:171","msg":"trace[1367671130] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2047; }","duration":"113.663297ms","start":"2026-08-09T13:02:28.69108Z","end":"2026-08-09T13:02:28.804743Z","steps":["trace[1367671130] 'read index received' (duration: 105.96199ms)","trace[1367671130] 'applied index is now lower than readState.Index' (duration: 7.698627ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:28.804891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.789011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:02:28.804948Z","caller":"traceutil/trace.go:171","msg":"trace[660214316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1976; }","duration":"113.905666ms","start":"2026-08-09T13:02:28.691028Z","end":"2026-08-09T13:02:28.804934Z","steps":["trace[660214316] 'agreement among raft nodes before linearized reading' (duration: 113.796812ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:30.890793Z","caller":"traceutil/trace.go:171","msg":"trace[1398428587] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"127.599393ms","start":"2026-08-09T13:02:30.763123Z","end":"2026-08-09T13:02:30.890723Z","steps":["trace[1398428587] 'process raft request' (duration: 127.329144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:31.866241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204185289755095,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-09T13:02:32.366655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204185289755095,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-09T13:02:32.388977Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.031803099s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-09T13:02:32.505187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.899211ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-09T13:02:32.505307Z","caller":"traceutil/trace.go:171","msg":"trace[181124822] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"1.139819888s","start":"2026-08-09T13:02:31.36546Z","end":"2026-08-09T13:02:32.50528Z","steps":["trace[181124822] 'read index received' (duration: 1.023815582s)","trace[181124822] 'applied index is now lower than readState.Index' (duration: 116.002096ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:32.505488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139987183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:02:32.505562Z","caller":"traceutil/trace.go:171","msg":"trace[1996587024] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1995; }","duration":"1.140116067s","start":"2026-08-09T13:02:31.365428Z","end":"2026-08-09T13:02:32.505544Z","steps":["trace[1996587024] 'agreement among raft nodes before linearized reading' (duration: 1.139956872s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:32.505569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.093145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-09T13:02:32.505602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:31.365411Z","time spent":"1.140183439s","remote":"127.0.0.1:46126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:02:32.505591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.726662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T13:02:32.505645Z","caller":"traceutil/trace.go:171","msg":"trace[727306392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1995; }","duration":"268.175978ms","start":"2026-08-09T13:02:32.237451Z","end":"2026-08-09T13:02:32.505627Z","steps":["trace[727306392] 'agreement among raft nodes before linearized reading' (duration: 267.966511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:32.505699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.299859ms","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-08-09T13:02:32.505705Z","caller":"traceutil/trace.go:171","msg":"trace[1577116086] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1995; }","duration":"481.872068ms","start":"2026-08-09T13:02:32.023801Z","end":"2026-08-09T13:02:32.505673Z","steps":["trace[1577116086] 'agreement among raft nodes before linearized reading' (duration: 481.618469ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:32.505765Z","caller":"traceutil/trace.go:171","msg":"trace[453146093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1995; }","duration":"311.395012ms","start":"2026-08-09T13:02:32.194351Z","end":"2026-08-09T13:02:32.505746Z","steps":["trace[453146093] 'agreement among raft nodes before linearized reading' (duration: 311.270038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:32.505795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:32.023777Z","time spent":"481.986922ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-09T13:02:32.505817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:32.194301Z","time spent":"311.502636ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-09T13:02:32.50585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.750157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-09T13:02:32.505932Z","caller":"traceutil/trace.go:171","msg":"trace[1686304725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1995; }","duration":"268.885533ms","start":"2026-08-09T13:02:32.237026Z","end":"2026-08-09T13:02:32.505911Z","steps":["trace[1686304725] 'agreement among raft nodes before linearized reading' (duration: 268.604343ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:04.894838Z","caller":"traceutil/trace.go:171","msg":"trace[1862216495] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"232.726894ms","start":"2026-08-09T13:03:04.662084Z","end":"2026-08-09T13:03:04.894811Z","steps":["trace[1862216495] 'read index received' (duration: 232.5338ms)","trace[1862216495] 'applied index is now lower than readState.Index' (duration: 191.804µs)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:04.895193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.599014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-09T13:03:04.895215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.235732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T13:03:04.895253Z","caller":"traceutil/trace.go:171","msg":"trace[1200435205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2171; }","duration":"230.690995ms","start":"2026-08-09T13:03:04.664543Z","end":"2026-08-09T13:03:04.895234Z","steps":["trace[1200435205] 'agreement among raft nodes before linearized reading' (duration: 230.473441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:04.895221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.122642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-09T13:03:04.895251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.673918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:03:04.895416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.213157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-09T13:03:04.895478Z","caller":"traceutil/trace.go:171","msg":"trace[1619537362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2171; }","duration":"205.932902ms","start":"2026-08-09T13:03:04.689525Z","end":"2026-08-09T13:03:04.895458Z","steps":["trace[1619537362] 'agreement among raft nodes before linearized reading' (duration: 205.649727ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:04.895504Z","caller":"traceutil/trace.go:171","msg":"trace[157911130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2171; }","duration":"229.33735ms","start":"2026-08-09T13:03:04.666149Z","end":"2026-08-09T13:03:04.895486Z","steps":["trace[157911130] 'agreement among raft nodes before linearized reading' (duration: 229.072205ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:04.895298Z","caller":"traceutil/trace.go:171","msg":"trace[748496374] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"169.384125ms","start":"2026-08-09T13:03:04.725893Z","end":"2026-08-09T13:03:04.895278Z","steps":["trace[748496374] 'agreement among raft nodes before linearized reading' (duration: 169.15981ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:04.895397Z","caller":"traceutil/trace.go:171","msg":"trace[1034921802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2171; }","duration":"233.316635ms","start":"2026-08-09T13:03:04.662025Z","end":"2026-08-09T13:03:04.895342Z","steps":["trace[1034921802] 'agreement among raft nodes before linearized reading' (duration: 232.942568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.500135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.345171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:03:05.500614Z","caller":"traceutil/trace.go:171","msg":"trace[258188272] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"600.755341ms","start":"2026-08-09T13:03:04.899837Z","end":"2026-08-09T13:03:05.500592Z","steps":["trace[258188272] 'process raft request' (duration: 600.514796ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:05.500615Z","caller":"traceutil/trace.go:171","msg":"trace[789736235] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"600.853973ms","start":"2026-08-09T13:03:04.89973Z","end":"2026-08-09T13:03:05.500584Z","steps":["trace[789736235] 'process raft request' (duration: 600.514526ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:05.500647Z","caller":"traceutil/trace.go:171","msg":"trace[2105310862] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"601.102598ms","start":"2026-08-09T13:03:04.899497Z","end":"2026-08-09T13:03:05.500599Z","steps":["trace[2105310862] 'process raft request' (duration: 75.157575ms)","trace[2105310862] 'compare' (duration: 525.107117ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:05.500702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:04.899821Z","time spent":"600.843883ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:05.500732Z","caller":"traceutil/trace.go:171","msg":"trace[970435086] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2256; }","duration":"551.835957ms","start":"2026-08-09T13:03:04.948873Z","end":"2026-08-09T13:03:05.500709Z","steps":["trace[970435086] 'read index received' (duration: 25.698341ms)","trace[970435086] 'applied index is now lower than readState.Index' (duration: 526.135076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:05.500786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:04.899705Z","time spent":"600.983356ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:05.50089Z","caller":"traceutil/trace.go:171","msg":"trace[1755657708] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"477.64081ms","start":"2026-08-09T13:03:05.023239Z","end":"2026-08-09T13:03:05.50088Z","steps":["trace[1755657708] 'process raft request' (duration: 477.154081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.500943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.023221Z","time spent":"477.696622ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:05.500947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:04.899483Z","time spent":"601.351892ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:05.500929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.036791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-09T13:03:05.501044Z","caller":"traceutil/trace.go:171","msg":"trace[271442072] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2175; }","duration":"552.200254ms","start":"2026-08-09T13:03:04.948817Z","end":"2026-08-09T13:03:05.501017Z","steps":["trace[271442072] 'agreement among raft nodes before linearized reading' (duration: 551.99042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.501112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:04.94879Z","time spent":"552.307126ms","remote":"127.0.0.1:33086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-09T13:03:05.651261Z","caller":"traceutil/trace.go:171","msg":"trace[1167696536] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2260; }","duration":"150.434792ms","start":"2026-08-09T13:03:05.500792Z","end":"2026-08-09T13:03:05.651226Z","steps":["trace[1167696536] 'read index received' (duration: 110.337047ms)","trace[1167696536] 'applied index is now lower than readState.Index' (duration: 40.096635ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:05.651423Z","caller":"traceutil/trace.go:171","msg":"trace[831084154] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"571.36826ms","start":"2026-08-09T13:03:05.079991Z","end":"2026-08-09T13:03:05.651359Z","steps":["trace[831084154] 'process raft request' (duration: 571.197567ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:05.651467Z","caller":"traceutil/trace.go:171","msg":"trace[394411723] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"621.700161ms","start":"2026-08-09T13:03:05.029709Z","end":"2026-08-09T13:03:05.65141Z","steps":["trace[394411723] 'process raft request' (duration: 581.474573ms)","trace[394411723] 'compare' (duration: 39.82187ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:05.651646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.855349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18ca24b4a88fd57f\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-08-09T13:03:05.651475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.532083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:03:05.651601Z","caller":"traceutil/trace.go:171","msg":"trace[1206592095] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"594.39331ms","start":"2026-08-09T13:03:05.057137Z","end":"2026-08-09T13:03:05.651531Z","steps":["trace[1206592095] 'process raft request' (duration: 594.009923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.651708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.958933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-09T13:03:05.651768Z","caller":"traceutil/trace.go:171","msg":"trace[738318048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2179; }","duration":"570.042515ms","start":"2026-08-09T13:03:05.081713Z","end":"2026-08-09T13:03:05.651755Z","steps":["trace[738318048] 'agreement among raft nodes before linearized reading' (duration: 569.857462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.651496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.032961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:03:05.651771Z","caller":"traceutil/trace.go:171","msg":"trace[624396478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2179; }","duration":"295.8623ms","start":"2026-08-09T13:03:05.355852Z","end":"2026-08-09T13:03:05.651715Z","steps":["trace[624396478] 'agreement among raft nodes before linearized reading' (duration: 295.527523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.651769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.890874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-09T13:03:05.651849Z","caller":"traceutil/trace.go:171","msg":"trace[1098234613] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2179; }","duration":"696.411328ms","start":"2026-08-09T13:03:04.955419Z","end":"2026-08-09T13:03:05.65183Z","steps":["trace[1098234613] 'agreement among raft nodes before linearized reading' (duration: 695.99642ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:05.651866Z","caller":"traceutil/trace.go:171","msg":"trace[560773686] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2179; }","duration":"148.058007ms","start":"2026-08-09T13:03:05.50379Z","end":"2026-08-09T13:03:05.651848Z","steps":["trace[560773686] 'agreement among raft nodes before linearized reading' (duration: 147.746531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.651898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:04.955413Z","time spent":"696.471029ms","remote":"127.0.0.1:33058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:03:05.651873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.057118Z","time spent":"594.638134ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:05.651663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.079974Z","time spent":"571.578204ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:05.651697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.741823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-09T13:03:05.652049Z","caller":"traceutil/trace.go:171","msg":"trace[1065967132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2179; }","duration":"566.103519ms","start":"2026-08-09T13:03:05.085919Z","end":"2026-08-09T13:03:05.652023Z","steps":["trace[1065967132] 'agreement among raft nodes before linearized reading' (duration: 565.62587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.652128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.085907Z","time spent":"566.203032ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-09T13:03:05.6517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.029696Z","time spent":"621.860065ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:05.651702Z","caller":"traceutil/trace.go:171","msg":"trace[961925726] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18ca24b4a88fd57f; range_end:; response_count:1; response_revision:2179; }","duration":"107.915401ms","start":"2026-08-09T13:03:05.543764Z","end":"2026-08-09T13:03:05.651679Z","steps":["trace[961925726] 'agreement among raft nodes before linearized reading' (duration: 107.767638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:05.651803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.081707Z","time spent":"570.088946ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-09T13:03:06.146743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.893564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:03:06.146933Z","caller":"traceutil/trace.go:171","msg":"trace[841815445] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2264; }","duration":"354.842356ms","start":"2026-08-09T13:03:05.792072Z","end":"2026-08-09T13:03:06.146915Z","steps":["trace[841815445] 'read index received' (duration: 51.511µs)","trace[841815445] 'applied index is now lower than readState.Index' (duration: 354.789775ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:06.147315Z","caller":"traceutil/trace.go:171","msg":"trace[1875017053] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"490.856833ms","start":"2026-08-09T13:03:05.656444Z","end":"2026-08-09T13:03:06.147301Z","steps":["trace[1875017053] 'process raft request' (duration: 490.414654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:06.147395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.656422Z","time spent":"490.934924ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:06.148029Z","caller":"traceutil/trace.go:171","msg":"trace[1212056352] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"491.800351ms","start":"2026-08-09T13:03:05.656197Z","end":"2026-08-09T13:03:06.147997Z","steps":["trace[1212056352] 'process raft request' (duration: 109.521431ms)","trace[1212056352] 'compare' (duration: 380.780532ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:06.148191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.656182Z","time spent":"491.925083ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:06.14837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.278853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T13:03:06.148455Z","caller":"traceutil/trace.go:171","msg":"trace[1244923154] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2182; }","duration":"356.408025ms","start":"2026-08-09T13:03:05.792025Z","end":"2026-08-09T13:03:06.148433Z","steps":["trace[1244923154] 'agreement among raft nodes before linearized reading' (duration: 355.555839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:06.148505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:05.792013Z","time spent":"356.481197ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":24,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:03:06.148713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.781393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T13:03:06.148775Z","caller":"traceutil/trace.go:171","msg":"trace[649546343] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2182; }","duration":"245.890335ms","start":"2026-08-09T13:03:05.902873Z","end":"2026-08-09T13:03:06.148763Z","steps":["trace[649546343] 'agreement among raft nodes before linearized reading' (duration: 245.13198ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:06.737673Z","caller":"traceutil/trace.go:171","msg":"trace[1903590068] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"160.522345ms","start":"2026-08-09T13:03:06.577125Z","end":"2026-08-09T13:03:06.737648Z","steps":["trace[1903590068] 'read index received' (duration: 160.26011ms)","trace[1903590068] 'applied index is now lower than readState.Index' (duration: 261.145µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:06.737737Z","caller":"traceutil/trace.go:171","msg":"trace[1598238412] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"293.652276ms","start":"2026-08-09T13:03:06.444044Z","end":"2026-08-09T13:03:06.737696Z","steps":["trace[1598238412] 'process raft request' (duration: 293.452243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:06.738024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.879191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T13:03:06.738141Z","caller":"traceutil/trace.go:171","msg":"trace[1053596003] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2183; }","duration":"161.057385ms","start":"2026-08-09T13:03:06.577066Z","end":"2026-08-09T13:03:06.738123Z","steps":["trace[1053596003] 'agreement among raft nodes before linearized reading' (duration: 160.725018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.198643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.178848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:03:07.198722Z","caller":"traceutil/trace.go:171","msg":"trace[1308655144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2183; }","duration":"459.31484ms","start":"2026-08-09T13:03:06.73939Z","end":"2026-08-09T13:03:07.198705Z","steps":["trace[1308655144] 'range keys from in-memory index tree' (duration: 459.049955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.198762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:06.739373Z","time spent":"459.381142ms","remote":"127.0.0.1:32908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-09T13:03:07.738711Z","caller":"traceutil/trace.go:171","msg":"trace[548370995] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"231.265926ms","start":"2026-08-09T13:03:07.507425Z","end":"2026-08-09T13:03:07.738691Z","steps":["trace[548370995] 'process raft request' (duration: 231.141434ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:07.995051Z","caller":"traceutil/trace.go:171","msg":"trace[1908798873] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"485.4556ms","start":"2026-08-09T13:03:07.509574Z","end":"2026-08-09T13:03:07.99503Z","steps":["trace[1908798873] 'process raft request' (duration: 485.107954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.995204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:07.509562Z","time spent":"485.553182ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:07.995256Z","caller":"traceutil/trace.go:171","msg":"trace[816622000] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"485.591912ms","start":"2026-08-09T13:03:07.509647Z","end":"2026-08-09T13:03:07.995239Z","steps":["trace[816622000] 'process raft request' (duration: 485.347668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.995374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:07.509639Z","time spent":"485.678805ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:07.995949Z","caller":"traceutil/trace.go:171","msg":"trace[1268904019] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"485.288097ms","start":"2026-08-09T13:03:07.510108Z","end":"2026-08-09T13:03:07.995396Z","steps":["trace[1268904019] 'process raft request' (duration: 485.058503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.996805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:07.510074Z","time spent":"486.628032ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:07.997135Z","caller":"traceutil/trace.go:171","msg":"trace[1449396320] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2267; }","duration":"307.150755ms","start":"2026-08-09T13:03:07.689916Z","end":"2026-08-09T13:03:07.997067Z","steps":["trace[1449396320] 'read index received' (duration: 48.71049ms)","trace[1449396320] 'applied index is now lower than readState.Index' (duration: 258.439425ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:07.997109Z","caller":"traceutil/trace.go:171","msg":"trace[1100669559] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"339.905421ms","start":"2026-08-09T13:03:07.657144Z","end":"2026-08-09T13:03:07.99705Z","steps":["trace[1100669559] 'process raft request' (duration: 338.215009ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:07.997283Z","caller":"traceutil/trace.go:171","msg":"trace[1423022211] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"339.542324ms","start":"2026-08-09T13:03:07.657711Z","end":"2026-08-09T13:03:07.997253Z","steps":["trace[1423022211] 'process raft request' (duration: 339.243578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.997295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:07.657123Z","time spent":"340.069684ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:07.997369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:07.657701Z","time spent":"339.621655ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:07.997482Z","caller":"traceutil/trace.go:171","msg":"trace[2035141312] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"339.638256ms","start":"2026-08-09T13:03:07.657829Z","end":"2026-08-09T13:03:07.997467Z","steps":["trace[2035141312] 'process raft request' (duration: 339.192397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.997494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.578363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:03:07.997519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:07.657803Z","time spent":"339.694056ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:07.997532Z","caller":"traceutil/trace.go:171","msg":"trace[1984648203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"307.635145ms","start":"2026-08-09T13:03:07.689878Z","end":"2026-08-09T13:03:07.997514Z","steps":["trace[1984648203] 'agreement among raft nodes before linearized reading' (duration: 307.294098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:07.997556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:07.689863Z","time spent":"307.687405ms","remote":"127.0.0.1:32898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-09T13:03:13.890434Z","caller":"traceutil/trace.go:171","msg":"trace[988523184] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"131.116473ms","start":"2026-08-09T13:03:13.759293Z","end":"2026-08-09T13:03:13.890409Z","steps":["trace[988523184] 'process raft request' (duration: 130.9439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.522885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204185289755636,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T13:03:14.677895Z","caller":"traceutil/trace.go:171","msg":"trace[958998116] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"660.90279ms","start":"2026-08-09T13:03:14.01694Z","end":"2026-08-09T13:03:14.677842Z","steps":["trace[958998116] 'process raft request' (duration: 643.386406ms)","trace[958998116] 'compare' (duration: 16.991374ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:14.678121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.016913Z","time spent":"661.078803ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:14.728458Z","caller":"traceutil/trace.go:171","msg":"trace[956393179] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"710.808153ms","start":"2026-08-09T13:03:14.017627Z","end":"2026-08-09T13:03:14.728435Z","steps":["trace[956393179] 'process raft request' (duration: 710.662851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.728599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.01759Z","time spent":"710.936105ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:14.728622Z","caller":"traceutil/trace.go:171","msg":"trace[518241543] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2299; }","duration":"706.602722ms","start":"2026-08-09T13:03:14.021999Z","end":"2026-08-09T13:03:14.728602Z","steps":["trace[518241543] 'read index received' (duration: 638.335909ms)","trace[518241543] 'applied index is now lower than readState.Index' (duration: 68.265813ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:14.728727Z","caller":"traceutil/trace.go:171","msg":"trace[1283912620] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"710.67184ms","start":"2026-08-09T13:03:14.018034Z","end":"2026-08-09T13:03:14.728705Z","steps":["trace[1283912620] 'process raft request' (duration: 710.364505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.728835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.018008Z","time spent":"710.773312ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:14.728875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.867267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-09T13:03:14.728936Z","caller":"traceutil/trace.go:171","msg":"trace[1748620382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2217; }","duration":"706.95795ms","start":"2026-08-09T13:03:14.02196Z","end":"2026-08-09T13:03:14.728918Z","steps":["trace[1748620382] 'agreement among raft nodes before linearized reading' (duration: 706.770096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.72899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.021944Z","time spent":"707.034491ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-09T13:03:14.729051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.292407ms","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":"warn","ts":"2026-08-09T13:03:14.728946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.802898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-09T13:03:14.729112Z","caller":"traceutil/trace.go:171","msg":"trace[70419451] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2217; }","duration":"607.356658ms","start":"2026-08-09T13:03:14.121737Z","end":"2026-08-09T13:03:14.729093Z","steps":["trace[70419451] 'agreement among raft nodes before linearized reading' (duration: 607.257586ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:14.729117Z","caller":"traceutil/trace.go:171","msg":"trace[1481983217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2217; }","duration":"549.999732ms","start":"2026-08-09T13:03:14.179103Z","end":"2026-08-09T13:03:14.729103Z","steps":["trace[1481983217] 'agreement among raft nodes before linearized reading' (duration: 549.767978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.729167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.179091Z","time spent":"550.063493ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-09T13:03:14.729222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.615213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-09T13:03:14.729164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.121692Z","time spent":"607.45776ms","remote":"127.0.0.1:55194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-09T13:03:14.729287Z","caller":"traceutil/trace.go:171","msg":"trace[1666518056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2217; }","duration":"705.702885ms","start":"2026-08-09T13:03:14.023564Z","end":"2026-08-09T13:03:14.729267Z","steps":["trace[1666518056] 'agreement among raft nodes before linearized reading' (duration: 705.578753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.729316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.94256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-09T13:03:14.729336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.023552Z","time spent":"705.774766ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-09T13:03:14.72936Z","caller":"traceutil/trace.go:171","msg":"trace[1675252513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2217; }","duration":"551.059113ms","start":"2026-08-09T13:03:14.178288Z","end":"2026-08-09T13:03:14.729347Z","steps":["trace[1675252513] 'agreement among raft nodes before linearized reading' (duration: 550.90287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.729401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.178266Z","time spent":"551.124834ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-09T13:03:14.729088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.004841ms","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-08-09T13:03:14.729487Z","caller":"traceutil/trace.go:171","msg":"trace[975207834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2217; }","duration":"706.404379ms","start":"2026-08-09T13:03:14.023046Z","end":"2026-08-09T13:03:14.729451Z","steps":["trace[975207834] 'agreement among raft nodes before linearized reading' (duration: 705.809567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.72955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.023003Z","time spent":"706.530931ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-09T13:03:14.729688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.036696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T13:03:14.72995Z","caller":"traceutil/trace.go:171","msg":"trace[861142163] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2217; }","duration":"481.328131ms","start":"2026-08-09T13:03:14.248589Z","end":"2026-08-09T13:03:14.729917Z","steps":["trace[861142163] 'agreement among raft nodes before linearized reading' (duration: 480.490555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:14.730107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.248576Z","time spent":"481.511345ms","remote":"127.0.0.1:55230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-09T13:03:15.488496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.031415ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:03:15.488655Z","caller":"traceutil/trace.go:171","msg":"trace[1970740067] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2217; }","duration":"625.202548ms","start":"2026-08-09T13:03:14.863429Z","end":"2026-08-09T13:03:15.488632Z","steps":["trace[1970740067] 'range keys from in-memory index tree' (duration: 625.016105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:15.488799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.744931ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-09T13:03:15.488932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.729871Z","time spent":"759.051534ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-09T13:03:15.489033Z","caller":"traceutil/trace.go:171","msg":"trace[1969609654] linearizableReadLoop","detail":"{readStateIndex:2303; appliedIndex:2302; }","duration":"758.262689ms","start":"2026-08-09T13:03:14.730742Z","end":"2026-08-09T13:03:15.489004Z","steps":["trace[1969609654] 'read index received' (duration: 289.301594ms)","trace[1969609654] 'applied index is now lower than readState.Index' (duration: 468.958555ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:15.489289Z","caller":"traceutil/trace.go:171","msg":"trace[1558257947] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"755.978985ms","start":"2026-08-09T13:03:14.733286Z","end":"2026-08-09T13:03:15.489265Z","steps":["trace[1558257947] 'process raft request' (duration: 755.884154ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:15.489299Z","caller":"traceutil/trace.go:171","msg":"trace[1860765005] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"756.179829ms","start":"2026-08-09T13:03:14.733068Z","end":"2026-08-09T13:03:15.489247Z","steps":["trace[1860765005] 'process raft request' (duration: 755.833892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:15.489453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.733274Z","time spent":"756.100958ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:15.489301Z","caller":"traceutil/trace.go:171","msg":"trace[299468943] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"756.024326ms","start":"2026-08-09T13:03:14.733241Z","end":"2026-08-09T13:03:15.489265Z","steps":["trace[299468943] 'process raft request' (duration: 755.826433ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:15.489605Z","caller":"traceutil/trace.go:171","msg":"trace[1953249175] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"755.68513ms","start":"2026-08-09T13:03:14.73389Z","end":"2026-08-09T13:03:15.489575Z","steps":["trace[1953249175] 'process raft request' (duration: 755.333774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:15.489705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.733881Z","time spent":"755.773252ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:15.489677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.733218Z","time spent":"756.349492ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:15.489312Z","caller":"traceutil/trace.go:171","msg":"trace[464023158] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"756.030097ms","start":"2026-08-09T13:03:14.733255Z","end":"2026-08-09T13:03:15.489285Z","steps":["trace[464023158] 'process raft request' (duration: 755.873574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:15.489761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.681749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:03:15.489629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.733051Z","time spent":"756.417444ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:03:15.489853Z","caller":"traceutil/trace.go:171","msg":"trace[1997879680] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2222; }","duration":"756.811031ms","start":"2026-08-09T13:03:14.733019Z","end":"2026-08-09T13:03:15.489831Z","steps":["trace[1997879680] 'agreement among raft nodes before linearized reading' (duration: 756.549136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:15.490061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.733011Z","time spent":"757.018015ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:03:15.489862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.117096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:03:15.490218Z","caller":"traceutil/trace.go:171","msg":"trace[1491185158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2222; }","duration":"759.423121ms","start":"2026-08-09T13:03:14.730722Z","end":"2026-08-09T13:03:15.490145Z","steps":["trace[1491185158] 'agreement among raft nodes before linearized reading' (duration: 758.438012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:15.489891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.73323Z","time spent":"756.599027ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:15.490368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:14.730711Z","time spent":"759.647245ms","remote":"127.0.0.1:32908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-09T13:03:53.709749Z","caller":"traceutil/trace.go:171","msg":"trace[95190336] linearizableReadLoop","detail":"{readStateIndex:2701; appliedIndex:2700; }","duration":"160.983077ms","start":"2026-08-09T13:03:53.548741Z","end":"2026-08-09T13:03:53.709724Z","steps":["trace[95190336] 'read index received' (duration: 160.763229ms)","trace[95190336] 'applied index is now lower than readState.Index' (duration: 218.798µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:53.710093Z","caller":"traceutil/trace.go:171","msg":"trace[13029005] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"241.783389ms","start":"2026-08-09T13:03:53.468296Z","end":"2026-08-09T13:03:53.710079Z","steps":["trace[13029005] 'process raft request' (duration: 241.267332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:53.710415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.653368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:03:53.710524Z","caller":"traceutil/trace.go:171","msg":"trace[998221989] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2601; }","duration":"161.807273ms","start":"2026-08-09T13:03:53.548689Z","end":"2026-08-09T13:03:53.710496Z","steps":["trace[998221989] 'agreement among raft nodes before linearized reading' (duration: 161.650577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:04:26.437327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204185289756945,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T13:04:26.456601Z","caller":"traceutil/trace.go:171","msg":"trace[448279935] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"527.559325ms","start":"2026-08-09T13:04:25.929011Z","end":"2026-08-09T13:04:26.45657Z","steps":["trace[448279935] 'process raft request' (duration: 527.351449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:04:26.457879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:04:25.928986Z","time spent":"528.748848ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:04:26.576496Z","caller":"traceutil/trace.go:171","msg":"trace[1018396765] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2905; }","duration":"639.793305ms","start":"2026-08-09T13:04:25.936678Z","end":"2026-08-09T13:04:26.576471Z","steps":["trace[1018396765] 'read index received' (duration: 519.755687ms)","trace[1018396765] 'applied index is now lower than readState.Index' (duration: 120.036438ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:04:26.576575Z","caller":"traceutil/trace.go:171","msg":"trace[140053306] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"407.506726ms","start":"2026-08-09T13:04:26.169003Z","end":"2026-08-09T13:04:26.57651Z","steps":["trace[140053306] 'process raft request' (duration: 404.686578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:04:26.576674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:04:26.168982Z","time spent":"407.64314ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:04:26.576833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.137606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-09T13:04:26.576887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.89756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-09T13:04:26.576974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.365748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-09T13:04:26.576966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.488845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-09T13:04:26.577009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.981699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18ca24c8a2bab948\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-08-09T13:04:26.577068Z","caller":"traceutil/trace.go:171","msg":"trace[1630038256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2799; }","duration":"635.591699ms","start":"2026-08-09T13:04:25.941432Z","end":"2026-08-09T13:04:26.577024Z","steps":["trace[1630038256] 'agreement among raft nodes before linearized reading' (duration: 635.365442ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:04:26.577023Z","caller":"traceutil/trace.go:171","msg":"trace[302327316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2799; }","duration":"637.43559ms","start":"2026-08-09T13:04:25.939576Z","end":"2026-08-09T13:04:26.577011Z","steps":["trace[302327316] 'agreement among raft nodes before linearized reading' (duration: 637.275966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:04:26.577119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:04:25.939556Z","time spent":"637.555373ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-09T13:04:26.577141Z","caller":"traceutil/trace.go:171","msg":"trace[1266240057] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18ca24c8a2bab948; range_end:; response_count:1; response_revision:2799; }","duration":"228.00259ms","start":"2026-08-09T13:04:26.349021Z","end":"2026-08-09T13:04:26.577024Z","steps":["trace[1266240057] 'agreement among raft nodes before linearized reading' (duration: 227.925297ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:04:26.576972Z","caller":"traceutil/trace.go:171","msg":"trace[403390560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2799; }","duration":"639.025834ms","start":"2026-08-09T13:04:25.937927Z","end":"2026-08-09T13:04:26.576953Z","steps":["trace[403390560] 'agreement among raft nodes before linearized reading' (duration: 638.819718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:04:26.577272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:04:25.937917Z","time spent":"639.319103ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-09T13:04:26.577119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:04:25.941425Z","time spent":"635.683931ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-09T13:04:26.576891Z","caller":"traceutil/trace.go:171","msg":"trace[220696401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2799; }","duration":"640.223088ms","start":"2026-08-09T13:04:25.936656Z","end":"2026-08-09T13:04:26.576879Z","steps":["trace[220696401] 'agreement among raft nodes before linearized reading' (duration: 639.975581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:04:26.577491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:04:25.936648Z","time spent":"640.833204ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-09T13:06:52.868908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.854736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:06:52.869006Z","caller":"traceutil/trace.go:171","msg":"trace[612870144] linearizableReadLoop","detail":"{readStateIndex:3916; appliedIndex:3915; }","duration":"105.773336ms","start":"2026-08-09T13:06:52.763218Z","end":"2026-08-09T13:06:52.868991Z","steps":["trace[612870144] 'read index received' (duration: 3.677043ms)","trace[612870144] 'applied index is now lower than readState.Index' (duration: 102.094923ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:06:52.869075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.855109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:06:52.869098Z","caller":"traceutil/trace.go:171","msg":"trace[205203563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3772; }","duration":"105.903381ms","start":"2026-08-09T13:06:52.763188Z","end":"2026-08-09T13:06:52.869092Z","steps":["trace[205203563] 'agreement among raft nodes before linearized reading' (duration: 105.840448ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:06:52.869265Z","caller":"traceutil/trace.go:171","msg":"trace[346568262] transaction","detail":"{read_only:false; response_revision:3772; number_of_response:1; }","duration":"281.56617ms","start":"2026-08-09T13:06:52.587691Z","end":"2026-08-09T13:06:52.869257Z","steps":["trace[346568262] 'process raft request' (duration: 179.29027ms)","trace[346568262] 'compare' (duration: 101.624218ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:08:15.247625Z","caller":"traceutil/trace.go:171","msg":"trace[1853514126] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5108; }","duration":"210.831178ms","start":"2026-08-09T13:08:15.036753Z","end":"2026-08-09T13:08:15.247585Z","steps":["trace[1853514126] 'read index received' (duration: 149.921704ms)","trace[1853514126] 'applied index is now lower than readState.Index' (duration: 60.907314ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:08:15.247658Z","caller":"traceutil/trace.go:171","msg":"trace[1531698216] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"301.177967ms","start":"2026-08-09T13:08:14.946434Z","end":"2026-08-09T13:08:15.247612Z","steps":["trace[1531698216] 'process raft request' (duration: 240.255122ms)","trace[1531698216] 'compare' (duration: 60.607533ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:08:15.247833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:08:14.946392Z","time spent":"301.337893ms","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3623,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-09T13:08:15.248019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.233983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d33a82b7-5a1d-4e89-8232-54f7497606f0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-09T13:08:15.248142Z","caller":"traceutil/trace.go:171","msg":"trace[19324478] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d33a82b7-5a1d-4e89-8232-54f7497606f0; range_end:; response_count:1; response_revision:4923; }","duration":"211.41092ms","start":"2026-08-09T13:08:15.036705Z","end":"2026-08-09T13:08:15.248116Z","steps":["trace[19324478] 'agreement among raft nodes before linearized reading' (duration: 210.989624ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:15.248722Z","caller":"traceutil/trace.go:171","msg":"trace[1776646743] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"103.429101ms","start":"2026-08-09T13:08:15.145264Z","end":"2026-08-09T13:08:15.248693Z","steps":["trace[1776646743] 'process raft request' (duration: 103.318327ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:23.056094Z","caller":"traceutil/trace.go:171","msg":"trace[1852376566] linearizableReadLoop","detail":"{readStateIndex:5281; appliedIndex:5278; }","duration":"102.835818ms","start":"2026-08-09T13:08:22.953222Z","end":"2026-08-09T13:08:23.056058Z","steps":["trace[1852376566] 'read index received' (duration: 102.580009ms)","trace[1852376566] 'applied index is now lower than readState.Index' (duration: 253.749µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:08:23.056104Z","caller":"traceutil/trace.go:171","msg":"trace[1095446694] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"115.995894ms","start":"2026-08-09T13:08:22.940041Z","end":"2026-08-09T13:08:23.056037Z","steps":["trace[1095446694] 'process raft request' (duration: 115.716073ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:23.05621Z","caller":"traceutil/trace.go:171","msg":"trace[958473494] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"115.508926ms","start":"2026-08-09T13:08:22.940656Z","end":"2026-08-09T13:08:23.056165Z","steps":["trace[958473494] 'process raft request' (duration: 115.254227ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:23.05627Z","caller":"traceutil/trace.go:171","msg":"trace[1505844780] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"113.757232ms","start":"2026-08-09T13:08:22.942426Z","end":"2026-08-09T13:08:23.056183Z","steps":["trace[1505844780] 'process raft request' (duration: 113.551584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:08:23.056395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.15109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-09T13:08:23.056459Z","caller":"traceutil/trace.go:171","msg":"trace[1776257213] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5089; }","duration":"103.258825ms","start":"2026-08-09T13:08:22.953186Z","end":"2026-08-09T13:08:23.056444Z","steps":["trace[1776257213] 'agreement among raft nodes before linearized reading' (duration: 103.041357ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:23.066278Z","caller":"traceutil/trace.go:171","msg":"trace[1301533688] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"109.10969ms","start":"2026-08-09T13:08:22.957147Z","end":"2026-08-09T13:08:23.066257Z","steps":["trace[1301533688] 'process raft request' (duration: 108.977335ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:29.335854Z","caller":"traceutil/trace.go:171","msg":"trace[1705734444] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"111.947905ms","start":"2026-08-09T13:08:29.223882Z","end":"2026-08-09T13:08:29.33583Z","steps":["trace[1705734444] 'process raft request' (duration: 111.80141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:08:36.730443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.563562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-m6hvq\" ","response":"range_response_count:1 size:4040"} {"level":"warn","ts":"2026-08-09T13:08:36.730479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.319198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T13:08:36.730566Z","caller":"traceutil/trace.go:171","msg":"trace[587946203] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-m6hvq; range_end:; response_count:1; response_revision:5408; }","duration":"396.738278ms","start":"2026-08-09T13:08:36.333802Z","end":"2026-08-09T13:08:36.73054Z","steps":["trace[587946203] 'range keys from in-memory index tree' (duration: 396.285322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:08:36.730568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.455936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7cb2c63-5dca-4a50-bd1d-05b6bb1dcff9\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-09T13:08:36.730474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.845393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m485\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-09T13:08:36.730735Z","caller":"traceutil/trace.go:171","msg":"trace[1083898269] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m485; range_end:; response_count:1; response_revision:5408; }","duration":"235.157754ms","start":"2026-08-09T13:08:36.49556Z","end":"2026-08-09T13:08:36.730717Z","steps":["trace[1083898269] 'range keys from in-memory index tree' (duration: 234.614275ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:36.730708Z","caller":"traceutil/trace.go:171","msg":"trace[261882252] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e7cb2c63-5dca-4a50-bd1d-05b6bb1dcff9; range_end:; response_count:1; response_revision:5408; }","duration":"311.641642ms","start":"2026-08-09T13:08:36.419046Z","end":"2026-08-09T13:08:36.730688Z","steps":["trace[261882252] 'range keys from in-memory index tree' (duration: 311.306881ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:08:36.730596Z","caller":"traceutil/trace.go:171","msg":"trace[1847722483] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5408; }","duration":"185.509915ms","start":"2026-08-09T13:08:36.54506Z","end":"2026-08-09T13:08:36.73057Z","steps":["trace[1847722483] 'count revisions from in-memory index tree' (duration: 185.230645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:08:36.730628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:08:36.333777Z","time spent":"396.833512ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4063,"request content":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-m6hvq\" "} {"level":"warn","ts":"2026-08-09T13:08:36.731216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:08:36.419022Z","time spent":"312.182812ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e7cb2c63-5dca-4a50-bd1d-05b6bb1dcff9\" "} {"level":"info","ts":"2026-08-09T13:08:36.926283Z","caller":"traceutil/trace.go:171","msg":"trace[320764192] linearizableReadLoop","detail":"{readStateIndex:5607; appliedIndex:5606; }","duration":"166.92677ms","start":"2026-08-09T13:08:36.759324Z","end":"2026-08-09T13:08:36.926251Z","steps":["trace[320764192] 'read index received' (duration: 166.742993ms)","trace[320764192] 'applied index is now lower than readState.Index' (duration: 182.497µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:08:36.926369Z","caller":"traceutil/trace.go:171","msg":"trace[7934748] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"187.51538ms","start":"2026-08-09T13:08:36.738802Z","end":"2026-08-09T13:08:36.926318Z","steps":["trace[7934748] 'process raft request' (duration: 187.295251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:08:36.926432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.081687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:08:36.926471Z","caller":"traceutil/trace.go:171","msg":"trace[453537388] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5409; }","duration":"167.160029ms","start":"2026-08-09T13:08:36.759297Z","end":"2026-08-09T13:08:36.926457Z","steps":["trace[453537388] 'agreement among raft nodes before linearized reading' (duration: 167.075636ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:09:51.620116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-08-09T13:09:51.701598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"80.036269ms","hash":139703658,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":34021376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-09T13:09:51.701667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":139703658,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-08-09T13:14:51.110678Z","caller":"traceutil/trace.go:171","msg":"trace[1074764645] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"171.810951ms","start":"2026-08-09T13:14:50.938842Z","end":"2026-08-09T13:14:51.110653Z","steps":["trace[1074764645] 'process raft request' (duration: 171.668336ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:14:51.626575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6043} {"level":"info","ts":"2026-08-09T13:14:51.724026Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6043,"took":"95.056091ms","hash":3538280056,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":23392256,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-09T13:14:51.724097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3538280056,"revision":6043,"compact-revision":2941} {"level":"info","ts":"2026-08-09T13:15:01.534729Z","caller":"traceutil/trace.go:171","msg":"trace[58599908] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"142.941643ms","start":"2026-08-09T13:15:01.391761Z","end":"2026-08-09T13:15:01.534703Z","steps":["trace[58599908] 'process raft request' (duration: 89.202135ms)","trace[58599908] 'compare' (duration: 53.637605ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:15:01.552885Z","caller":"traceutil/trace.go:171","msg":"trace[1855323379] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"160.940845ms","start":"2026-08-09T13:15:01.391926Z","end":"2026-08-09T13:15:01.552867Z","steps":["trace[1855323379] 'process raft request' (duration: 160.79495ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:01.553096Z","caller":"traceutil/trace.go:171","msg":"trace[2027288888] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"141.509367ms","start":"2026-08-09T13:15:01.41158Z","end":"2026-08-09T13:15:01.553089Z","steps":["trace[2027288888] 'process raft request' (duration: 141.260609ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:01.553199Z","caller":"traceutil/trace.go:171","msg":"trace[1485621180] linearizableReadLoop","detail":"{readStateIndex:8578; appliedIndex:8576; }","duration":"155.562934ms","start":"2026-08-09T13:15:01.397608Z","end":"2026-08-09T13:15:01.553171Z","steps":["trace[1485621180] 'read index received' (duration: 83.331148ms)","trace[1485621180] 'applied index is now lower than readState.Index' (duration: 72.230486ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:15:01.553292Z","caller":"traceutil/trace.go:171","msg":"trace[22580215] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"134.308658ms","start":"2026-08-09T13:15:01.418976Z","end":"2026-08-09T13:15:01.553285Z","steps":["trace[22580215] 'process raft request' (duration: 134.078231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.55338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.713729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.553439Z","caller":"traceutil/trace.go:171","msg":"trace[783394001] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8291; }","duration":"155.826583ms","start":"2026-08-09T13:15:01.397598Z","end":"2026-08-09T13:15:01.553425Z","steps":["trace[783394001] 'agreement among raft nodes before linearized reading' (duration: 155.72659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.553528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.229058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.553572Z","caller":"traceutil/trace.go:171","msg":"trace[504277873] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8291; }","duration":"118.31719ms","start":"2026-08-09T13:15:01.435245Z","end":"2026-08-09T13:15:01.553563Z","steps":["trace[504277873] 'agreement among raft nodes before linearized reading' (duration: 118.120804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.553649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.922388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.553687Z","caller":"traceutil/trace.go:171","msg":"trace[1599608248] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8291; }","duration":"134.993701ms","start":"2026-08-09T13:15:01.418683Z","end":"2026-08-09T13:15:01.553677Z","steps":["trace[1599608248] 'agreement among raft nodes before linearized reading' (duration: 134.934719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.553643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.66128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:01.553739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.386442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.553791Z","caller":"traceutil/trace.go:171","msg":"trace[529361] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8291; }","duration":"118.441094ms","start":"2026-08-09T13:15:01.435342Z","end":"2026-08-09T13:15:01.553783Z","steps":["trace[529361] 'agreement among raft nodes before linearized reading' (duration: 118.376342ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:01.553789Z","caller":"traceutil/trace.go:171","msg":"trace[492805437] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8291; }","duration":"117.851076ms","start":"2026-08-09T13:15:01.435893Z","end":"2026-08-09T13:15:01.553744Z","steps":["trace[492805437] 'agreement among raft nodes before linearized reading' (duration: 117.584357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.553859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.062804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.553891Z","caller":"traceutil/trace.go:171","msg":"trace[834427718] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8291; }","duration":"136.123996ms","start":"2026-08-09T13:15:01.417758Z","end":"2026-08-09T13:15:01.553882Z","steps":["trace[834427718] 'agreement among raft nodes before linearized reading' (duration: 136.076945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.554047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.472368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.554081Z","caller":"traceutil/trace.go:171","msg":"trace[1758045636] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8291; }","duration":"136.55198ms","start":"2026-08-09T13:15:01.417521Z","end":"2026-08-09T13:15:01.554073Z","steps":["trace[1758045636] 'agreement among raft nodes before linearized reading' (duration: 136.480108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.554129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.902535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.554158Z","caller":"traceutil/trace.go:171","msg":"trace[1827237620] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8291; }","duration":"155.951097ms","start":"2026-08-09T13:15:01.398199Z","end":"2026-08-09T13:15:01.55415Z","steps":["trace[1827237620] 'agreement among raft nodes before linearized reading' (duration: 155.905955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:01.554337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.72953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:01.554365Z","caller":"traceutil/trace.go:171","msg":"trace[285937410] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8291; }","duration":"156.787703ms","start":"2026-08-09T13:15:01.397569Z","end":"2026-08-09T13:15:01.554356Z","steps":["trace[285937410] 'agreement among raft nodes before linearized reading' (duration: 155.73459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.582186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.003116Z","caller":"traceutil/trace.go:171","msg":"trace[437887889] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8291; }","duration":"274.70329ms","start":"2026-08-09T13:15:01.728398Z","end":"2026-08-09T13:15:02.003102Z","steps":["trace[437887889] 'count revisions from in-memory index tree' (duration: 274.457892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.512931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.003067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.18045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.003166Z","caller":"traceutil/trace.go:171","msg":"trace[1329103029] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8291; }","duration":"362.670377ms","start":"2026-08-09T13:15:01.640479Z","end":"2026-08-09T13:15:02.00315Z","steps":["trace[1329103029] 'range keys from in-memory index tree' (duration: 362.395348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.640455Z","time spent":"362.742708ms","remote":"127.0.0.1:47624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 "} {"level":"info","ts":"2026-08-09T13:15:02.003218Z","caller":"traceutil/trace.go:171","msg":"trace[184337049] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8291; }","duration":"339.408037ms","start":"2026-08-09T13:15:01.663782Z","end":"2026-08-09T13:15:02.00319Z","steps":["trace[184337049] 'range keys from in-memory index tree' (duration: 339.108158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.473723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.00328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.663776Z","time spent":"339.489719ms","remote":"127.0.0.1:47626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 "} {"level":"info","ts":"2026-08-09T13:15:02.003302Z","caller":"traceutil/trace.go:171","msg":"trace[1970678616] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8291; }","duration":"274.512004ms","start":"2026-08-09T13:15:01.728783Z","end":"2026-08-09T13:15:02.003295Z","steps":["trace[1970678616] 'range keys from in-memory index tree' (duration: 274.449252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.563203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-745911c8-7a03-49ff-a64a-299be805d7ac\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-08-09T13:15:02.0034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.439942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.003402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.029918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T13:15:02.003421Z","caller":"traceutil/trace.go:171","msg":"trace[1740487820] range","detail":"{range_begin:/registry/persistentvolumes/pvc-745911c8-7a03-49ff-a64a-299be805d7ac; range_end:; response_count:1; response_revision:8291; }","duration":"362.666577ms","start":"2026-08-09T13:15:01.640732Z","end":"2026-08-09T13:15:02.003398Z","steps":["trace[1740487820] 'range keys from in-memory index tree' (duration: 362.289275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.321273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-09T13:15:02.003462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.230938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.003422Z","caller":"traceutil/trace.go:171","msg":"trace[1156912939] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8291; }","duration":"246.496494ms","start":"2026-08-09T13:15:01.75692Z","end":"2026-08-09T13:15:02.003416Z","steps":["trace[1156912939] 'range keys from in-memory index tree' (duration: 246.331418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.003468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.64072Z","time spent":"362.740309ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-745911c8-7a03-49ff-a64a-299be805d7ac\" "} {"level":"info","ts":"2026-08-09T13:15:02.003481Z","caller":"traceutil/trace.go:171","msg":"trace[1681699455] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8291; }","duration":"231.300211ms","start":"2026-08-09T13:15:01.772174Z","end":"2026-08-09T13:15:02.003474Z","steps":["trace[1681699455] 'range keys from in-memory index tree' (duration: 231.140515ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.003478Z","caller":"traceutil/trace.go:171","msg":"trace[1742672712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8291; }","duration":"108.431246ms","start":"2026-08-09T13:15:01.895034Z","end":"2026-08-09T13:15:02.003465Z","steps":["trace[1742672712] 'range keys from in-memory index tree' (duration: 108.165708ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.003488Z","caller":"traceutil/trace.go:171","msg":"trace[1087841185] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8291; }","duration":"363.140081ms","start":"2026-08-09T13:15:01.640342Z","end":"2026-08-09T13:15:02.003482Z","steps":["trace[1087841185] 'count revisions from in-memory index tree' (duration: 362.990796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.640335Z","time spent":"363.170502ms","remote":"127.0.0.1:47624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:15:02.003584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.27659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.003607Z","caller":"traceutil/trace.go:171","msg":"trace[988292704] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8291; }","duration":"231.331882ms","start":"2026-08-09T13:15:01.772269Z","end":"2026-08-09T13:15:02.003601Z","steps":["trace[988292704] 'count revisions from in-memory index tree' (duration: 231.189827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.523681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.003695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.212628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.003438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.098582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.003717Z","caller":"traceutil/trace.go:171","msg":"trace[61748250] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8291; }","duration":"231.312831ms","start":"2026-08-09T13:15:01.772398Z","end":"2026-08-09T13:15:02.003711Z","steps":["trace[61748250] 'range keys from in-memory index tree' (duration: 230.992081ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.003722Z","caller":"traceutil/trace.go:171","msg":"trace[1821960146] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8291; }","duration":"246.387971ms","start":"2026-08-09T13:15:01.757327Z","end":"2026-08-09T13:15:02.003715Z","steps":["trace[1821960146] 'range keys from in-memory index tree' (duration: 246.05877ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.003453Z","caller":"traceutil/trace.go:171","msg":"trace[928183153] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8291; }","duration":"324.124841ms","start":"2026-08-09T13:15:01.679314Z","end":"2026-08-09T13:15:02.003439Z","steps":["trace[928183153] 'count revisions from in-memory index tree' (duration: 323.946635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.679284Z","time spent":"324.512503ms","remote":"127.0.0.1:33348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:15:02.003819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.098068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.003846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.573414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.003879Z","caller":"traceutil/trace.go:171","msg":"trace[1597566778] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8291; }","duration":"289.17099ms","start":"2026-08-09T13:15:01.714691Z","end":"2026-08-09T13:15:02.003862Z","steps":["trace[1597566778] 'count revisions from in-memory index tree' (duration: 289.026546ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.003887Z","caller":"traceutil/trace.go:171","msg":"trace[661623557] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8291; }","duration":"340.660697ms","start":"2026-08-09T13:15:01.663216Z","end":"2026-08-09T13:15:02.003877Z","steps":["trace[661623557] 'count revisions from in-memory index tree' (duration: 340.512152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.35466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.003927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.663197Z","time spent":"340.715259ms","remote":"127.0.0.1:47626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:15:02.003993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.05991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.004028Z","caller":"traceutil/trace.go:171","msg":"trace[344100501] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8291; }","duration":"319.101471ms","start":"2026-08-09T13:15:01.684918Z","end":"2026-08-09T13:15:02.004019Z","steps":["trace[344100501] 'count revisions from in-memory index tree' (duration: 319.033149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.004053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.684914Z","time spent":"319.133132ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:15:02.00406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.624855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.004076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.844852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.004091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.450769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.004114Z","caller":"traceutil/trace.go:171","msg":"trace[962614270] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8291; }","duration":"289.499241ms","start":"2026-08-09T13:15:01.714609Z","end":"2026-08-09T13:15:02.004108Z","steps":["trace[962614270] 'range keys from in-memory index tree' (duration: 289.322595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.00411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.351936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.004115Z","caller":"traceutil/trace.go:171","msg":"trace[1591120829] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8291; }","duration":"317.892153ms","start":"2026-08-09T13:15:01.686212Z","end":"2026-08-09T13:15:02.004104Z","steps":["trace[1591120829] 'range keys from in-memory index tree' (duration: 317.819841ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.003934Z","caller":"traceutil/trace.go:171","msg":"trace[904498368] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8291; }","duration":"246.957008ms","start":"2026-08-09T13:15:01.756971Z","end":"2026-08-09T13:15:02.003928Z","steps":["trace[904498368] 'count revisions from in-memory index tree' (duration: 246.316949ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.004141Z","caller":"traceutil/trace.go:171","msg":"trace[2024244138] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8291; }","duration":"289.396418ms","start":"2026-08-09T13:15:01.714736Z","end":"2026-08-09T13:15:02.004132Z","steps":["trace[2024244138] 'range keys from in-memory index tree' (duration: 289.323596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.00415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.686207Z","time spent":"317.934394ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 "} {"level":"info","ts":"2026-08-09T13:15:02.004091Z","caller":"traceutil/trace.go:171","msg":"trace[2039182249] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8291; }","duration":"340.668087ms","start":"2026-08-09T13:15:01.663415Z","end":"2026-08-09T13:15:02.004083Z","steps":["trace[2039182249] 'range keys from in-memory index tree' (duration: 340.532383ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.004223Z","caller":"traceutil/trace.go:171","msg":"trace[660610202] linearizableReadLoop","detail":"{readStateIndex:8581; appliedIndex:8580; }","duration":"106.076731ms","start":"2026-08-09T13:15:01.89814Z","end":"2026-08-09T13:15:02.004216Z","steps":["trace[660610202] 'read index received' (duration: 51.220907ms)","trace[660610202] 'applied index is now lower than readState.Index' (duration: 54.855104ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:15:02.004236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.66341Z","time spent":"340.814572ms","remote":"127.0.0.1:47626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 "} {"level":"info","ts":"2026-08-09T13:15:02.003694Z","caller":"traceutil/trace.go:171","msg":"trace[1483590750] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8291; }","duration":"362.580104ms","start":"2026-08-09T13:15:01.641104Z","end":"2026-08-09T13:15:02.003684Z","steps":["trace[1483590750] 'range keys from in-memory index tree' (duration: 362.48072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.003122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.249825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:15:02.004273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.641096Z","time spent":"363.168002ms","remote":"127.0.0.1:47624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 "} {"level":"info","ts":"2026-08-09T13:15:02.004291Z","caller":"traceutil/trace.go:171","msg":"trace[1241983119] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8291; }","duration":"319.481294ms","start":"2026-08-09T13:15:01.6848Z","end":"2026-08-09T13:15:02.004282Z","steps":["trace[1241983119] 'range keys from in-memory index tree' (duration: 318.09692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.004332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:01.684779Z","time spent":"319.530046ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 "} {"level":"warn","ts":"2026-08-09T13:15:02.004374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.232885ms","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-08-09T13:15:02.00439Z","caller":"traceutil/trace.go:171","msg":"trace[1116558039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8292; }","duration":"106.267577ms","start":"2026-08-09T13:15:01.898116Z","end":"2026-08-09T13:15:02.004384Z","steps":["trace[1116558039] 'agreement among raft nodes before linearized reading' (duration: 106.162654ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:02.004454Z","caller":"traceutil/trace.go:171","msg":"trace[460313275] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"107.660992ms","start":"2026-08-09T13:15:01.896785Z","end":"2026-08-09T13:15:02.004446Z","steps":["trace[460313275] 'process raft request' (duration: 52.602632ms)","trace[460313275] 'compare' (duration: 54.345377ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:15:02.004467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.699972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-09T13:15:02.004493Z","caller":"traceutil/trace.go:171","msg":"trace[1830976639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8292; }","duration":"101.789245ms","start":"2026-08-09T13:15:01.902698Z","end":"2026-08-09T13:15:02.004487Z","steps":["trace[1830976639] 'agreement among raft nodes before linearized reading' (duration: 101.678392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.004561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.827874ms","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-08-09T13:15:02.004605Z","caller":"traceutil/trace.go:171","msg":"trace[1239982729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8292; }","duration":"100.913067ms","start":"2026-08-09T13:15:01.903683Z","end":"2026-08-09T13:15:02.004596Z","steps":["trace[1239982729] 'agreement among raft nodes before linearized reading' (duration: 100.810523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.004568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.829345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-09T13:15:02.004674Z","caller":"traceutil/trace.go:171","msg":"trace[1430764043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8292; }","duration":"100.9879ms","start":"2026-08-09T13:15:01.903679Z","end":"2026-08-09T13:15:02.004667Z","steps":["trace[1430764043] 'agreement among raft nodes before linearized reading' (duration: 100.794034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:02.004674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.145227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:02.005858Z","caller":"traceutil/trace.go:171","msg":"trace[6775729] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8291; }","duration":"277.314254ms","start":"2026-08-09T13:15:01.72851Z","end":"2026-08-09T13:15:02.005824Z","steps":["trace[6775729] 'range keys from in-memory index tree' (duration: 276.111105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:03.701841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.6385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:15:03.702195Z","caller":"traceutil/trace.go:171","msg":"trace[574513109] transaction","detail":"{read_only:false; response_revision:8301; number_of_response:1; }","duration":"143.237942ms","start":"2026-08-09T13:15:03.558915Z","end":"2026-08-09T13:15:03.702153Z","steps":["trace[574513109] 'process raft request' (duration: 30.192059ms)","trace[574513109] 'compare' (duration: 112.536417ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:15:03.702197Z","caller":"traceutil/trace.go:171","msg":"trace[1747122842] transaction","detail":"{read_only:false; response_revision:8302; number_of_response:1; }","duration":"135.639881ms","start":"2026-08-09T13:15:03.566511Z","end":"2026-08-09T13:15:03.702151Z","steps":["trace[1747122842] 'process raft request' (duration: 135.442175ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:04.150735Z","caller":"traceutil/trace.go:171","msg":"trace[1104678451] linearizableReadLoop","detail":"{readStateIndex:8592; appliedIndex:8591; }","duration":"109.358596ms","start":"2026-08-09T13:15:04.041347Z","end":"2026-08-09T13:15:04.150706Z","steps":["trace[1104678451] 'read index received' (duration: 109.239872ms)","trace[1104678451] 'applied index is now lower than readState.Index' (duration: 117.604µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:15:04.150816Z","caller":"traceutil/trace.go:171","msg":"trace[1352301026] transaction","detail":"{read_only:false; response_revision:8303; number_of_response:1; }","duration":"113.114736ms","start":"2026-08-09T13:15:04.037678Z","end":"2026-08-09T13:15:04.150792Z","steps":["trace[1352301026] 'process raft request' (duration: 112.879608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:04.15102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.659195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-09T13:15:04.151069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.086367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-09T13:15:04.151069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.888631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-09T13:15:04.151101Z","caller":"traceutil/trace.go:171","msg":"trace[439678610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8303; }","duration":"109.79704ms","start":"2026-08-09T13:15:04.041286Z","end":"2026-08-09T13:15:04.151083Z","steps":["trace[439678610] 'agreement among raft nodes before linearized reading' (duration: 109.527531ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:04.151121Z","caller":"traceutil/trace.go:171","msg":"trace[342821777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8303; }","duration":"109.013645ms","start":"2026-08-09T13:15:04.042095Z","end":"2026-08-09T13:15:04.151109Z","steps":["trace[342821777] 'agreement among raft nodes before linearized reading' (duration: 108.844789ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:04.15112Z","caller":"traceutil/trace.go:171","msg":"trace[900466063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8303; }","duration":"109.16379ms","start":"2026-08-09T13:15:04.04194Z","end":"2026-08-09T13:15:04.151104Z","steps":["trace[900466063] 'agreement among raft nodes before linearized reading' (duration: 108.927152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:04.15162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.075997ms","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-08-09T13:15:04.151901Z","caller":"traceutil/trace.go:171","msg":"trace[1276216693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8303; }","duration":"109.579553ms","start":"2026-08-09T13:15:04.042282Z","end":"2026-08-09T13:15:04.151862Z","steps":["trace[1276216693] 'agreement among raft nodes before linearized reading' (duration: 108.655213ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:28.381395Z","caller":"traceutil/trace.go:171","msg":"trace[2120509096] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"100.484074ms","start":"2026-08-09T13:15:28.280891Z","end":"2026-08-09T13:15:28.381376Z","steps":["trace[2120509096] 'process raft request' (duration: 100.240836ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:31.590627Z","caller":"traceutil/trace.go:171","msg":"trace[442854422] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"275.055411ms","start":"2026-08-09T13:15:31.315553Z","end":"2026-08-09T13:15:31.590608Z","steps":["trace[442854422] 'process raft request' (duration: 274.936838ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:31.882626Z","caller":"traceutil/trace.go:171","msg":"trace[1907701335] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"548.845333ms","start":"2026-08-09T13:15:31.333745Z","end":"2026-08-09T13:15:31.88259Z","steps":["trace[1907701335] 'process raft request' (duration: 548.448691ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:31.882821Z","caller":"traceutil/trace.go:171","msg":"trace[108668268] linearizableReadLoop","detail":"{readStateIndex:8799; appliedIndex:8798; }","duration":"515.163762ms","start":"2026-08-09T13:15:31.36762Z","end":"2026-08-09T13:15:31.882783Z","steps":["trace[108668268] 'read index received' (duration: 223.540774ms)","trace[108668268] 'applied index is now lower than readState.Index' (duration: 291.620208ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:15:31.882883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:31.333732Z","time spent":"549.004919ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:15:31.882926Z","caller":"traceutil/trace.go:171","msg":"trace[1856453458] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"306.308155ms","start":"2026-08-09T13:15:31.576592Z","end":"2026-08-09T13:15:31.8829Z","steps":["trace[1856453458] 'process raft request' (duration: 305.944363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:31.883098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:31.576572Z","time spent":"306.430199ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:15:31.883149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.501724ms","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-08-09T13:15:31.8832Z","caller":"traceutil/trace.go:171","msg":"trace[380106973] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8503; }","duration":"515.596746ms","start":"2026-08-09T13:15:31.367589Z","end":"2026-08-09T13:15:31.883186Z","steps":["trace[380106973] 'agreement among raft nodes before linearized reading' (duration: 515.351548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:31.883252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:15:31.367576Z","time spent":"515.661439ms","remote":"127.0.0.1:33102","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":"info","ts":"2026-08-09T13:15:31.883675Z","caller":"traceutil/trace.go:171","msg":"trace[683130609] transaction","detail":"{read_only:false; response_revision:8504; number_of_response:1; }","duration":"234.539974ms","start":"2026-08-09T13:15:31.649122Z","end":"2026-08-09T13:15:31.883662Z","steps":["trace[683130609] 'process raft request' (duration: 234.126771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:31.88371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.708968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-09T13:15:31.883763Z","caller":"traceutil/trace.go:171","msg":"trace[448487058] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8506; }","duration":"261.801231ms","start":"2026-08-09T13:15:31.621948Z","end":"2026-08-09T13:15:31.88375Z","steps":["trace[448487058] 'agreement among raft nodes before linearized reading' (duration: 261.626436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:31.883973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.785926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-09T13:15:31.884061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.370726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:15:31.884074Z","caller":"traceutil/trace.go:171","msg":"trace[369375984] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8506; }","duration":"203.932952ms","start":"2026-08-09T13:15:31.680112Z","end":"2026-08-09T13:15:31.884045Z","steps":["trace[369375984] 'agreement among raft nodes before linearized reading' (duration: 203.800107ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:31.884119Z","caller":"traceutil/trace.go:171","msg":"trace[1103869700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8506; }","duration":"193.432588ms","start":"2026-08-09T13:15:31.69066Z","end":"2026-08-09T13:15:31.884092Z","steps":["trace[1103869700] 'agreement among raft nodes before linearized reading' (duration: 193.373156ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:35.725873Z","caller":"traceutil/trace.go:171","msg":"trace[1631768853] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"120.891352ms","start":"2026-08-09T13:15:35.604953Z","end":"2026-08-09T13:15:35.725844Z","steps":["trace[1631768853] 'process raft request' (duration: 119.988134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:44.159734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.937413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-kdhhg\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-08-09T13:15:44.159839Z","caller":"traceutil/trace.go:171","msg":"trace[260172089] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-kdhhg; range_end:; response_count:1; response_revision:8607; }","duration":"211.089488ms","start":"2026-08-09T13:15:43.948726Z","end":"2026-08-09T13:15:44.159815Z","steps":["trace[260172089] 'range keys from in-memory index tree' (duration: 210.858852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:15:44.161741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.860597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:15:44.16204Z","caller":"traceutil/trace.go:171","msg":"trace[237842363] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"213.051331ms","start":"2026-08-09T13:15:43.948849Z","end":"2026-08-09T13:15:44.1619Z","steps":["trace[237842363] 'process raft request' (duration: 65.876073ms)","trace[237842363] 'compare' (duration: 145.071931ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:15:44.171144Z","caller":"traceutil/trace.go:171","msg":"trace[2067301109] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"209.636333ms","start":"2026-08-09T13:15:43.961491Z","end":"2026-08-09T13:15:44.171127Z","steps":["trace[2067301109] 'process raft request' (duration: 209.588931ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:44.171185Z","caller":"traceutil/trace.go:171","msg":"trace[310554840] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"218.315958ms","start":"2026-08-09T13:15:43.952852Z","end":"2026-08-09T13:15:44.171168Z","steps":["trace[310554840] 'process raft request' (duration: 218.139022ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:44.171239Z","caller":"traceutil/trace.go:171","msg":"trace[990109524] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"208.305351ms","start":"2026-08-09T13:15:43.962915Z","end":"2026-08-09T13:15:44.17122Z","steps":["trace[990109524] 'process raft request' (duration: 208.187877ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:44.351758Z","caller":"traceutil/trace.go:171","msg":"trace[48512419] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"183.817662ms","start":"2026-08-09T13:15:44.167408Z","end":"2026-08-09T13:15:44.351226Z","steps":["trace[48512419] 'process raft request' (duration: 174.764074ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:15:44.953482Z","caller":"traceutil/trace.go:171","msg":"trace[408523956] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"187.176829ms","start":"2026-08-09T13:15:44.76626Z","end":"2026-08-09T13:15:44.953437Z","steps":["trace[408523956] 'process raft request' (duration: 171.344366ms)","trace[408523956] 'compare' (duration: 15.386269ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:15:44.974765Z","caller":"traceutil/trace.go:171","msg":"trace[1790156776] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"173.171764ms","start":"2026-08-09T13:15:44.801558Z","end":"2026-08-09T13:15:44.97473Z","steps":["trace[1790156776] 'process raft request' (duration: 173.005998ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:16:02.595004Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002387.wal"} {"level":"warn","ts":"2026-08-09T13:17:00.810084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.759904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:17:00.810416Z","caller":"traceutil/trace.go:171","msg":"trace[60817746] linearizableReadLoop","detail":"{readStateIndex:9685; appliedIndex:9683; }","duration":"119.899425ms","start":"2026-08-09T13:17:00.690492Z","end":"2026-08-09T13:17:00.810391Z","steps":["trace[60817746] 'read index received' (duration: 31.721µs)","trace[60817746] 'applied index is now lower than readState.Index' (duration: 119.866294ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:17:00.810467Z","caller":"traceutil/trace.go:171","msg":"trace[313170671] transaction","detail":"{read_only:false; response_revision:9361; number_of_response:1; }","duration":"368.985317ms","start":"2026-08-09T13:17:00.441454Z","end":"2026-08-09T13:17:00.81044Z","steps":["trace[313170671] 'process raft request' (duration: 45.770788ms)","trace[313170671] 'compare' (duration: 322.153304ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:17:00.81052Z","caller":"traceutil/trace.go:171","msg":"trace[1885395666] transaction","detail":"{read_only:false; response_revision:9362; number_of_response:1; }","duration":"368.395008ms","start":"2026-08-09T13:17:00.442065Z","end":"2026-08-09T13:17:00.81046Z","steps":["trace[1885395666] 'process raft request' (duration: 368.14645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:17:00.810623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:17:00.441418Z","time spent":"369.122941ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:17:00.810754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:17:00.442037Z","time spent":"368.586984ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:17:00.810861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.093041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:17:00.811071Z","caller":"traceutil/trace.go:171","msg":"trace[1162493241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9362; }","duration":"120.598218ms","start":"2026-08-09T13:17:00.690437Z","end":"2026-08-09T13:17:00.811035Z","steps":["trace[1162493241] 'agreement among raft nodes before linearized reading' (duration: 120.025699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:17:01.024476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.800932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:17:01.024657Z","caller":"traceutil/trace.go:171","msg":"trace[1913815928] linearizableReadLoop","detail":"{readStateIndex:9686; appliedIndex:9685; }","duration":"110.635821ms","start":"2026-08-09T13:17:00.913991Z","end":"2026-08-09T13:17:01.024627Z","steps":["trace[1913815928] 'read index received' (duration: 8.455086ms)","trace[1913815928] 'applied index is now lower than readState.Index' (duration: 102.178275ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:17:01.024673Z","caller":"traceutil/trace.go:171","msg":"trace[1846211653] transaction","detail":"{read_only:false; response_revision:9363; number_of_response:1; }","duration":"125.106275ms","start":"2026-08-09T13:17:00.899533Z","end":"2026-08-09T13:17:01.024639Z","steps":["trace[1846211653] 'process raft request' (duration: 22.975382ms)","trace[1846211653] 'compare' (duration: 101.574115ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:17:01.024854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.854569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-09T13:17:01.024925Z","caller":"traceutil/trace.go:171","msg":"trace[1043036729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9363; }","duration":"110.988633ms","start":"2026-08-09T13:17:00.913921Z","end":"2026-08-09T13:17:01.02491Z","steps":["trace[1043036729] 'agreement among raft nodes before linearized reading' (duration: 110.788447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:17:01.025062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.089405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-09T13:17:01.025158Z","caller":"traceutil/trace.go:171","msg":"trace[1077506331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9363; }","duration":"107.22371ms","start":"2026-08-09T13:17:00.917912Z","end":"2026-08-09T13:17:01.025136Z","steps":["trace[1077506331] 'agreement among raft nodes before linearized reading' (duration: 106.955781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:17:01.025159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.646779ms","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-08-09T13:17:01.025235Z","caller":"traceutil/trace.go:171","msg":"trace[656990341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9363; }","duration":"109.771673ms","start":"2026-08-09T13:17:00.915444Z","end":"2026-08-09T13:17:01.025216Z","steps":["trace[656990341] 'agreement among raft nodes before linearized reading' (duration: 109.528065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:17:01.025516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.102787ms","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-08-09T13:17:01.025603Z","caller":"traceutil/trace.go:171","msg":"trace[1645877652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9363; }","duration":"111.17454ms","start":"2026-08-09T13:17:00.914383Z","end":"2026-08-09T13:17:01.025557Z","steps":["trace[1645877652] 'agreement among raft nodes before linearized reading' (duration: 110.446686ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:17:41.271279Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-09T13:17:41.279471Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-09T13:17:41.279598Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-09T13:19:51.633771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8237} {"level":"info","ts":"2026-08-09T13:19:51.715536Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8237,"took":"79.653244ms","hash":527882683,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":24514560,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-09T13:19:51.71562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":527882683,"revision":8237,"compact-revision":6043} {"level":"info","ts":"2026-08-09T13:22:39.006182Z","caller":"traceutil/trace.go:171","msg":"trace[1377845097] transaction","detail":"{read_only:false; response_revision:11796; number_of_response:1; }","duration":"107.585707ms","start":"2026-08-09T13:22:38.89857Z","end":"2026-08-09T13:22:39.006156Z","steps":["trace[1377845097] 'process raft request' (duration: 107.459893ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:22:39.006244Z","caller":"traceutil/trace.go:171","msg":"trace[1742206555] transaction","detail":"{read_only:false; response_revision:11795; number_of_response:1; }","duration":"109.143347ms","start":"2026-08-09T13:22:38.897069Z","end":"2026-08-09T13:22:39.006212Z","steps":["trace[1742206555] 'process raft request' (duration: 40.501104ms)","trace[1742206555] 'compare' (duration: 68.176938ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:24:51.639489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10462} {"level":"info","ts":"2026-08-09T13:24:51.719143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10462,"took":"77.323134ms","hash":4213106157,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":25612288,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-09T13:24:51.719269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4213106157,"revision":10462,"compact-revision":8237} {"level":"info","ts":"2026-08-09T13:28:45.207146Z","caller":"traceutil/trace.go:171","msg":"trace[2141582671] linearizableReadLoop","detail":"{readStateIndex:14761; appliedIndex:14760; }","duration":"176.031277ms","start":"2026-08-09T13:28:45.031087Z","end":"2026-08-09T13:28:45.207119Z","steps":["trace[2141582671] 'read index received' (duration: 175.781749ms)","trace[2141582671] 'applied index is now lower than readState.Index' (duration: 248.388µs)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:28:45.20741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.297906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m485\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-09T13:28:45.207443Z","caller":"traceutil/trace.go:171","msg":"trace[1605763669] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m485; range_end:; response_count:1; response_revision:14273; }","duration":"176.385149ms","start":"2026-08-09T13:28:45.031047Z","end":"2026-08-09T13:28:45.207432Z","steps":["trace[1605763669] 'agreement among raft nodes before linearized reading' (duration: 176.189432ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:28:45.207825Z","caller":"traceutil/trace.go:171","msg":"trace[19563049] transaction","detail":"{read_only:false; response_revision:14273; number_of_response:1; }","duration":"204.193236ms","start":"2026-08-09T13:28:45.00362Z","end":"2026-08-09T13:28:45.207813Z","steps":["trace[19563049] 'process raft request' (duration: 203.330718ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:28:48.069127Z","caller":"traceutil/trace.go:171","msg":"trace[2019604761] transaction","detail":"{read_only:false; response_revision:14288; number_of_response:1; }","duration":"129.930676ms","start":"2026-08-09T13:28:47.93917Z","end":"2026-08-09T13:28:48.069101Z","steps":["trace[2019604761] 'process raft request' (duration: 129.753241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:29:07.670479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.841539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m485\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-09T13:29:07.67055Z","caller":"traceutil/trace.go:171","msg":"trace[2141045254] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m485; range_end:; response_count:1; response_revision:14395; }","duration":"274.954823ms","start":"2026-08-09T13:29:07.395581Z","end":"2026-08-09T13:29:07.670536Z","steps":["trace[2141045254] 'range keys from in-memory index tree' (duration: 274.680315ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:29:15.821563Z","caller":"traceutil/trace.go:171","msg":"trace[233402979] transaction","detail":"{read_only:false; response_revision:14436; number_of_response:1; }","duration":"114.300377ms","start":"2026-08-09T13:29:15.7072Z","end":"2026-08-09T13:29:15.8215Z","steps":["trace[233402979] 'process raft request' (duration: 114.016558ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:29:45.330716Z","caller":"traceutil/trace.go:171","msg":"trace[908131408] transaction","detail":"{read_only:false; response_revision:14796; number_of_response:1; }","duration":"208.918987ms","start":"2026-08-09T13:29:45.12177Z","end":"2026-08-09T13:29:45.330689Z","steps":["trace[908131408] 'process raft request' (duration: 208.765102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:29:45.581402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.303325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:29:45.581716Z","caller":"traceutil/trace.go:171","msg":"trace[485463446] transaction","detail":"{read_only:false; response_revision:14798; number_of_response:1; }","duration":"120.647619ms","start":"2026-08-09T13:29:45.461049Z","end":"2026-08-09T13:29:45.581696Z","steps":["trace[485463446] 'process raft request' (duration: 120.461603ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:29:45.581838Z","caller":"traceutil/trace.go:171","msg":"trace[2122932618] linearizableReadLoop","detail":"{readStateIndex:15308; appliedIndex:15306; }","duration":"393.568617ms","start":"2026-08-09T13:29:45.1882Z","end":"2026-08-09T13:29:45.581768Z","steps":["trace[2122932618] 'read index received' (duration: 142.388603ms)","trace[2122932618] 'applied index is now lower than readState.Index' (duration: 251.173674ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:29:45.581974Z","caller":"traceutil/trace.go:171","msg":"trace[1011912586] transaction","detail":"{read_only:false; response_revision:14797; number_of_response:1; }","duration":"458.838291ms","start":"2026-08-09T13:29:45.123101Z","end":"2026-08-09T13:29:45.58194Z","steps":["trace[1011912586] 'process raft request' (duration: 344.902676ms)","trace[1011912586] 'compare' (duration: 113.113619ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:29:45.582092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.882568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-09T13:29:45.582153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:29:45.123081Z","time spent":"458.974375ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:29:45.582167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.843969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-09T13:29:45.582166Z","caller":"traceutil/trace.go:171","msg":"trace[346208973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14798; }","duration":"394.002442ms","start":"2026-08-09T13:29:45.188146Z","end":"2026-08-09T13:29:45.582149Z","steps":["trace[346208973] 'agreement among raft nodes before linearized reading' (duration: 393.802125ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:29:45.582209Z","caller":"traceutil/trace.go:171","msg":"trace[2101012830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14798; }","duration":"384.915652ms","start":"2026-08-09T13:29:45.19728Z","end":"2026-08-09T13:29:45.582196Z","steps":["trace[2101012830] 'agreement among raft nodes before linearized reading' (duration: 384.708825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:29:45.582215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:29:45.188124Z","time spent":"394.082614ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-09T13:29:45.582213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.614312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26c5ad3e-ad23-4de9-8d8d-924dc1c0fdff\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-08-09T13:29:45.582248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:29:45.197274Z","time spent":"384.965033ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-09T13:29:45.582269Z","caller":"traceutil/trace.go:171","msg":"trace[1518256912] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26c5ad3e-ad23-4de9-8d8d-924dc1c0fdff; range_end:; response_count:1; response_revision:14798; }","duration":"190.704835ms","start":"2026-08-09T13:29:45.391548Z","end":"2026-08-09T13:29:45.582253Z","steps":["trace[1518256912] 'agreement among raft nodes before linearized reading' (duration: 190.478008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:29:45.582276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.065148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26c5ad3e-ad23-4de9-8d8d-924dc1c0fdff\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-09T13:29:45.582383Z","caller":"traceutil/trace.go:171","msg":"trace[1938728023] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26c5ad3e-ad23-4de9-8d8d-924dc1c0fdff; range_end:; response_count:1; response_revision:14798; }","duration":"318.193563ms","start":"2026-08-09T13:29:45.264152Z","end":"2026-08-09T13:29:45.582345Z","steps":["trace[1938728023] 'agreement among raft nodes before linearized reading' (duration: 317.992506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:29:45.582422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:29:45.264131Z","time spent":"318.281665ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3660,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26c5ad3e-ad23-4de9-8d8d-924dc1c0fdff\" "} {"level":"info","ts":"2026-08-09T13:29:51.645949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12688} {"level":"info","ts":"2026-08-09T13:29:51.721433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12688,"took":"72.047415ms","hash":690334462,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":28844032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-09T13:29:51.721509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":690334462,"revision":12688,"compact-revision":10462} {"level":"warn","ts":"2026-08-09T13:30:37.520354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.429896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-09T13:30:37.520803Z","caller":"traceutil/trace.go:171","msg":"trace[1685405055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15430; }","duration":"132.909571ms","start":"2026-08-09T13:30:37.387859Z","end":"2026-08-09T13:30:37.520769Z","steps":["trace[1685405055] 'range keys from in-memory index tree' (duration: 132.136806ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:31:02.663981Z","caller":"traceutil/trace.go:171","msg":"trace[305838494] transaction","detail":"{read_only:false; response_revision:15564; number_of_response:1; }","duration":"244.438855ms","start":"2026-08-09T13:31:02.419512Z","end":"2026-08-09T13:31:02.663951Z","steps":["trace[305838494] 'process raft request' (duration: 234.556527ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:31:02.738091Z","caller":"traceutil/trace.go:171","msg":"trace[1012248673] linearizableReadLoop","detail":"{readStateIndex:16098; appliedIndex:16096; }","duration":"207.147326ms","start":"2026-08-09T13:31:02.530902Z","end":"2026-08-09T13:31:02.738049Z","steps":["trace[1012248673] 'read index received' (duration: 123.182068ms)","trace[1012248673] 'applied index is now lower than readState.Index' (duration: 83.963638ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:31:02.73857Z","caller":"traceutil/trace.go:171","msg":"trace[1400993461] transaction","detail":"{read_only:false; response_revision:15565; number_of_response:1; }","duration":"314.081292ms","start":"2026-08-09T13:31:02.424463Z","end":"2026-08-09T13:31:02.738544Z","steps":["trace[1400993461] 'process raft request' (duration: 313.38545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:31:02.738617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.684094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:31:02.738747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:31:02.424437Z","time spent":"314.190406ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:31:02.738749Z","caller":"traceutil/trace.go:171","msg":"trace[1869050755] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:15565; }","duration":"207.86489ms","start":"2026-08-09T13:31:02.530862Z","end":"2026-08-09T13:31:02.738726Z","steps":["trace[1869050755] 'agreement among raft nodes before linearized reading' (duration: 207.659053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:31:02.890905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.063575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m485\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-09T13:31:02.890998Z","caller":"traceutil/trace.go:171","msg":"trace[1386263888] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m485; range_end:; response_count:1; response_revision:15565; }","duration":"114.21634ms","start":"2026-08-09T13:31:02.776759Z","end":"2026-08-09T13:31:02.890975Z","steps":["trace[1386263888] 'range keys from in-memory index tree' (duration: 113.877999ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:34:51.651951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14859} {"level":"info","ts":"2026-08-09T13:34:51.742425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14859,"took":"87.594303ms","hash":2082466666,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":30425088,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-09T13:34:51.742506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2082466666,"revision":14859,"compact-revision":12688} {"level":"info","ts":"2026-08-09T13:37:17.248887Z","caller":"traceutil/trace.go:171","msg":"trace[1173301076] linearizableReadLoop","detail":"{readStateIndex:18911; appliedIndex:18910; }","duration":"189.205999ms","start":"2026-08-09T13:37:17.059657Z","end":"2026-08-09T13:37:17.248863Z","steps":["trace[1173301076] 'read index received' (duration: 189.061135ms)","trace[1173301076] 'applied index is now lower than readState.Index' (duration: 143.984µs)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:37:17.249135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.469088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hwzmt\" ","response":"range_response_count:1 size:20747"} {"level":"info","ts":"2026-08-09T13:37:17.24918Z","caller":"traceutil/trace.go:171","msg":"trace[756258673] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hwzmt; range_end:; response_count:1; response_revision:18286; }","duration":"189.560951ms","start":"2026-08-09T13:37:17.059605Z","end":"2026-08-09T13:37:17.249166Z","steps":["trace[756258673] 'agreement among raft nodes before linearized reading' (duration: 189.402826ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:37:17.249387Z","caller":"traceutil/trace.go:171","msg":"trace[1876834011] transaction","detail":"{read_only:false; response_revision:18286; number_of_response:1; }","duration":"291.015682ms","start":"2026-08-09T13:37:16.958361Z","end":"2026-08-09T13:37:17.249376Z","steps":["trace[1876834011] 'process raft request' (duration: 290.365091ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:37:17.780165Z","caller":"traceutil/trace.go:171","msg":"trace[395325868] transaction","detail":"{read_only:false; response_revision:18291; number_of_response:1; }","duration":"104.712406ms","start":"2026-08-09T13:37:17.675409Z","end":"2026-08-09T13:37:17.780121Z","steps":["trace[395325868] 'process raft request' (duration: 104.464008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:37:18.397536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440204185289801801,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T13:37:18.497208Z","caller":"traceutil/trace.go:171","msg":"trace[46207317] transaction","detail":"{read_only:false; response_revision:18294; number_of_response:1; }","duration":"637.065458ms","start":"2026-08-09T13:37:17.860107Z","end":"2026-08-09T13:37:18.497173Z","steps":["trace[46207317] 'process raft request' (duration: 609.652774ms)","trace[46207317] 'compare' (duration: 27.097744ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:37:18.497817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:37:17.860078Z","time spent":"637.218543ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:37:18.515808Z","caller":"traceutil/trace.go:171","msg":"trace[1008717014] linearizableReadLoop","detail":"{readStateIndex:18920; appliedIndex:18918; }","duration":"618.446788ms","start":"2026-08-09T13:37:17.89729Z","end":"2026-08-09T13:37:18.515737Z","steps":["trace[1008717014] 'read index received' (duration: 572.486066ms)","trace[1008717014] 'applied index is now lower than readState.Index' (duration: 45.959282ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:37:18.515894Z","caller":"traceutil/trace.go:171","msg":"trace[1088871993] transaction","detail":"{read_only:false; response_revision:18295; number_of_response:1; }","duration":"594.225036ms","start":"2026-08-09T13:37:17.921626Z","end":"2026-08-09T13:37:18.515851Z","steps":["trace[1088871993] 'process raft request' (duration: 593.896346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:37:18.516053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.716196ms","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-08-09T13:37:18.516119Z","caller":"traceutil/trace.go:171","msg":"trace[1217613344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18295; }","duration":"618.879402ms","start":"2026-08-09T13:37:17.897228Z","end":"2026-08-09T13:37:18.516108Z","steps":["trace[1217613344] 'agreement among raft nodes before linearized reading' (duration: 618.677755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:37:18.516152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:37:17.921602Z","time spent":"594.441033ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:37:18.516173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:37:17.897204Z","time spent":"618.959284ms","remote":"127.0.0.1:33204","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-08-09T13:37:18.516193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.058016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-09T13:37:18.516311Z","caller":"traceutil/trace.go:171","msg":"trace[1790890591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18295; }","duration":"614.234172ms","start":"2026-08-09T13:37:17.902047Z","end":"2026-08-09T13:37:18.516282Z","steps":["trace[1790890591] 'agreement among raft nodes before linearized reading' (duration: 613.913402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:37:18.516339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.573604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-lmdqg\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-08-09T13:37:18.516371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:37:17.902028Z","time spent":"614.331125ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-09T13:37:18.516418Z","caller":"traceutil/trace.go:171","msg":"trace[1898675203] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-lmdqg; range_end:; response_count:1; response_revision:18295; }","duration":"172.696537ms","start":"2026-08-09T13:37:18.343705Z","end":"2026-08-09T13:37:18.516401Z","steps":["trace[1898675203] 'agreement among raft nodes before linearized reading' (duration: 172.563753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:37:18.516417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.333472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-09T13:37:18.516226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.328905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-09T13:37:18.516578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.175938ms","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-08-09T13:37:18.516602Z","caller":"traceutil/trace.go:171","msg":"trace[747328764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18295; }","duration":"613.236559ms","start":"2026-08-09T13:37:17.90336Z","end":"2026-08-09T13:37:18.516596Z","steps":["trace[747328764] 'agreement among raft nodes before linearized reading' (duration: 613.158257ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:37:18.516578Z","caller":"traceutil/trace.go:171","msg":"trace[1152682500] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:18295; }","duration":"528.524728ms","start":"2026-08-09T13:37:17.988013Z","end":"2026-08-09T13:37:18.516538Z","steps":["trace[1152682500] 'agreement among raft nodes before linearized reading' (duration: 528.285471ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:37:18.516614Z","caller":"traceutil/trace.go:171","msg":"trace[907640192] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:18295; }","duration":"471.753498ms","start":"2026-08-09T13:37:18.044841Z","end":"2026-08-09T13:37:18.516595Z","steps":["trace[907640192] 'agreement among raft nodes before linearized reading' (duration: 471.241972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:37:18.516646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:37:17.90335Z","time spent":"613.279841ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-09T13:37:18.516681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:37:17.98798Z","time spent":"528.667093ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":76,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-09T13:37:18.516665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:37:18.044791Z","time spent":"471.857762ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"info","ts":"2026-08-09T13:39:06.12958Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-09T13:39:06.139276Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-09T13:39:06.139524Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-09T13:39:51.659949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17309} {"level":"info","ts":"2026-08-09T13:39:51.768112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17309,"took":"103.791814ms","hash":4071350691,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":34033664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-09T13:39:51.768183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4071350691,"revision":17309,"compact-revision":14859} {"level":"info","ts":"2026-08-09T13:41:59.245392Z","caller":"traceutil/trace.go:171","msg":"trace[553057325] transaction","detail":"{read_only:false; response_revision:20602; number_of_response:1; }","duration":"182.938449ms","start":"2026-08-09T13:41:59.062431Z","end":"2026-08-09T13:41:59.245369Z","steps":["trace[553057325] 'process raft request' (duration: 182.781804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:43:14.798243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.462243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-09T13:43:14.79841Z","caller":"traceutil/trace.go:171","msg":"trace[204463591] transaction","detail":"{read_only:false; response_revision:21030; number_of_response:1; }","duration":"177.859279ms","start":"2026-08-09T13:43:14.620531Z","end":"2026-08-09T13:43:14.79839Z","steps":["trace[204463591] 'process raft request' (duration: 46.138058ms)","trace[204463591] 'compare' (duration: 131.347829ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:43:14.807704Z","caller":"traceutil/trace.go:171","msg":"trace[1814625704] linearizableReadLoop","detail":"{readStateIndex:21748; appliedIndex:21747; }","duration":"117.816952ms","start":"2026-08-09T13:43:14.689844Z","end":"2026-08-09T13:43:14.807661Z","steps":["trace[1814625704] 'read index received' (duration: 117.531953ms)","trace[1814625704] 'applied index is now lower than readState.Index' (duration: 283.729µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:43:14.808054Z","caller":"traceutil/trace.go:171","msg":"trace[1297986879] transaction","detail":"{read_only:false; response_revision:21031; number_of_response:1; }","duration":"187.298927ms","start":"2026-08-09T13:43:14.62074Z","end":"2026-08-09T13:43:14.808039Z","steps":["trace[1297986879] 'process raft request' (duration: 186.746789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:43:14.808684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.808833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-09T13:43:14.808736Z","caller":"traceutil/trace.go:171","msg":"trace[1531165797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21031; }","duration":"118.909486ms","start":"2026-08-09T13:43:14.689808Z","end":"2026-08-09T13:43:14.808718Z","steps":["trace[1531165797] 'agreement among raft nodes before linearized reading' (duration: 118.805083ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:44:12.904297Z","caller":"traceutil/trace.go:171","msg":"trace[788302990] transaction","detail":"{read_only:false; response_revision:21327; number_of_response:1; }","duration":"165.022932ms","start":"2026-08-09T13:44:12.739249Z","end":"2026-08-09T13:44:12.904272Z","steps":["trace[788302990] 'process raft request' (duration: 164.859827ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:44:13.249129Z","caller":"traceutil/trace.go:171","msg":"trace[963791] transaction","detail":"{read_only:false; response_revision:21328; number_of_response:1; }","duration":"508.780752ms","start":"2026-08-09T13:44:12.740324Z","end":"2026-08-09T13:44:13.249105Z","steps":["trace[963791] 'process raft request' (duration: 508.600236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:44:13.249267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:44:12.74031Z","time spent":"508.887145ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:44:13.249345Z","caller":"traceutil/trace.go:171","msg":"trace[1313491944] linearizableReadLoop","detail":"{readStateIndex:22058; appliedIndex:22055; }","duration":"485.483296ms","start":"2026-08-09T13:44:12.763815Z","end":"2026-08-09T13:44:13.249298Z","steps":["trace[1313491944] 'read index received' (duration: 140.416165ms)","trace[1313491944] 'applied index is now lower than readState.Index' (duration: 345.066021ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:44:13.24951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.679182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-09T13:44:13.249552Z","caller":"traceutil/trace.go:171","msg":"trace[415858678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21329; }","duration":"485.755624ms","start":"2026-08-09T13:44:12.763786Z","end":"2026-08-09T13:44:13.249542Z","steps":["trace[415858678] 'agreement among raft nodes before linearized reading' (duration: 485.650211ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:44:13.249486Z","caller":"traceutil/trace.go:171","msg":"trace[1917197548] transaction","detail":"{read_only:false; response_revision:21329; number_of_response:1; }","duration":"509.004879ms","start":"2026-08-09T13:44:12.740387Z","end":"2026-08-09T13:44:13.249392Z","steps":["trace[1917197548] 'process raft request' (duration: 508.675008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:44:13.249726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:44:12.740362Z","time spent":"509.278127ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:44:13.249581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:44:12.763776Z","time spent":"485.798755ms","remote":"127.0.0.1:32898","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-08-09T13:44:13.421641Z","caller":"traceutil/trace.go:171","msg":"trace[1126121222] transaction","detail":"{read_only:false; response_revision:21330; number_of_response:1; }","duration":"420.575706ms","start":"2026-08-09T13:44:13.001028Z","end":"2026-08-09T13:44:13.421604Z","steps":["trace[1126121222] 'process raft request' (duration: 393.888673ms)","trace[1126121222] 'compare' (duration: 26.444845ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:44:13.421808Z","caller":"traceutil/trace.go:171","msg":"trace[602943946] transaction","detail":"{read_only:false; response_revision:21331; number_of_response:1; }","duration":"415.189777ms","start":"2026-08-09T13:44:13.006598Z","end":"2026-08-09T13:44:13.421788Z","steps":["trace[602943946] 'process raft request' (duration: 414.905958ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:44:13.421872Z","caller":"traceutil/trace.go:171","msg":"trace[823138887] linearizableReadLoop","detail":"{readStateIndex:22060; appliedIndex:22058; }","duration":"172.451808ms","start":"2026-08-09T13:44:13.24941Z","end":"2026-08-09T13:44:13.421862Z","steps":["trace[823138887] 'read index received' (duration: 145.439135ms)","trace[823138887] 'applied index is now lower than readState.Index' (duration: 27.011073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:44:13.421914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:44:13.00658Z","time spent":"415.289199ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:44:13.421947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.916403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-09T13:44:13.421986Z","caller":"traceutil/trace.go:171","msg":"trace[1271737572] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:21332; }","duration":"198.957784ms","start":"2026-08-09T13:44:13.223018Z","end":"2026-08-09T13:44:13.421975Z","steps":["trace[1271737572] 'agreement among raft nodes before linearized reading' (duration: 198.887062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:44:13.421813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:44:13.001Z","time spent":"420.732351ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:44:51.666655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19610} {"level":"info","ts":"2026-08-09T13:44:51.782254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19610,"took":"112.564306ms","hash":2239885937,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":28827648,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-09T13:44:51.782382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2239885937,"revision":19610,"compact-revision":17309} {"level":"info","ts":"2026-08-09T13:49:51.672513Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21765} {"level":"info","ts":"2026-08-09T13:49:51.773045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21765,"took":"97.561776ms","hash":592934055,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":43438080,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-09T13:49:51.773938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":592934055,"revision":21765,"compact-revision":19610} {"level":"info","ts":"2026-08-09T13:54:51.679171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24836} {"level":"info","ts":"2026-08-09T13:54:51.840262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24836,"took":"156.868511ms","hash":2264323922,"current-db-size-bytes":59006976,"current-db-size":"59 MB","current-db-size-in-use-bytes":30576640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-09T13:54:51.840332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2264323922,"revision":24836,"compact-revision":21765} {"level":"info","ts":"2026-08-09T13:56:48.44487Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000070dd.wal"}