{"level":"warn","ts":"2026-04-03T02:18:07.7464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T02:18:07.746471Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-04-03T02:18:07.746529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T02:18:07.746537Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-03T02:18:07.746554Z","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-04-03T02:18:07.747168Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-03T02:18:07.747255Z","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":8,"max-cpu-available":8,"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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-04-03T02:18:07.750925Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.500159ms"} {"level":"info","ts":"2026-04-03T02:18:07.758272Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-03T02:18:07.758337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T02:18:07.758359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-03T02:18:07.758369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T02:18:07.758385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-03T02:18:07.758419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-03T02:18:07.763866Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T02:18:07.765061Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T02:18:07.766223Z","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-04-03T02:18:07.768057Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T02:18:07.768641Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T02:18:07.768819Z","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-04-03T02:18:07.768897Z","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-04-03T02:18:07.768934Z","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-04-03T02:18:07.770364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-03T02:18:07.770635Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-03T02:18:07.774621Z","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-04-03T02:18:07.774757Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-03T02:18:07.774831Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-03T02:18:07.775137Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T02:18:07.77521Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T02:18:08.059135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T02:18:08.059199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T02:18:08.059236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-03T02:18:08.059307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-03T02:18:08.059328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-03T02:18:08.059468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-03T02:18:08.059543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-03T02:18:08.060608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T02:18:08.060799Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:18:08.063929Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-03T02:18:08.064038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:18:08.064444Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:18:08.065817Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:18:08.065955Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:18:08.065999Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:18:08.066028Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T02:18:08.066045Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T02:18:08.066344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T02:18:42.63063Z","caller":"traceutil/trace.go:171","msg":"trace[1831976688] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"282.193962ms","start":"2026-04-03T02:18:42.34841Z","end":"2026-04-03T02:18:42.630604Z","steps":["trace[1831976688] 'read index received' (duration: 281.342477ms)","trace[1831976688] 'applied index is now lower than readState.Index' (duration: 850.415µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:18:42.63076Z","caller":"traceutil/trace.go:171","msg":"trace[1686999183] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"340.947956ms","start":"2026-04-03T02:18:42.289768Z","end":"2026-04-03T02:18:42.630716Z","steps":["trace[1686999183] 'process raft request' (duration: 340.231402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:42.630903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.465433ms","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-04-03T02:18:42.630976Z","caller":"traceutil/trace.go:171","msg":"trace[975603743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"282.589553ms","start":"2026-04-03T02:18:42.348376Z","end":"2026-04-03T02:18:42.630965Z","steps":["trace[975603743] 'agreement among raft nodes before linearized reading' (duration: 282.381892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:42.631035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.015907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zmwcv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-03T02:18:42.631128Z","caller":"traceutil/trace.go:171","msg":"trace[217820095] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zmwcv; range_end:; response_count:1; response_revision:613; }","duration":"255.136577ms","start":"2026-04-03T02:18:42.375963Z","end":"2026-04-03T02:18:42.6311Z","steps":["trace[217820095] 'agreement among raft nodes before linearized reading' (duration: 254.926396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:42.631031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.324931ms","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-04-03T02:18:42.631263Z","caller":"traceutil/trace.go:171","msg":"trace[1028267186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"280.566472ms","start":"2026-04-03T02:18:42.35067Z","end":"2026-04-03T02:18:42.631236Z","steps":["trace[1028267186] 'agreement among raft nodes before linearized reading' (duration: 280.16398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:42.633589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:42.289731Z","time spent":"342.035163ms","remote":"127.0.0.1:34296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:18:43.129054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.689887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:18:43.129336Z","caller":"traceutil/trace.go:171","msg":"trace[807464443] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"491.520071ms","start":"2026-04-03T02:18:42.637745Z","end":"2026-04-03T02:18:43.129265Z","steps":["trace[807464443] 'process raft request' (duration: 280.323771ms)","trace[807464443] 'compare' (duration: 210.548327ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:18:43.129422Z","caller":"traceutil/trace.go:171","msg":"trace[156619867] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"443.147685ms","start":"2026-04-03T02:18:42.686251Z","end":"2026-04-03T02:18:43.129399Z","steps":["trace[156619867] 'process raft request' (duration: 443.016424ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:18:43.129435Z","caller":"traceutil/trace.go:171","msg":"trace[994190365] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"482.025798ms","start":"2026-04-03T02:18:42.647365Z","end":"2026-04-03T02:18:43.129391Z","steps":["trace[994190365] 'process raft request' (duration: 481.817917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:18:43.129496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:42.637725Z","time spent":"491.711172ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:18:43.129511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:42.686226Z","time spent":"443.241326ms","remote":"127.0.0.1:34696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T02:18:43.129602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:18:42.647353Z","time spent":"482.153428ms","remote":"127.0.0.1:34368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:18:59.965765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.419951ms","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-04-03T02:18:59.965857Z","caller":"traceutil/trace.go:171","msg":"trace[1689701020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"122.564421ms","start":"2026-04-03T02:18:59.843272Z","end":"2026-04-03T02:18:59.965837Z","steps":["trace[1689701020] 'range keys from in-memory index tree' (duration: 122.168499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:19:32.152937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.177891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-03T02:19:32.153029Z","caller":"traceutil/trace.go:171","msg":"trace[2074643597] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1193; }","duration":"150.323486ms","start":"2026-04-03T02:19:32.002687Z","end":"2026-04-03T02:19:32.153011Z","steps":["trace[2074643597] 'range keys from in-memory index tree' (duration: 150.051815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:19.14225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.836464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d0b1eca2-98b0-4f3d-b8e7-79a00b52c92c\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-03T02:20:19.142343Z","caller":"traceutil/trace.go:171","msg":"trace[400219813] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d0b1eca2-98b0-4f3d-b8e7-79a00b52c92c; range_end:; response_count:1; response_revision:1850; }","duration":"252.028728ms","start":"2026-04-03T02:20:18.890291Z","end":"2026-04-03T02:20:19.142319Z","steps":["trace[400219813] 'range keys from in-memory index tree' (duration: 251.613839ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:19.146924Z","caller":"traceutil/trace.go:171","msg":"trace[2051110122] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"156.456385ms","start":"2026-04-03T02:20:18.990445Z","end":"2026-04-03T02:20:19.146902Z","steps":["trace[2051110122] 'read index received' (duration: 156.24651ms)","trace[2051110122] 'applied index is now lower than readState.Index' (duration: 208.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:19.146961Z","caller":"traceutil/trace.go:171","msg":"trace[1139012928] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"209.038667ms","start":"2026-04-03T02:20:18.937898Z","end":"2026-04-03T02:20:19.146937Z","steps":["trace[1139012928] 'process raft request' (duration: 208.865913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:19.14707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.626489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-03T02:20:19.147124Z","caller":"traceutil/trace.go:171","msg":"trace[1140875024] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"156.719551ms","start":"2026-04-03T02:20:18.99039Z","end":"2026-04-03T02:20:19.147109Z","steps":["trace[1140875024] 'agreement among raft nodes before linearized reading' (duration: 156.582648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:19.147172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.607276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-03T02:20:19.147226Z","caller":"traceutil/trace.go:171","msg":"trace[1852096432] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1851; }","duration":"137.709269ms","start":"2026-04-03T02:20:19.009506Z","end":"2026-04-03T02:20:19.147215Z","steps":["trace[1852096432] 'agreement among raft nodes before linearized reading' (duration: 137.534575ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:19.312648Z","caller":"traceutil/trace.go:171","msg":"trace[902017101] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"163.030311ms","start":"2026-04-03T02:20:19.149587Z","end":"2026-04-03T02:20:19.312617Z","steps":["trace[902017101] 'process raft request' (duration: 107.968171ms)","trace[902017101] 'compare' (duration: 54.805344ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:19.312975Z","caller":"traceutil/trace.go:171","msg":"trace[1295276824] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"161.165316ms","start":"2026-04-03T02:20:19.151777Z","end":"2026-04-03T02:20:19.312942Z","steps":["trace[1295276824] 'process raft request' (duration: 160.758555ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:19.31298Z","caller":"traceutil/trace.go:171","msg":"trace[692085206] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"160.820658ms","start":"2026-04-03T02:20:19.152115Z","end":"2026-04-03T02:20:19.312936Z","steps":["trace[692085206] 'process raft request' (duration: 160.664873ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:48.604353Z","caller":"traceutil/trace.go:171","msg":"trace[689026388] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2141; }","duration":"268.685461ms","start":"2026-04-03T02:20:48.335642Z","end":"2026-04-03T02:20:48.604327Z","steps":["trace[689026388] 'read index received' (duration: 262.137087ms)","trace[689026388] 'applied index is now lower than readState.Index' (duration: 6.546884ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:48.604478Z","caller":"traceutil/trace.go:171","msg":"trace[483447182] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"357.413187ms","start":"2026-04-03T02:20:48.247052Z","end":"2026-04-03T02:20:48.604466Z","steps":["trace[483447182] 'process raft request' (duration: 350.831252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:48.60458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:48.247037Z","time spent":"357.466867ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:48.604863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.199471ms","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":"warn","ts":"2026-04-03T02:20:48.6049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.295941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-03T02:20:48.604938Z","caller":"traceutil/trace.go:171","msg":"trace[1021980147] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2069; }","duration":"269.311953ms","start":"2026-04-03T02:20:48.33561Z","end":"2026-04-03T02:20:48.604922Z","steps":["trace[1021980147] 'agreement among raft nodes before linearized reading' (duration: 269.181781ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:48.604974Z","caller":"traceutil/trace.go:171","msg":"trace[820060483] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2069; }","duration":"262.414634ms","start":"2026-04-03T02:20:48.34254Z","end":"2026-04-03T02:20:48.604955Z","steps":["trace[820060483] 'agreement among raft nodes before linearized reading' (duration: 261.931825ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:53.67946Z","caller":"traceutil/trace.go:171","msg":"trace[1895951573] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"202.030943ms","start":"2026-04-03T02:20:53.477407Z","end":"2026-04-03T02:20:53.679438Z","steps":["trace[1895951573] 'read index received' (duration: 201.973282ms)","trace[1895951573] 'applied index is now lower than readState.Index' (duration: 56.661µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:53.679501Z","caller":"traceutil/trace.go:171","msg":"trace[1732516364] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"400.247837ms","start":"2026-04-03T02:20:53.279235Z","end":"2026-04-03T02:20:53.679483Z","steps":["trace[1732516364] 'process raft request' (duration: 400.055424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:53.679616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.279207Z","time spent":"400.333529ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:53.679669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.263897ms","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-04-03T02:20:53.679705Z","caller":"traceutil/trace.go:171","msg":"trace[691470144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2096; }","duration":"202.340219ms","start":"2026-04-03T02:20:53.477355Z","end":"2026-04-03T02:20:53.679695Z","steps":["trace[691470144] 'agreement among raft nodes before linearized reading' (duration: 202.197476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:54.204626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.8186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:20:54.20483Z","caller":"traceutil/trace.go:171","msg":"trace[295960284] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2170; }","duration":"525.287194ms","start":"2026-04-03T02:20:53.679526Z","end":"2026-04-03T02:20:54.204813Z","steps":["trace[295960284] 'read index received' (duration: 200.274022ms)","trace[295960284] 'applied index is now lower than readState.Index' (duration: 325.012022ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:54.204918Z","caller":"traceutil/trace.go:171","msg":"trace[862157500] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"528.33743ms","start":"2026-04-03T02:20:53.676571Z","end":"2026-04-03T02:20:54.204908Z","steps":["trace[862157500] 'process raft request' (duration: 528.153677ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:54.204944Z","caller":"traceutil/trace.go:171","msg":"trace[211427394] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"592.269252ms","start":"2026-04-03T02:20:53.612633Z","end":"2026-04-03T02:20:54.204902Z","steps":["trace[211427394] 'process raft request' (duration: 267.092206ms)","trace[211427394] 'compare' (duration: 324.714508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:20:54.204981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.676554Z","time spent":"528.395621ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:54.205104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.612607Z","time spent":"592.409105ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:54.205455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.31355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-03T02:20:54.205492Z","caller":"traceutil/trace.go:171","msg":"trace[550958767] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2098; }","duration":"544.381261ms","start":"2026-04-03T02:20:53.661099Z","end":"2026-04-03T02:20:54.20548Z","steps":["trace[550958767] 'agreement among raft nodes before linearized reading' (duration: 544.067966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:54.205527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.661086Z","time spent":"544.433452ms","remote":"127.0.0.1:40320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-03T02:20:54.205549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.623361ms","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-04-03T02:20:54.205591Z","caller":"traceutil/trace.go:171","msg":"trace[2024866212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2100; }","duration":"431.702202ms","start":"2026-04-03T02:20:53.773876Z","end":"2026-04-03T02:20:54.205579Z","steps":["trace[2024866212] 'agreement among raft nodes before linearized reading' (duration: 431.554999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:54.205616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.773857Z","time spent":"431.752724ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T02:20:54.205612Z","caller":"traceutil/trace.go:171","msg":"trace[4219401] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"522.776187ms","start":"2026-04-03T02:20:53.68281Z","end":"2026-04-03T02:20:54.205587Z","steps":["trace[4219401] 'process raft request' (duration: 521.979173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:54.205646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.885048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:20:54.205704Z","caller":"traceutil/trace.go:171","msg":"trace[1968757759] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"365.110682ms","start":"2026-04-03T02:20:53.840566Z","end":"2026-04-03T02:20:54.205676Z","steps":["trace[1968757759] 'process raft request' (duration: 364.803296ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:54.205728Z","caller":"traceutil/trace.go:171","msg":"trace[791332986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2100; }","duration":"103.01648ms","start":"2026-04-03T02:20:54.102689Z","end":"2026-04-03T02:20:54.205705Z","steps":["trace[791332986] 'agreement among raft nodes before linearized reading' (duration: 102.819387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:54.205744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.47ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-03T02:20:54.205763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.682795Z","time spent":"522.87877ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:20:54.205798Z","caller":"traceutil/trace.go:171","msg":"trace[386637924] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2100; }","duration":"419.559932ms","start":"2026-04-03T02:20:53.786222Z","end":"2026-04-03T02:20:54.205782Z","steps":["trace[386637924] 'agreement among raft nodes before linearized reading' (duration: 419.395269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:54.205817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.840538Z","time spent":"365.224843ms","remote":"127.0.0.1:34274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T02:20:54.205842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:53.786202Z","time spent":"419.631213ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-03T02:20:54.733404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.485622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:20:54.733978Z","caller":"traceutil/trace.go:171","msg":"trace[1961590978] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"523.792714ms","start":"2026-04-03T02:20:54.210139Z","end":"2026-04-03T02:20:54.733932Z","steps":["trace[1961590978] 'process raft request' (duration: 298.628469ms)","trace[1961590978] 'compare' (duration: 224.296969ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:54.734125Z","caller":"traceutil/trace.go:171","msg":"trace[926099148] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"522.917489ms","start":"2026-04-03T02:20:54.211175Z","end":"2026-04-03T02:20:54.734093Z","steps":["trace[926099148] 'process raft request' (duration: 522.674945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:54.73419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:54.210126Z","time spent":"523.938257ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:54.734289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:54.211155Z","time spent":"523.049401ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:55.33018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.433357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-03T02:20:55.330982Z","caller":"traceutil/trace.go:171","msg":"trace[2090539083] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2105; }","duration":"117.251312ms","start":"2026-04-03T02:20:55.213672Z","end":"2026-04-03T02:20:55.330924Z","steps":["trace[2090539083] 'range keys from in-memory index tree' (duration: 116.216214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:55.33034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.271607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-03T02:20:55.331339Z","caller":"traceutil/trace.go:171","msg":"trace[322717099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2105; }","duration":"404.357845ms","start":"2026-04-03T02:20:54.926952Z","end":"2026-04-03T02:20:55.33131Z","steps":["trace[322717099] 'range keys from in-memory index tree' (duration: 402.975801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:55.331411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:54.926919Z","time spent":"404.475407ms","remote":"127.0.0.1:34468","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-04-03T02:20:55.607464Z","caller":"traceutil/trace.go:171","msg":"trace[489728549] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"271.238931ms","start":"2026-04-03T02:20:55.336178Z","end":"2026-04-03T02:20:55.607417Z","steps":["trace[489728549] 'process raft request' (duration: 271.017176ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:56.387262Z","caller":"traceutil/trace.go:171","msg":"trace[653578732] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"702.776104ms","start":"2026-04-03T02:20:55.684448Z","end":"2026-04-03T02:20:56.387224Z","steps":["trace[653578732] 'process raft request' (duration: 702.594961ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:56.387306Z","caller":"traceutil/trace.go:171","msg":"trace[1435588611] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2181; }","duration":"283.140888ms","start":"2026-04-03T02:20:56.104136Z","end":"2026-04-03T02:20:56.387277Z","steps":["trace[1435588611] 'read index received' (duration: 283.129658ms)","trace[1435588611] 'applied index is now lower than readState.Index' (duration: 9.31µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:20:56.387476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:55.684424Z","time spent":"702.933267ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:56.387486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.324421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:20:56.387531Z","caller":"traceutil/trace.go:171","msg":"trace[324748208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2107; }","duration":"283.426242ms","start":"2026-04-03T02:20:56.104094Z","end":"2026-04-03T02:20:56.387521Z","steps":["trace[324748208] 'agreement among raft nodes before linearized reading' (duration: 283.31075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.048413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.129351ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:20:57.048796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.714638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:20:57.048559Z","caller":"traceutil/trace.go:171","msg":"trace[1003160109] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2107; }","duration":"188.279244ms","start":"2026-04-03T02:20:56.860248Z","end":"2026-04-03T02:20:57.048527Z","steps":["trace[1003160109] 'range keys from in-memory index tree' (duration: 188.110341ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:57.04954Z","caller":"traceutil/trace.go:171","msg":"trace[2137987444] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"836.463106ms","start":"2026-04-03T02:20:56.213057Z","end":"2026-04-03T02:20:57.04952Z","steps":["trace[2137987444] 'process raft request' (duration: 368.74023ms)","trace[2137987444] 'compare' (duration: 466.412333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:20:57.049706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.213032Z","time spent":"836.615808ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:20:57.049903Z","caller":"traceutil/trace.go:171","msg":"trace[801904737] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2181; }","duration":"662.49299ms","start":"2026-04-03T02:20:56.387377Z","end":"2026-04-03T02:20:57.04987Z","steps":["trace[801904737] 'read index received' (duration: 194.391198ms)","trace[801904737] 'applied index is now lower than readState.Index' (duration: 468.097812ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:20:57.245158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.307352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-03T02:20:57.245199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140112674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:20:57.245254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033827113s","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-04-03T02:20:57.245283Z","caller":"traceutil/trace.go:171","msg":"trace[151306451] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2108; }","duration":"907.508886ms","start":"2026-04-03T02:20:56.337742Z","end":"2026-04-03T02:20:57.245251Z","steps":["trace[151306451] 'agreement among raft nodes before linearized reading' (duration: 712.296438ms)","trace[151306451] 'range keys from in-memory index tree' (duration: 194.880932ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:57.245347Z","caller":"traceutil/trace.go:171","msg":"trace[933050765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"1.140250646s","start":"2026-04-03T02:20:56.105036Z","end":"2026-04-03T02:20:57.245287Z","steps":["trace[933050765] 'agreement among raft nodes before linearized reading' (duration: 944.986337ms)","trace[933050765] 'range keys from in-memory index tree' (duration: 195.101846ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:57.245375Z","caller":"traceutil/trace.go:171","msg":"trace[1042689773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2108; }","duration":"1.034013546s","start":"2026-04-03T02:20:56.211325Z","end":"2026-04-03T02:20:57.245338Z","steps":["trace[1042689773] 'agreement among raft nodes before linearized reading' (duration: 838.690285ms)","trace[1042689773] 'range keys from in-memory index tree' (duration: 194.991554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:20:57.245396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.337716Z","time spent":"907.663468ms","remote":"127.0.0.1:40320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-03T02:20:57.24541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.105031Z","time spent":"1.140367728s","remote":"127.0.0.1:34210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T02:20:57.245446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.211294Z","time spent":"1.034138688s","remote":"127.0.0.1:34468","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":"warn","ts":"2026-04-03T02:20:57.245736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.758546ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-03T02:20:57.24614Z","caller":"traceutil/trace.go:171","msg":"trace[2005408705] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2182; }","duration":"196.153296ms","start":"2026-04-03T02:20:57.049967Z","end":"2026-04-03T02:20:57.24612Z","steps":["trace[2005408705] 'read index received' (duration: 76.021593ms)","trace[2005408705] 'applied index is now lower than readState.Index' (duration: 120.130683ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:57.246156Z","caller":"traceutil/trace.go:171","msg":"trace[2080598263] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"504.982335ms","start":"2026-04-03T02:20:56.741141Z","end":"2026-04-03T02:20:57.246124Z","steps":["trace[2080598263] 'process raft request' (duration: 504.7542ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:57.246319Z","caller":"traceutil/trace.go:171","msg":"trace[587757069] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"114.433972ms","start":"2026-04-03T02:20:57.131867Z","end":"2026-04-03T02:20:57.246301Z","steps":["trace[587757069] 'process raft request' (duration: 114.211767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.246387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.109369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-03T02:20:57.246434Z","caller":"traceutil/trace.go:171","msg":"trace[54943917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2110; }","duration":"422.175221ms","start":"2026-04-03T02:20:56.824246Z","end":"2026-04-03T02:20:57.246422Z","steps":["trace[54943917] 'agreement among raft nodes before linearized reading' (duration: 421.915617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.246469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.824235Z","time spent":"422.225422ms","remote":"127.0.0.1:34468","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":"warn","ts":"2026-04-03T02:20:57.246467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.365222ms","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-04-03T02:20:57.246598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.676874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-03T02:20:57.246721Z","caller":"traceutil/trace.go:171","msg":"trace[391557295] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2110; }","duration":"506.867158ms","start":"2026-04-03T02:20:56.739825Z","end":"2026-04-03T02:20:57.246693Z","steps":["trace[391557295] 'agreement among raft nodes before linearized reading' (duration: 506.349499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.246749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.741102Z","time spent":"505.276249ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:57.24678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.624313ms","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-04-03T02:20:57.246911Z","caller":"traceutil/trace.go:171","msg":"trace[212482391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2110; }","duration":"506.767675ms","start":"2026-04-03T02:20:56.740124Z","end":"2026-04-03T02:20:57.246892Z","steps":["trace[212482391] 'agreement among raft nodes before linearized reading' (duration: 506.033122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.246979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.740116Z","time spent":"506.846887ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T02:20:57.24679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.739804Z","time spent":"506.973939ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-03T02:20:57.246648Z","caller":"traceutil/trace.go:171","msg":"trace[143875510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2110; }","duration":"473.547305ms","start":"2026-04-03T02:20:56.773086Z","end":"2026-04-03T02:20:57.246633Z","steps":["trace[143875510] 'agreement among raft nodes before linearized reading' (duration: 473.050396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.247232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:56.773019Z","time spent":"474.198136ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T02:20:57.831711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.989764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:20:57.832301Z","caller":"traceutil/trace.go:171","msg":"trace[1664833359] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"578.890386ms","start":"2026-04-03T02:20:57.253333Z","end":"2026-04-03T02:20:57.832223Z","steps":["trace[1664833359] 'process raft request' (duration: 578.852556ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:57.832403Z","caller":"traceutil/trace.go:171","msg":"trace[257295386] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"580.502994ms","start":"2026-04-03T02:20:57.251845Z","end":"2026-04-03T02:20:57.832347Z","steps":["trace[257295386] 'process raft request' (duration: 580.25486ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:57.832511Z","caller":"traceutil/trace.go:171","msg":"trace[1831561913] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"579.855103ms","start":"2026-04-03T02:20:57.252646Z","end":"2026-04-03T02:20:57.832501Z","steps":["trace[1831561913] 'process raft request' (duration: 579.489437ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:57.832503Z","caller":"traceutil/trace.go:171","msg":"trace[1223673296] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"581.395211ms","start":"2026-04-03T02:20:57.25108Z","end":"2026-04-03T02:20:57.832475Z","steps":["trace[1223673296] 'process raft request' (duration: 580.967673ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:20:57.83262Z","caller":"traceutil/trace.go:171","msg":"trace[1711947850] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"599.892387ms","start":"2026-04-03T02:20:57.232711Z","end":"2026-04-03T02:20:57.832603Z","steps":["trace[1711947850] 'process raft request' (duration: 347.911246ms)","trace[1711947850] 'compare' (duration: 250.403593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:20:57.832611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.252637Z","time spent":"579.943714ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:57.832667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.251062Z","time spent":"581.508893ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:57.832682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.232682Z","time spent":"599.970629ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:57.832671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.251834Z","time spent":"580.701178ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:57.832431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.253325Z","time spent":"579.020438ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:20:57.832846Z","caller":"traceutil/trace.go:171","msg":"trace[2126292735] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"586.580362ms","start":"2026-04-03T02:20:57.246216Z","end":"2026-04-03T02:20:57.832796Z","steps":["trace[2126292735] 'read index received' (duration: 334.509339ms)","trace[2126292735] 'applied index is now lower than readState.Index' (duration: 252.069583ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:20:57.832982Z","caller":"traceutil/trace.go:171","msg":"trace[1732677095] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"582.432799ms","start":"2026-04-03T02:20:57.250464Z","end":"2026-04-03T02:20:57.832897Z","steps":["trace[1732677095] 'process raft request' (duration: 581.478272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.833072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.384841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:20:57.833098Z","caller":"traceutil/trace.go:171","msg":"trace[2142441658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2116; }","duration":"585.520163ms","start":"2026-04-03T02:20:57.247568Z","end":"2026-04-03T02:20:57.833088Z","steps":["trace[2142441658] 'agreement among raft nodes before linearized reading' (duration: 585.475882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.833126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.247541Z","time spent":"585.579965ms","remote":"127.0.0.1:34214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-03T02:20:57.833142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.160833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-03T02:20:57.833213Z","caller":"traceutil/trace.go:171","msg":"trace[789051402] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2116; }","duration":"771.276114ms","start":"2026-04-03T02:20:57.061927Z","end":"2026-04-03T02:20:57.833203Z","steps":["trace[789051402] 'agreement among raft nodes before linearized reading' (duration: 771.03267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.833262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.471152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-03T02:20:57.833283Z","caller":"traceutil/trace.go:171","msg":"trace[164581759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2116; }","duration":"221.510214ms","start":"2026-04-03T02:20:57.611767Z","end":"2026-04-03T02:20:57.833277Z","steps":["trace[164581759] 'agreement among raft nodes before linearized reading' (duration: 221.448482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:20:57.833289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.061905Z","time spent":"771.373877ms","remote":"127.0.0.1:34384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-03T02:20:57.833446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:20:57.250444Z","time spent":"582.628302ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:20:57.833502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.771575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:20:57.83361Z","caller":"traceutil/trace.go:171","msg":"trace[1142349222] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2116; }","duration":"265.965779ms","start":"2026-04-03T02:20:57.567621Z","end":"2026-04-03T02:20:57.833587Z","steps":["trace[1142349222] 'agreement among raft nodes before linearized reading' (duration: 265.736025ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:22:30.416136Z","caller":"traceutil/trace.go:171","msg":"trace[661608465] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"101.1564ms","start":"2026-04-03T02:22:30.314961Z","end":"2026-04-03T02:22:30.416118Z","steps":["trace[661608465] 'process raft request' (duration: 101.048559ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:22:30.42364Z","caller":"traceutil/trace.go:171","msg":"trace[1431150654] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"107.636584ms","start":"2026-04-03T02:22:30.315992Z","end":"2026-04-03T02:22:30.423628Z","steps":["trace[1431150654] 'process raft request' (duration: 107.539053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:22:30.741478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.520655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:22:30.741625Z","caller":"traceutil/trace.go:171","msg":"trace[1367546134] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"312.203629ms","start":"2026-04-03T02:22:30.429406Z","end":"2026-04-03T02:22:30.741609Z","steps":["trace[1367546134] 'process raft request' (duration: 138.480001ms)","trace[1367546134] 'compare' (duration: 173.430544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:22:30.741691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:22:30.429373Z","time spent":"312.29383ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:22:30.780918Z","caller":"traceutil/trace.go:171","msg":"trace[421850133] linearizableReadLoop","detail":"{readStateIndex:2817; appliedIndex:2813; }","duration":"214.272407ms","start":"2026-04-03T02:22:30.566624Z","end":"2026-04-03T02:22:30.780897Z","steps":["trace[421850133] 'read index received' (duration: 1.271795ms)","trace[421850133] 'applied index is now lower than readState.Index' (duration: 212.999512ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:22:30.781209Z","caller":"traceutil/trace.go:171","msg":"trace[1531322946] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"351.679435ms","start":"2026-04-03T02:22:30.429509Z","end":"2026-04-03T02:22:30.781189Z","steps":["trace[1531322946] 'process raft request' (duration: 351.2143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:22:30.781348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:22:30.429474Z","time spent":"351.787337ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:22:30.781253Z","caller":"traceutil/trace.go:171","msg":"trace[1123712416] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"351.550594ms","start":"2026-04-03T02:22:30.429675Z","end":"2026-04-03T02:22:30.781225Z","steps":["trace[1123712416] 'process raft request' (duration: 351.136759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:22:30.781396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.746322ms","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":"warn","ts":"2026-04-03T02:22:30.78161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:22:30.429656Z","time spent":"351.844888ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:22:30.781689Z","caller":"traceutil/trace.go:171","msg":"trace[1929513935] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2721; }","duration":"215.096396ms","start":"2026-04-03T02:22:30.566565Z","end":"2026-04-03T02:22:30.781661Z","steps":["trace[1929513935] 'agreement among raft nodes before linearized reading' (duration: 214.699452ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:22:30.781987Z","caller":"traceutil/trace.go:171","msg":"trace[1716502412] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"351.048508ms","start":"2026-04-03T02:22:30.430914Z","end":"2026-04-03T02:22:30.781963Z","steps":["trace[1716502412] 'process raft request' (duration: 349.944885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:22:30.782102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:22:30.430885Z","time spent":"351.158859ms","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:22:30.782946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.87946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a2b77855cde9f5\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-03T02:22:30.783074Z","caller":"traceutil/trace.go:171","msg":"trace[438129413] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a2b77855cde9f5; range_end:; response_count:1; response_revision:2722; }","duration":"180.043571ms","start":"2026-04-03T02:22:30.603003Z","end":"2026-04-03T02:22:30.783047Z","steps":["trace[438129413] 'agreement among raft nodes before linearized reading' (duration: 179.746838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:22:30.783126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.527888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-03T02:22:30.783177Z","caller":"traceutil/trace.go:171","msg":"trace[178349361] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2722; }","duration":"117.616849ms","start":"2026-04-03T02:22:30.665547Z","end":"2026-04-03T02:22:30.783163Z","steps":["trace[178349361] 'agreement among raft nodes before linearized reading' (duration: 117.347595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:18.965839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.968615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-03T02:26:18.965922Z","caller":"traceutil/trace.go:171","msg":"trace[70151240] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4541; }","duration":"107.110948ms","start":"2026-04-03T02:26:18.858791Z","end":"2026-04-03T02:26:18.965902Z","steps":["trace[70151240] 'range keys from in-memory index tree' (duration: 106.699959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:27.780985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.935343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-03T02:26:27.781075Z","caller":"traceutil/trace.go:171","msg":"trace[242511816] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4730; }","duration":"145.061685ms","start":"2026-04-03T02:26:27.635991Z","end":"2026-04-03T02:26:27.781053Z","steps":["trace[242511816] 'range keys from in-memory index tree' (duration: 144.762381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:27.781588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.705007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61020"} {"level":"info","ts":"2026-04-03T02:26:27.781638Z","caller":"traceutil/trace.go:171","msg":"trace[1158831761] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4730; }","duration":"142.781018ms","start":"2026-04-03T02:26:27.638847Z","end":"2026-04-03T02:26:27.781628Z","steps":["trace[1158831761] 'range keys from in-memory index tree' (duration: 142.571905ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:27.781756Z","caller":"traceutil/trace.go:171","msg":"trace[1694464623] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"141.950385ms","start":"2026-04-03T02:26:27.639776Z","end":"2026-04-03T02:26:27.781726Z","steps":["trace[1694464623] 'process raft request' (duration: 137.143315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:27.782247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.380928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-03T02:26:27.782298Z","caller":"traceutil/trace.go:171","msg":"trace[117538863] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4730; }","duration":"143.46234ms","start":"2026-04-03T02:26:27.638822Z","end":"2026-04-03T02:26:27.782284Z","steps":["trace[117538863] 'range keys from in-memory index tree' (duration: 142.571605ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:27.782657Z","caller":"traceutil/trace.go:171","msg":"trace[1966119854] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"128.172788ms","start":"2026-04-03T02:26:27.654466Z","end":"2026-04-03T02:26:27.782638Z","steps":["trace[1966119854] 'process raft request' (duration: 128.030026ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.045115Z","caller":"traceutil/trace.go:171","msg":"trace[1871519603] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"254.356843ms","start":"2026-04-03T02:26:27.790734Z","end":"2026-04-03T02:26:28.045091Z","steps":["trace[1871519603] 'process raft request' (duration: 250.482448ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.045171Z","caller":"traceutil/trace.go:171","msg":"trace[998180282] linearizableReadLoop","detail":"{readStateIndex:4907; appliedIndex:4906; }","duration":"254.351643ms","start":"2026-04-03T02:26:27.7908Z","end":"2026-04-03T02:26:28.045152Z","steps":["trace[998180282] 'read index received' (duration: 19.800965ms)","trace[998180282] 'applied index is now lower than readState.Index' (duration: 234.549888ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:28.045355Z","caller":"traceutil/trace.go:171","msg":"trace[1738279750] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"253.5101ms","start":"2026-04-03T02:26:27.791836Z","end":"2026-04-03T02:26:28.045347Z","steps":["trace[1738279750] 'process raft request' (duration: 253.151214ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.045398Z","caller":"traceutil/trace.go:171","msg":"trace[278781107] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"245.204812ms","start":"2026-04-03T02:26:27.800185Z","end":"2026-04-03T02:26:28.04539Z","steps":["trace[278781107] 'process raft request' (duration: 244.922718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:28.045685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.01644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5bb6f9cff5-6pdn9.18a2b7b3f86e17cd\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-03T02:26:28.045766Z","caller":"traceutil/trace.go:171","msg":"trace[1063962739] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5bb6f9cff5-6pdn9.18a2b7b3f86e17cd; range_end:; response_count:1; response_revision:4737; }","duration":"231.133481ms","start":"2026-04-03T02:26:27.814613Z","end":"2026-04-03T02:26:28.045746Z","steps":["trace[1063962739] 'agreement among raft nodes before linearized reading' (duration: 230.932468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:28.04584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.022053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-04-03T02:26:28.04587Z","caller":"traceutil/trace.go:171","msg":"trace[2127525559] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4737; }","duration":"255.074475ms","start":"2026-04-03T02:26:27.790787Z","end":"2026-04-03T02:26:28.045862Z","steps":["trace[2127525559] 'agreement among raft nodes before linearized reading' (duration: 254.986434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:28.046013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.829402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-03T02:26:28.046046Z","caller":"traceutil/trace.go:171","msg":"trace[362753050] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4737; }","duration":"203.896103ms","start":"2026-04-03T02:26:27.842138Z","end":"2026-04-03T02:26:28.046035Z","steps":["trace[362753050] 'agreement among raft nodes before linearized reading' (duration: 203.806121ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.046279Z","caller":"traceutil/trace.go:171","msg":"trace[238236955] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"254.055528ms","start":"2026-04-03T02:26:27.792208Z","end":"2026-04-03T02:26:28.046263Z","steps":["trace[238236955] 'process raft request' (duration: 252.854539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:28.323782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.856898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:26:28.324046Z","caller":"traceutil/trace.go:171","msg":"trace[1176703253] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"225.807776ms","start":"2026-04-03T02:26:28.098207Z","end":"2026-04-03T02:26:28.324015Z","steps":["trace[1176703253] 'process raft request' (duration: 122.592112ms)","trace[1176703253] 'compare' (duration: 102.701306ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:28.32413Z","caller":"traceutil/trace.go:171","msg":"trace[59669442] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4916; }","duration":"211.660524ms","start":"2026-04-03T02:26:28.11245Z","end":"2026-04-03T02:26:28.32411Z","steps":["trace[59669442] 'read index received' (duration: 23.818481ms)","trace[59669442] 'applied index is now lower than readState.Index' (duration: 187.841093ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:26:28.324297Z","caller":"traceutil/trace.go:171","msg":"trace[171719822] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"224.254521ms","start":"2026-04-03T02:26:28.100034Z","end":"2026-04-03T02:26:28.324289Z","steps":["trace[171719822] 'process raft request' (duration: 224.019467ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.324398Z","caller":"traceutil/trace.go:171","msg":"trace[867530315] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"225.142415ms","start":"2026-04-03T02:26:28.09925Z","end":"2026-04-03T02:26:28.324392Z","steps":["trace[867530315] 'process raft request' (duration: 224.634317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:28.324477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.02531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:26:28.324512Z","caller":"traceutil/trace.go:171","msg":"trace[936648761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4748; }","duration":"212.117681ms","start":"2026-04-03T02:26:28.112383Z","end":"2026-04-03T02:26:28.324501Z","steps":["trace[936648761] 'agreement among raft nodes before linearized reading' (duration: 212.004199ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.324665Z","caller":"traceutil/trace.go:171","msg":"trace[1913599047] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"225.151335ms","start":"2026-04-03T02:26:28.099464Z","end":"2026-04-03T02:26:28.324615Z","steps":["trace[1913599047] 'process raft request' (duration: 224.533155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:28.324796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.712532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-03T02:26:28.324829Z","caller":"traceutil/trace.go:171","msg":"trace[1608369406] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4748; }","duration":"198.773403ms","start":"2026-04-03T02:26:28.126045Z","end":"2026-04-03T02:26:28.324818Z","steps":["trace[1608369406] 'agreement among raft nodes before linearized reading' (duration: 198.65268ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.522821Z","caller":"traceutil/trace.go:171","msg":"trace[739325837] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4921; }","duration":"133.812797ms","start":"2026-04-03T02:26:28.388982Z","end":"2026-04-03T02:26:28.522794Z","steps":["trace[739325837] 'read index received' (duration: 125.582912ms)","trace[739325837] 'applied index is now lower than readState.Index' (duration: 8.228695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:26:28.523074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.061341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de008f06-bc4b-4e37-ba4d-03e9d0f707fc\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-03T02:26:28.523144Z","caller":"traceutil/trace.go:171","msg":"trace[2126472949] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de008f06-bc4b-4e37-ba4d-03e9d0f707fc; range_end:; response_count:1; response_revision:4749; }","duration":"134.171102ms","start":"2026-04-03T02:26:28.388937Z","end":"2026-04-03T02:26:28.523108Z","steps":["trace[2126472949] 'agreement among raft nodes before linearized reading' (duration: 133.968319ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.523312Z","caller":"traceutil/trace.go:171","msg":"trace[190837486] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"155.352229ms","start":"2026-04-03T02:26:28.36794Z","end":"2026-04-03T02:26:28.523292Z","steps":["trace[190837486] 'process raft request' (duration: 146.641286ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:28.523785Z","caller":"traceutil/trace.go:171","msg":"trace[1064253417] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"111.531241ms","start":"2026-04-03T02:26:28.412241Z","end":"2026-04-03T02:26:28.523772Z","steps":["trace[1064253417] 'process raft request' (duration: 110.998291ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:26:31.158676Z","caller":"traceutil/trace.go:171","msg":"trace[112827954] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"212.497558ms","start":"2026-04-03T02:26:30.946153Z","end":"2026-04-03T02:26:31.15865Z","steps":["trace[112827954] 'process raft request' (duration: 211.962679ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:28:08.501091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2917} {"level":"info","ts":"2026-04-03T02:28:08.578538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2917,"took":"75.060083ms","hash":3302141437,"current-db-size-bytes":54165504,"current-db-size":"54 MB","current-db-size-in-use-bytes":29499392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-03T02:28:08.578638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3302141437,"revision":2917,"compact-revision":-1} {"level":"info","ts":"2026-04-03T02:30:07.512889Z","caller":"traceutil/trace.go:171","msg":"trace[745631574] transaction","detail":"{read_only:false; response_revision:6446; number_of_response:1; }","duration":"108.994176ms","start":"2026-04-03T02:30:07.403849Z","end":"2026-04-03T02:30:07.512843Z","steps":["trace[745631574] 'process raft request' (duration: 41.032006ms)","trace[745631574] 'compare' (duration: 67.798017ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:30:35.578325Z","caller":"traceutil/trace.go:171","msg":"trace[261103698] transaction","detail":"{read_only:false; response_revision:6638; number_of_response:1; }","duration":"206.157478ms","start":"2026-04-03T02:30:35.372142Z","end":"2026-04-03T02:30:35.578299Z","steps":["trace[261103698] 'process raft request' (duration: 206.008606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:35.578706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.182662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-03T02:30:35.578705Z","caller":"traceutil/trace.go:171","msg":"trace[85735939] linearizableReadLoop","detail":"{readStateIndex:6875; appliedIndex:6875; }","duration":"203.807478ms","start":"2026-04-03T02:30:35.374488Z","end":"2026-04-03T02:30:35.578296Z","steps":["trace[85735939] 'read index received' (duration: 203.796958ms)","trace[85735939] 'applied index is now lower than readState.Index' (duration: 8.01µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:30:35.578789Z","caller":"traceutil/trace.go:171","msg":"trace[1572030441] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6638; }","duration":"204.327865ms","start":"2026-04-03T02:30:35.374445Z","end":"2026-04-03T02:30:35.578773Z","steps":["trace[1572030441] 'agreement among raft nodes before linearized reading' (duration: 204.027851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:35.58504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.287308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:30:35.585102Z","caller":"traceutil/trace.go:171","msg":"trace[1784841839] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:6640; }","duration":"183.338778ms","start":"2026-04-03T02:30:35.401736Z","end":"2026-04-03T02:30:35.585075Z","steps":["trace[1784841839] 'agreement among raft nodes before linearized reading' (duration: 183.226157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:35.585243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.395562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107263"} {"level":"info","ts":"2026-04-03T02:30:35.585281Z","caller":"traceutil/trace.go:171","msg":"trace[1492969015] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6640; }","duration":"203.456093ms","start":"2026-04-03T02:30:35.381812Z","end":"2026-04-03T02:30:35.585268Z","steps":["trace[1492969015] 'agreement among raft nodes before linearized reading' (duration: 203.350022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:35.585288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.320409ms","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-04-03T02:30:35.585323Z","caller":"traceutil/trace.go:171","msg":"trace[565088290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6640; }","duration":"134.41733ms","start":"2026-04-03T02:30:35.450899Z","end":"2026-04-03T02:30:35.585317Z","steps":["trace[565088290] 'agreement among raft nodes before linearized reading' (duration: 134.332289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:30:35.58541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.224539ms","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-04-03T02:30:35.585425Z","caller":"traceutil/trace.go:171","msg":"trace[805705418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6640; }","duration":"142.263469ms","start":"2026-04-03T02:30:35.443157Z","end":"2026-04-03T02:30:35.585421Z","steps":["trace[805705418] 'agreement among raft nodes before linearized reading' (duration: 142.217198ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:33:08.507546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5638} {"level":"info","ts":"2026-04-03T02:33:08.602944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5638,"took":"92.618525ms","hash":2562175919,"current-db-size-bytes":54165504,"current-db-size":"54 MB","current-db-size-in-use-bytes":18210816,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-03T02:33:08.603102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2562175919,"revision":5638,"compact-revision":2917}