{"level":"warn","ts":"2026-04-06T21:04:46.234814Z","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-06T21:04:46.234928Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-06T21:04:46.235001Z","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-06T21:04:46.235013Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-06T21:04:46.235039Z","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-06T21:04:46.235745Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-04-06T21:04:46.235859Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-06T21:04:46.240963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.87099ms"} {"level":"info","ts":"2026-04-06T21:04:46.251589Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-04-06T21:04:46.251709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T21:04:46.251793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-04-06T21:04:46.251832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T21:04:46.251855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-04-06T21:04:46.25193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-04-06T21:04:46.25858Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T21:04:46.260561Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T21:04:46.261896Z","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-06T21:04:46.264774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T21:04:46.265083Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T21:04:46.265116Z","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-06T21:04:46.265267Z","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-06T21:04:46.265295Z","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-06T21:04:46.267877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-04-06T21:04:46.268073Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-06T21:04:46.270022Z","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-06T21:04:46.2703Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T21:04:46.270236Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-06T21:04:46.270348Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T21:04:46.270373Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-06T21:04:47.052748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T21:04:47.052829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T21:04:47.052859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-04-06T21:04:47.052938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-04-06T21:04:47.052975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-06T21:04:47.052992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-04-06T21:04:47.053011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-06T21:04:47.054059Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T21:04:47.054071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:04:47.054082Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:47.054151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:04:47.054356Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T21:04:47.054381Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T21:04:47.055069Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:47.055202Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:47.055305Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:04:47.057807Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-04-06T21:04:47.058221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T21:05:02.360573Z","caller":"traceutil/trace.go:171","msg":"trace[18693990] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"106.400004ms","start":"2026-04-06T21:05:02.254149Z","end":"2026-04-06T21:05:02.360549Z","steps":["trace[18693990] 'process raft request' (duration: 106.245929ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:04.550029Z","caller":"traceutil/trace.go:171","msg":"trace[1903031166] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"410.261262ms","start":"2026-04-06T21:05:04.139751Z","end":"2026-04-06T21:05:04.550012Z","steps":["trace[1903031166] 'read index received' (duration: 410.115908ms)","trace[1903031166] 'applied index is now lower than readState.Index' (duration: 144.564µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:05:04.550111Z","caller":"traceutil/trace.go:171","msg":"trace[2080132834] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"600.380246ms","start":"2026-04-06T21:05:03.949707Z","end":"2026-04-06T21:05:04.550087Z","steps":["trace[2080132834] 'process raft request' (duration: 600.206581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:04.550152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.387555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-06T21:05:04.550194Z","caller":"traceutil/trace.go:171","msg":"trace[916109648] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:376; }","duration":"410.476058ms","start":"2026-04-06T21:05:04.13971Z","end":"2026-04-06T21:05:04.550186Z","steps":["trace[916109648] 'agreement among raft nodes before linearized reading' (duration: 410.396385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:04.550215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:04.139689Z","time spent":"410.521109ms","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6983,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-06T21:05:04.550723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:03.94968Z","time spent":"600.491629ms","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:05:27.747811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.627195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6lfsg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-06T21:05:27.747908Z","caller":"traceutil/trace.go:171","msg":"trace[1042179431] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6lfsg; range_end:; response_count:1; response_revision:647; }","duration":"392.740699ms","start":"2026-04-06T21:05:27.355128Z","end":"2026-04-06T21:05:27.747869Z","steps":["trace[1042179431] 'range keys from in-memory index tree' (duration: 392.44017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:27.747893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.888971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T21:05:27.747938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:05:27.355104Z","time spent":"392.826861ms","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-6lfsg\" "} {"level":"warn","ts":"2026-04-06T21:05:27.747914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.581235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-06T21:05:27.747983Z","caller":"traceutil/trace.go:171","msg":"trace[230914162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:647; }","duration":"274.702349ms","start":"2026-04-06T21:05:27.473267Z","end":"2026-04-06T21:05:27.747969Z","steps":["trace[230914162] 'range keys from in-memory index tree' (duration: 274.452002ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:27.74798Z","caller":"traceutil/trace.go:171","msg":"trace[717179107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"112.012945ms","start":"2026-04-06T21:05:27.635943Z","end":"2026-04-06T21:05:27.747956Z","steps":["trace[717179107] 'range keys from in-memory index tree' (duration: 111.814909ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:27.856232Z","caller":"traceutil/trace.go:171","msg":"trace[1547388733] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"104.156058ms","start":"2026-04-06T21:05:27.752046Z","end":"2026-04-06T21:05:27.856202Z","steps":["trace[1547388733] 'process raft request' (duration: 103.960333ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:27.87626Z","caller":"traceutil/trace.go:171","msg":"trace[1707847736] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"113.813116ms","start":"2026-04-06T21:05:27.76241Z","end":"2026-04-06T21:05:27.876224Z","steps":["trace[1707847736] 'process raft request' (duration: 113.530158ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:41.462378Z","caller":"traceutil/trace.go:171","msg":"trace[1682194232] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"130.704987ms","start":"2026-04-06T21:05:41.331635Z","end":"2026-04-06T21:05:41.46234Z","steps":["trace[1682194232] 'process raft request' (duration: 130.568204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:43.622369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.917143ms","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-06T21:05:43.622441Z","caller":"traceutil/trace.go:171","msg":"trace[2001803591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"156.037387ms","start":"2026-04-06T21:05:43.46639Z","end":"2026-04-06T21:05:43.622427Z","steps":["trace[2001803591] 'range keys from in-memory index tree' (duration: 155.723548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:05:43.622455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.278864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-thn4f\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-06T21:05:43.622552Z","caller":"traceutil/trace.go:171","msg":"trace[2014093917] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-thn4f; range_end:; response_count:1; response_revision:755; }","duration":"171.400057ms","start":"2026-04-06T21:05:43.45111Z","end":"2026-04-06T21:05:43.62251Z","steps":["trace[2014093917] 'range keys from in-memory index tree' (duration: 171.110719ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:05:43.630643Z","caller":"traceutil/trace.go:171","msg":"trace[671317468] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"155.284456ms","start":"2026-04-06T21:05:43.475338Z","end":"2026-04-06T21:05:43.630622Z","steps":["trace[671317468] 'process raft request' (duration: 155.134551ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.27409Z","caller":"traceutil/trace.go:171","msg":"trace[2008173933] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"153.16056ms","start":"2026-04-06T21:07:10.120904Z","end":"2026-04-06T21:07:10.274064Z","steps":["trace[2008173933] 'read index received' (duration: 152.715828ms)","trace[2008173933] 'applied index is now lower than readState.Index' (duration: 443.402µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:10.274153Z","caller":"traceutil/trace.go:171","msg":"trace[1596172525] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"241.331247ms","start":"2026-04-06T21:07:10.032798Z","end":"2026-04-06T21:07:10.274129Z","steps":["trace[1596172525] 'process raft request' (duration: 241.104651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:10.274407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.491239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-06T21:07:10.274496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.699105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-htt94\" ","response":"range_response_count:1 size:5814"} {"level":"info","ts":"2026-04-06T21:07:10.274607Z","caller":"traceutil/trace.go:171","msg":"trace[1655924683] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-htt94; range_end:; response_count:1; response_revision:1926; }","duration":"134.90241ms","start":"2026-04-06T21:07:10.139681Z","end":"2026-04-06T21:07:10.274584Z","steps":["trace[1655924683] 'agreement among raft nodes before linearized reading' (duration: 134.594312ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.274497Z","caller":"traceutil/trace.go:171","msg":"trace[1920937442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1926; }","duration":"153.641933ms","start":"2026-04-06T21:07:10.120833Z","end":"2026-04-06T21:07:10.274475Z","steps":["trace[1920937442] 'agreement among raft nodes before linearized reading' (duration: 153.388536ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.44301Z","caller":"traceutil/trace.go:171","msg":"trace[759784140] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"161.005087ms","start":"2026-04-06T21:07:10.281976Z","end":"2026-04-06T21:07:10.442981Z","steps":["trace[759784140] 'process raft request' (duration: 160.929415ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.443101Z","caller":"traceutil/trace.go:171","msg":"trace[1368838296] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"163.700482ms","start":"2026-04-06T21:07:10.279367Z","end":"2026-04-06T21:07:10.443067Z","steps":["trace[1368838296] 'process raft request' (duration: 163.319512ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.559399Z","caller":"traceutil/trace.go:171","msg":"trace[820196788] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2005; }","duration":"244.026172ms","start":"2026-04-06T21:07:10.315347Z","end":"2026-04-06T21:07:10.559373Z","steps":["trace[820196788] 'read index received' (duration: 127.476653ms)","trace[820196788] 'applied index is now lower than readState.Index' (duration: 116.548359ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:10.559463Z","caller":"traceutil/trace.go:171","msg":"trace[1125993830] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"257.329013ms","start":"2026-04-06T21:07:10.302087Z","end":"2026-04-06T21:07:10.559416Z","steps":["trace[1125993830] 'process raft request' (duration: 207.429342ms)","trace[1125993830] 'compare' (duration: 49.687755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:07:10.559697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.31743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11981"} {"level":"info","ts":"2026-04-06T21:07:10.559758Z","caller":"traceutil/trace.go:171","msg":"trace[1489441432] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1929; }","duration":"244.432733ms","start":"2026-04-06T21:07:10.31531Z","end":"2026-04-06T21:07:10.559743Z","steps":["trace[1489441432] 'agreement among raft nodes before linearized reading' (duration: 244.199336ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.580456Z","caller":"traceutil/trace.go:171","msg":"trace[119916585] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"130.218159ms","start":"2026-04-06T21:07:10.450206Z","end":"2026-04-06T21:07:10.580424Z","steps":["trace[119916585] 'process raft request' (duration: 130.028324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:10.58056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.83342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-ggmz7\" ","response":"range_response_count:1 size:6110"} {"level":"warn","ts":"2026-04-06T21:07:10.580595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.347153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-06T21:07:10.580646Z","caller":"traceutil/trace.go:171","msg":"trace[1775012392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1930; }","duration":"210.443716ms","start":"2026-04-06T21:07:10.37019Z","end":"2026-04-06T21:07:10.580634Z","steps":["trace[1775012392] 'agreement among raft nodes before linearized reading' (duration: 210.300122ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.580645Z","caller":"traceutil/trace.go:171","msg":"trace[850780046] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-ggmz7; range_end:; response_count:1; response_revision:1930; }","duration":"133.964143ms","start":"2026-04-06T21:07:10.44666Z","end":"2026-04-06T21:07:10.580624Z","steps":["trace[850780046] 'agreement among raft nodes before linearized reading' (duration: 133.757208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:10.580653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.572779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:07:10.580703Z","caller":"traceutil/trace.go:171","msg":"trace[363905279] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1930; }","duration":"187.65329ms","start":"2026-04-06T21:07:10.393035Z","end":"2026-04-06T21:07:10.580688Z","steps":["trace[363905279] 'agreement among raft nodes before linearized reading' (duration: 187.572088ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.706091Z","caller":"traceutil/trace.go:171","msg":"trace[1897809090] linearizableReadLoop","detail":"{readStateIndex:2012; appliedIndex:2010; }","duration":"113.320088ms","start":"2026-04-06T21:07:10.592747Z","end":"2026-04-06T21:07:10.706067Z","steps":["trace[1897809090] 'read index received' (duration: 21.486978ms)","trace[1897809090] 'applied index is now lower than readState.Index' (duration: 91.83186ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:10.706262Z","caller":"traceutil/trace.go:171","msg":"trace[343921981] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"115.813068ms","start":"2026-04-06T21:07:10.590419Z","end":"2026-04-06T21:07:10.706232Z","steps":["trace[343921981] 'process raft request' (duration: 115.590572ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.706391Z","caller":"traceutil/trace.go:171","msg":"trace[514077577] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"116.578079ms","start":"2026-04-06T21:07:10.589789Z","end":"2026-04-06T21:07:10.706367Z","steps":["trace[514077577] 'process raft request' (duration: 92.851598ms)","trace[514077577] 'compare' (duration: 23.231667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:07:10.706623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.773911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-06T21:07:10.706683Z","caller":"traceutil/trace.go:171","msg":"trace[1978096330] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1933; }","duration":"113.939515ms","start":"2026-04-06T21:07:10.592726Z","end":"2026-04-06T21:07:10.706665Z","steps":["trace[1978096330] 'agreement among raft nodes before linearized reading' (duration: 113.505573ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:10.889175Z","caller":"traceutil/trace.go:171","msg":"trace[416787298] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"151.679299ms","start":"2026-04-06T21:07:10.737463Z","end":"2026-04-06T21:07:10.889143Z","steps":["trace[416787298] 'process raft request' (duration: 118.492104ms)","trace[416787298] 'compare' (duration: 32.941657ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:14.103762Z","caller":"traceutil/trace.go:171","msg":"trace[1035680703] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"127.01956ms","start":"2026-04-06T21:07:13.976719Z","end":"2026-04-06T21:07:14.103738Z","steps":["trace[1035680703] 'process raft request' (duration: 126.815055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:47.088137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.32608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-06T21:07:47.088208Z","caller":"traceutil/trace.go:171","msg":"trace[672690052] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2136; }","duration":"140.439363ms","start":"2026-04-06T21:07:46.947754Z","end":"2026-04-06T21:07:47.088194Z","steps":["trace[672690052] 'range keys from in-memory index tree' (duration: 140.164096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:52.800992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.67936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:07:52.801089Z","caller":"traceutil/trace.go:171","msg":"trace[675787272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2167; }","duration":"163.824034ms","start":"2026-04-06T21:07:52.637241Z","end":"2026-04-06T21:07:52.801065Z","steps":["trace[675787272] 'range keys from in-memory index tree' (duration: 163.598637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:52.801288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.695686ms","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-06T21:07:52.801396Z","caller":"traceutil/trace.go:171","msg":"trace[349613005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2167; }","duration":"142.874241ms","start":"2026-04-06T21:07:52.658497Z","end":"2026-04-06T21:07:52.801371Z","steps":["trace[349613005] 'range keys from in-memory index tree' (duration: 142.451279ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:53.452506Z","caller":"traceutil/trace.go:171","msg":"trace[549009766] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"317.934427ms","start":"2026-04-06T21:07:53.13454Z","end":"2026-04-06T21:07:53.452474Z","steps":["trace[549009766] 'read index received' (duration: 317.732841ms)","trace[549009766] 'applied index is now lower than readState.Index' (duration: 200.756µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:07:53.452583Z","caller":"traceutil/trace.go:171","msg":"trace[2020952824] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"347.380725ms","start":"2026-04-06T21:07:53.105175Z","end":"2026-04-06T21:07:53.452556Z","steps":["trace[2020952824] 'process raft request' (duration: 347.151328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:53.452746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.189123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-06T21:07:53.452807Z","caller":"traceutil/trace.go:171","msg":"trace[1895624646] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"318.306626ms","start":"2026-04-06T21:07:53.134485Z","end":"2026-04-06T21:07:53.452792Z","steps":["trace[1895624646] 'agreement among raft nodes before linearized reading' (duration: 318.126331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:53.452769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:53.105149Z","time spent":"347.500428ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:07:53.452844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:53.134463Z","time spent":"318.372779ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-06T21:07:53.957068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.581176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:07:53.957181Z","caller":"traceutil/trace.go:171","msg":"trace[1949948115] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"500.11912ms","start":"2026-04-06T21:07:53.457044Z","end":"2026-04-06T21:07:53.957163Z","steps":["trace[1949948115] 'process raft request' (duration: 91.231365ms)","trace[1949948115] 'compare' (duration: 408.398011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:07:53.957238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:53.457016Z","time spent":"500.194872ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:07:53.996047Z","caller":"traceutil/trace.go:171","msg":"trace[1347792866] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"538.801665ms","start":"2026-04-06T21:07:53.457218Z","end":"2026-04-06T21:07:53.99602Z","steps":["trace[1347792866] 'process raft request' (duration: 538.688512ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:07:53.996068Z","caller":"traceutil/trace.go:171","msg":"trace[1052675008] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2259; }","duration":"429.546358ms","start":"2026-04-06T21:07:53.566493Z","end":"2026-04-06T21:07:53.996039Z","steps":["trace[1052675008] 'read index received' (duration: 429.534448ms)","trace[1052675008] 'applied index is now lower than readState.Index' (duration: 9.59µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:07:53.996198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:53.457199Z","time spent":"538.926319ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:07:53.99631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.792745ms","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-06T21:07:53.996355Z","caller":"traceutil/trace.go:171","msg":"trace[403084042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2172; }","duration":"429.901639ms","start":"2026-04-06T21:07:53.566441Z","end":"2026-04-06T21:07:53.996343Z","steps":["trace[403084042] 'agreement among raft nodes before linearized reading' (duration: 429.696293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:07:53.996393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:07:53.566417Z","time spent":"429.96727ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-06T21:09:01.348121Z","caller":"traceutil/trace.go:171","msg":"trace[258904194] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"179.754647ms","start":"2026-04-06T21:09:01.168334Z","end":"2026-04-06T21:09:01.348088Z","steps":["trace[258904194] 'process raft request' (duration: 109.843364ms)","trace[258904194] 'compare' (duration: 69.800289ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:12:42.600804Z","caller":"traceutil/trace.go:171","msg":"trace[1169543185] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"116.833246ms","start":"2026-04-06T21:12:42.48383Z","end":"2026-04-06T21:12:42.600663Z","steps":["trace[1169543185] 'process raft request' (duration: 88.320497ms)","trace[1169543185] 'compare' (duration: 28.149978ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:12:42.601469Z","caller":"traceutil/trace.go:171","msg":"trace[342379290] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"104.732564ms","start":"2026-04-06T21:12:42.496712Z","end":"2026-04-06T21:12:42.601445Z","steps":["trace[342379290] 'process raft request' (duration: 104.665982ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:42.601586Z","caller":"traceutil/trace.go:171","msg":"trace[1121038602] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"112.785328ms","start":"2026-04-06T21:12:42.488767Z","end":"2026-04-06T21:12:42.601552Z","steps":["trace[1121038602] 'process raft request' (duration: 112.530521ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:42.601781Z","caller":"traceutil/trace.go:171","msg":"trace[1018206015] linearizableReadLoop","detail":"{readStateIndex:4780; appliedIndex:4777; }","duration":"110.994726ms","start":"2026-04-06T21:12:42.490774Z","end":"2026-04-06T21:12:42.601769Z","steps":["trace[1018206015] 'read index received' (duration: 81.387566ms)","trace[1018206015] 'applied index is now lower than readState.Index' (duration: 29.60608ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:12:42.601763Z","caller":"traceutil/trace.go:171","msg":"trace[1599977946] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"116.831975ms","start":"2026-04-06T21:12:42.484856Z","end":"2026-04-06T21:12:42.601688Z","steps":["trace[1599977946] 'process raft request' (duration: 116.349701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:12:42.601943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.14713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-06T21:12:42.601984Z","caller":"traceutil/trace.go:171","msg":"trace[1407083878] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4602; }","duration":"111.215393ms","start":"2026-04-06T21:12:42.490759Z","end":"2026-04-06T21:12:42.601974Z","steps":["trace[1407083878] 'agreement among raft nodes before linearized reading' (duration: 111.058268ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:50.768072Z","caller":"traceutil/trace.go:171","msg":"trace[2112279075] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"192.041941ms","start":"2026-04-06T21:12:50.576006Z","end":"2026-04-06T21:12:50.768048Z","steps":["trace[2112279075] 'process raft request' (duration: 191.918778ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:50.910899Z","caller":"traceutil/trace.go:171","msg":"trace[2034051215] linearizableReadLoop","detail":"{readStateIndex:5079; appliedIndex:5077; }","duration":"274.862009ms","start":"2026-04-06T21:12:50.636009Z","end":"2026-04-06T21:12:50.910871Z","steps":["trace[2034051215] 'read index received' (duration: 131.858783ms)","trace[2034051215] 'applied index is now lower than readState.Index' (duration: 143.002116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:12:50.91109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.063666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:12:50.911135Z","caller":"traceutil/trace.go:171","msg":"trace[171988520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4890; }","duration":"275.173819ms","start":"2026-04-06T21:12:50.635951Z","end":"2026-04-06T21:12:50.911125Z","steps":["trace[171988520] 'agreement among raft nodes before linearized reading' (duration: 275.017733ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:12:50.911077Z","caller":"traceutil/trace.go:171","msg":"trace[1062491217] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"332.958307ms","start":"2026-04-06T21:12:50.578085Z","end":"2026-04-06T21:12:50.911043Z","steps":["trace[1062491217] 'process raft request' (duration: 327.866859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:12:50.911362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:12:50.578071Z","time spent":"333.199535ms","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3702,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:13:09.614955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.287703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:13:09.615114Z","caller":"traceutil/trace.go:171","msg":"trace[364464359] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"261.326482ms","start":"2026-04-06T21:13:09.353756Z","end":"2026-04-06T21:13:09.615083Z","steps":["trace[364464359] 'process raft request' (duration: 123.800553ms)","trace[364464359] 'compare' (duration: 137.155669ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:13:09.615158Z","caller":"traceutil/trace.go:171","msg":"trace[1678551108] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"245.078614ms","start":"2026-04-06T21:13:09.370063Z","end":"2026-04-06T21:13:09.615141Z","steps":["trace[1678551108] 'process raft request' (duration: 245.016782ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:09.615466Z","caller":"traceutil/trace.go:171","msg":"trace[1338038727] linearizableReadLoop","detail":"{readStateIndex:5460; appliedIndex:5459; }","duration":"247.205296ms","start":"2026-04-06T21:13:09.368246Z","end":"2026-04-06T21:13:09.615451Z","steps":["trace[1338038727] 'read index received' (duration: 109.297595ms)","trace[1338038727] 'applied index is now lower than readState.Index' (duration: 137.906511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:13:09.615789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.078426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ef3e303-776e-4022-b72f-f077b956882c\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-04-06T21:13:09.615792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.211796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-06T21:13:09.615835Z","caller":"traceutil/trace.go:171","msg":"trace[1660217284] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ef3e303-776e-4022-b72f-f077b956882c; range_end:; response_count:1; response_revision:5263; }","duration":"146.160728ms","start":"2026-04-06T21:13:09.469663Z","end":"2026-04-06T21:13:09.615824Z","steps":["trace[1660217284] 'agreement among raft nodes before linearized reading' (duration: 146.009864ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:09.615846Z","caller":"traceutil/trace.go:171","msg":"trace[1024555661] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5263; }","duration":"247.294708ms","start":"2026-04-06T21:13:09.368538Z","end":"2026-04-06T21:13:09.615833Z","steps":["trace[1024555661] 'agreement among raft nodes before linearized reading' (duration: 247.090782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:09.615842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.575536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-06T21:13:09.615936Z","caller":"traceutil/trace.go:171","msg":"trace[1906549483] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5263; }","duration":"247.736952ms","start":"2026-04-06T21:13:09.368175Z","end":"2026-04-06T21:13:09.615912Z","steps":["trace[1906549483] 'agreement among raft nodes before linearized reading' (duration: 247.447172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:09.615848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.861349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2265"} {"level":"info","ts":"2026-04-06T21:13:09.616012Z","caller":"traceutil/trace.go:171","msg":"trace[1214361249] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5263; }","duration":"244.053075ms","start":"2026-04-06T21:13:09.371942Z","end":"2026-04-06T21:13:09.615995Z","steps":["trace[1214361249] 'agreement among raft nodes before linearized reading' (duration: 243.764206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.006246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.807131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:13:10.006579Z","caller":"traceutil/trace.go:171","msg":"trace[757004777] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5461; }","duration":"380.565925ms","start":"2026-04-06T21:13:09.625992Z","end":"2026-04-06T21:13:10.006557Z","steps":["trace[757004777] 'read index received' (duration: 174.335912ms)","trace[757004777] 'applied index is now lower than readState.Index' (duration: 206.228453ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:13:10.006738Z","caller":"traceutil/trace.go:171","msg":"trace[1798593067] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"386.642671ms","start":"2026-04-06T21:13:09.620078Z","end":"2026-04-06T21:13:10.006721Z","steps":["trace[1798593067] 'process raft request' (duration: 386.359213ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:10.006718Z","caller":"traceutil/trace.go:171","msg":"trace[2079992431] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"386.809305ms","start":"2026-04-06T21:13:09.619879Z","end":"2026-04-06T21:13:10.006688Z","steps":["trace[2079992431] 'process raft request' (duration: 386.485776ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:10.006776Z","caller":"traceutil/trace.go:171","msg":"trace[1543818968] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"387.016691ms","start":"2026-04-06T21:13:09.61972Z","end":"2026-04-06T21:13:10.006737Z","steps":["trace[1543818968] 'process raft request' (duration: 180.623874ms)","trace[1543818968] 'compare' (duration: 205.652616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:13:10.006868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:13:09.619856Z","time spent":"386.938959ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:13:10.006857Z","caller":"traceutil/trace.go:171","msg":"trace[635965419] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"377.583048ms","start":"2026-04-06T21:13:09.62923Z","end":"2026-04-06T21:13:10.006813Z","steps":["trace[635965419] 'process raft request' (duration: 377.256849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.006977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.75119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a3e0ed6b2fe494\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-04-06T21:13:10.007019Z","caller":"traceutil/trace.go:171","msg":"trace[641666694] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a3e0ed6b2fe494; range_end:; response_count:1; response_revision:5267; }","duration":"204.831112ms","start":"2026-04-06T21:13:09.802171Z","end":"2026-04-06T21:13:10.007002Z","steps":["trace[641666694] 'agreement among raft nodes before linearized reading' (duration: 204.705718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.007017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:13:09.629211Z","time spent":"377.728233ms","remote":"127.0.0.1:38210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2577,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:13:10.006976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.952336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-06T21:13:10.007093Z","caller":"traceutil/trace.go:171","msg":"trace[274762910] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5267; }","duration":"381.09892ms","start":"2026-04-06T21:13:09.62597Z","end":"2026-04-06T21:13:10.007069Z","steps":["trace[274762910] 'agreement among raft nodes before linearized reading' (duration: 380.77346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.007148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:13:09.625962Z","time spent":"381.175563ms","remote":"127.0.0.1:38108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1153,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-04-06T21:13:10.007005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:13:09.61971Z","time spent":"387.195606ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:13:10.006829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:13:09.620067Z","time spent":"386.713432ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:13:10.006845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.753408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:13:10.007418Z","caller":"traceutil/trace.go:171","msg":"trace[446774355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5267; }","duration":"374.343364ms","start":"2026-04-06T21:13:09.633059Z","end":"2026-04-06T21:13:10.007402Z","steps":["trace[446774355] 'agreement among raft nodes before linearized reading' (duration: 373.731267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.007476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:13:09.63305Z","time spent":"374.407907ms","remote":"127.0.0.1:59048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-06T21:13:10.00757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.627216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ef3e303-776e-4022-b72f-f077b956882c\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-04-06T21:13:10.007646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.469321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-04-06T21:13:10.007643Z","caller":"traceutil/trace.go:171","msg":"trace[700541501] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ef3e303-776e-4022-b72f-f077b956882c; range_end:; response_count:1; response_revision:5267; }","duration":"256.732599ms","start":"2026-04-06T21:13:09.750893Z","end":"2026-04-06T21:13:10.007625Z","steps":["trace[700541501] 'agreement among raft nodes before linearized reading' (duration: 256.06733ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:10.007693Z","caller":"traceutil/trace.go:171","msg":"trace[1985910756] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5267; }","duration":"169.558814ms","start":"2026-04-06T21:13:09.83812Z","end":"2026-04-06T21:13:10.007679Z","steps":["trace[1985910756] 'agreement among raft nodes before linearized reading' (duration: 168.692759ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:10.193535Z","caller":"traceutil/trace.go:171","msg":"trace[1861155408] linearizableReadLoop","detail":"{readStateIndex:5466; appliedIndex:5465; }","duration":"182.157808ms","start":"2026-04-06T21:13:10.011354Z","end":"2026-04-06T21:13:10.193512Z","steps":["trace[1861155408] 'read index received' (duration: 172.368415ms)","trace[1861155408] 'applied index is now lower than readState.Index' (duration: 9.787303ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:13:10.193526Z","caller":"traceutil/trace.go:171","msg":"trace[1932817959] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"182.608741ms","start":"2026-04-06T21:13:10.010889Z","end":"2026-04-06T21:13:10.193498Z","steps":["trace[1932817959] 'process raft request' (duration: 172.816078ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:10.193837Z","caller":"traceutil/trace.go:171","msg":"trace[1238110223] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"178.377469ms","start":"2026-04-06T21:13:10.015428Z","end":"2026-04-06T21:13:10.193805Z","steps":["trace[1238110223] 'process raft request' (duration: 178.027249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.193939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80136"} {"level":"info","ts":"2026-04-06T21:13:10.193965Z","caller":"traceutil/trace.go:171","msg":"trace[1958302737] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5269; }","duration":"182.610651ms","start":"2026-04-06T21:13:10.011347Z","end":"2026-04-06T21:13:10.193958Z","steps":["trace[1958302737] 'agreement among raft nodes before linearized reading' (duration: 182.284412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.194096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.889462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-06T21:13:10.194133Z","caller":"traceutil/trace.go:171","msg":"trace[1328536194] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"173.94692ms","start":"2026-04-06T21:13:10.020178Z","end":"2026-04-06T21:13:10.194125Z","steps":["trace[1328536194] 'process raft request' (duration: 173.367224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:13:10.19436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.259202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-06T21:13:10.194148Z","caller":"traceutil/trace.go:171","msg":"trace[706565806] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5270; }","duration":"180.975344ms","start":"2026-04-06T21:13:10.013159Z","end":"2026-04-06T21:13:10.194134Z","steps":["trace[706565806] 'agreement among raft nodes before linearized reading' (duration: 180.845841ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:13:10.194386Z","caller":"traceutil/trace.go:171","msg":"trace[1130370099] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5270; }","duration":"164.316443ms","start":"2026-04-06T21:13:10.030063Z","end":"2026-04-06T21:13:10.19438Z","steps":["trace[1130370099] 'agreement among raft nodes before linearized reading' (duration: 164.249912ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:14:01.040402Z","caller":"traceutil/trace.go:171","msg":"trace[1415518659] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"101.256943ms","start":"2026-04-06T21:14:00.939125Z","end":"2026-04-06T21:14:01.040382Z","steps":["trace[1415518659] 'process raft request' (duration: 101.121209ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:14:47.087248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2985} {"level":"info","ts":"2026-04-06T21:14:47.153874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2985,"took":"65.501159ms","hash":4025430677,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":34394112,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-06T21:14:47.15395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4025430677,"revision":2985,"compact-revision":-1} {"level":"info","ts":"2026-04-06T21:19:08.015314Z","caller":"traceutil/trace.go:171","msg":"trace[323140675] linearizableReadLoop","detail":"{readStateIndex:8362; appliedIndex:8361; }","duration":"282.709494ms","start":"2026-04-06T21:19:07.73258Z","end":"2026-04-06T21:19:08.01529Z","steps":["trace[323140675] 'read index received' (duration: 282.539029ms)","trace[323140675] 'applied index is now lower than readState.Index' (duration: 169.235µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:19:08.015433Z","caller":"traceutil/trace.go:171","msg":"trace[973325439] transaction","detail":"{read_only:false; response_revision:8074; number_of_response:1; }","duration":"296.941205ms","start":"2026-04-06T21:19:07.71846Z","end":"2026-04-06T21:19:08.015401Z","steps":["trace[973325439] 'process raft request' (duration: 296.662747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:08.015512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.924759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-06T21:19:08.015555Z","caller":"traceutil/trace.go:171","msg":"trace[890713434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8074; }","duration":"283.021552ms","start":"2026-04-06T21:19:07.732523Z","end":"2026-04-06T21:19:08.015545Z","steps":["trace[890713434] 'agreement among raft nodes before linearized reading' (duration: 282.886368ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:08.166007Z","caller":"traceutil/trace.go:171","msg":"trace[982848189] transaction","detail":"{read_only:false; response_revision:8076; number_of_response:1; }","duration":"146.49425ms","start":"2026-04-06T21:19:08.019484Z","end":"2026-04-06T21:19:08.165978Z","steps":["trace[982848189] 'process raft request' (duration: 146.432408ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:08.166022Z","caller":"traceutil/trace.go:171","msg":"trace[1472725617] transaction","detail":"{read_only:false; response_revision:8075; number_of_response:1; }","duration":"286.667277ms","start":"2026-04-06T21:19:07.879313Z","end":"2026-04-06T21:19:08.165981Z","steps":["trace[1472725617] 'process raft request' (duration: 247.166097ms)","trace[1472725617] 'compare' (duration: 39.285014ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:19:08.166143Z","caller":"traceutil/trace.go:171","msg":"trace[1464186917] linearizableReadLoop","detail":"{readStateIndex:8363; appliedIndex:8362; }","duration":"150.723441ms","start":"2026-04-06T21:19:08.015389Z","end":"2026-04-06T21:19:08.166113Z","steps":["trace[1464186917] 'read index received' (duration: 111.077846ms)","trace[1464186917] 'applied index is now lower than readState.Index' (duration: 39.641795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:19:08.166332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.855169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-06T21:19:08.166341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.403627ms","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-06T21:19:08.16639Z","caller":"traceutil/trace.go:171","msg":"trace[498366081] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8076; }","duration":"193.958021ms","start":"2026-04-06T21:19:07.972416Z","end":"2026-04-06T21:19:08.166374Z","steps":["trace[498366081] 'agreement among raft nodes before linearized reading' (duration: 193.768616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:08.166384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.375773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-06T21:19:08.166458Z","caller":"traceutil/trace.go:171","msg":"trace[1338499665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8076; }","duration":"221.496696ms","start":"2026-04-06T21:19:07.944941Z","end":"2026-04-06T21:19:08.166438Z","steps":["trace[1338499665] 'agreement among raft nodes before linearized reading' (duration: 221.26393ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:08.166393Z","caller":"traceutil/trace.go:171","msg":"trace[149843867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8076; }","duration":"432.49503ms","start":"2026-04-06T21:19:07.733888Z","end":"2026-04-06T21:19:08.166383Z","steps":["trace[149843867] 'agreement among raft nodes before linearized reading' (duration: 432.309034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:08.166627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:19:07.733876Z","time spent":"432.713536ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-06T21:19:19.645791Z","caller":"traceutil/trace.go:171","msg":"trace[535383859] linearizableReadLoop","detail":"{readStateIndex:8426; appliedIndex:8425; }","duration":"161.512723ms","start":"2026-04-06T21:19:19.484254Z","end":"2026-04-06T21:19:19.645767Z","steps":["trace[535383859] 'read index received' (duration: 153.352467ms)","trace[535383859] 'applied index is now lower than readState.Index' (duration: 8.159466ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:19:19.645864Z","caller":"traceutil/trace.go:171","msg":"trace[494776902] transaction","detail":"{read_only:false; response_revision:8135; number_of_response:1; }","duration":"188.622937ms","start":"2026-04-06T21:19:19.45721Z","end":"2026-04-06T21:19:19.645832Z","steps":["trace[494776902] 'process raft request' (duration: 180.41748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:19.64593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.660517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:19:19.645987Z","caller":"traceutil/trace.go:171","msg":"trace[1284115388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8135; }","duration":"161.760551ms","start":"2026-04-06T21:19:19.484211Z","end":"2026-04-06T21:19:19.645972Z","steps":["trace[1284115388] 'agreement among raft nodes before linearized reading' (duration: 161.660648ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.462217Z","caller":"traceutil/trace.go:171","msg":"trace[537101528] linearizableReadLoop","detail":"{readStateIndex:8429; appliedIndex:8428; }","duration":"245.960833ms","start":"2026-04-06T21:19:20.216228Z","end":"2026-04-06T21:19:20.462189Z","steps":["trace[537101528] 'read index received' (duration: 207.305246ms)","trace[537101528] 'applied index is now lower than readState.Index' (duration: 38.654257ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:19:20.462356Z","caller":"traceutil/trace.go:171","msg":"trace[1467791424] transaction","detail":"{read_only:false; response_revision:8138; number_of_response:1; }","duration":"253.853291ms","start":"2026-04-06T21:19:20.20849Z","end":"2026-04-06T21:19:20.462343Z","steps":["trace[1467791424] 'process raft request' (duration: 215.015999ms)","trace[1467791424] 'compare' (duration: 38.340328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:19:20.462736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.480147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-06T21:19:20.462758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.550978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-06T21:19:20.462791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.381355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sb2lh\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-06T21:19:20.462799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.167994ms","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-06T21:19:20.462832Z","caller":"traceutil/trace.go:171","msg":"trace[1077361346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8138; }","duration":"246.611801ms","start":"2026-04-06T21:19:20.216185Z","end":"2026-04-06T21:19:20.462797Z","steps":["trace[1077361346] 'agreement among raft nodes before linearized reading' (duration: 246.338153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:20.462791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.278215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-06T21:19:20.462879Z","caller":"traceutil/trace.go:171","msg":"trace[328646085] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8138; }","duration":"241.289008ms","start":"2026-04-06T21:19:20.22157Z","end":"2026-04-06T21:19:20.46286Z","steps":["trace[328646085] 'agreement among raft nodes before linearized reading' (duration: 241.01679ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.462876Z","caller":"traceutil/trace.go:171","msg":"trace[648136094] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sb2lh; range_end:; response_count:1; response_revision:8138; }","duration":"228.510989ms","start":"2026-04-06T21:19:20.234341Z","end":"2026-04-06T21:19:20.462852Z","steps":["trace[648136094] 'agreement among raft nodes before linearized reading' (duration: 228.245101ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.462911Z","caller":"traceutil/trace.go:171","msg":"trace[2023949581] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:8138; }","duration":"172.496052ms","start":"2026-04-06T21:19:20.290397Z","end":"2026-04-06T21:19:20.462893Z","steps":["trace[2023949581] 'agreement among raft nodes before linearized reading' (duration: 172.219714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:20.462816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.352773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-06T21:19:20.46301Z","caller":"traceutil/trace.go:171","msg":"trace[817594156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8138; }","duration":"238.658332ms","start":"2026-04-06T21:19:20.224338Z","end":"2026-04-06T21:19:20.462996Z","steps":["trace[817594156] 'agreement among raft nodes before linearized reading' (duration: 238.25253ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.462831Z","caller":"traceutil/trace.go:171","msg":"trace[1469851025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8138; }","duration":"238.657592ms","start":"2026-04-06T21:19:20.224158Z","end":"2026-04-06T21:19:20.462816Z","steps":["trace[1469851025] 'agreement among raft nodes before linearized reading' (duration: 238.446056ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.607368Z","caller":"traceutil/trace.go:171","msg":"trace[1772842577] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"139.974832ms","start":"2026-04-06T21:19:20.467363Z","end":"2026-04-06T21:19:20.607338Z","steps":["trace[1772842577] 'process raft request' (duration: 131.246969ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.607647Z","caller":"traceutil/trace.go:171","msg":"trace[235351381] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"140.204019ms","start":"2026-04-06T21:19:20.467417Z","end":"2026-04-06T21:19:20.607621Z","steps":["trace[235351381] 'process raft request' (duration: 139.876649ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.607935Z","caller":"traceutil/trace.go:171","msg":"trace[2039724081] transaction","detail":"{read_only:false; response_revision:8141; number_of_response:1; }","duration":"140.472266ms","start":"2026-04-06T21:19:20.467418Z","end":"2026-04-06T21:19:20.60789Z","steps":["trace[2039724081] 'process raft request' (duration: 140.117916ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.608005Z","caller":"traceutil/trace.go:171","msg":"trace[171320507] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"140.550289ms","start":"2026-04-06T21:19:20.467435Z","end":"2026-04-06T21:19:20.607985Z","steps":["trace[171320507] 'process raft request' (duration: 140.306632ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.608051Z","caller":"traceutil/trace.go:171","msg":"trace[875110012] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"121.125738ms","start":"2026-04-06T21:19:20.486905Z","end":"2026-04-06T21:19:20.60803Z","steps":["trace[875110012] 'process raft request' (duration: 120.984004ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:20.661918Z","caller":"traceutil/trace.go:171","msg":"trace[1161797512] transaction","detail":"{read_only:false; response_revision:8145; number_of_response:1; }","duration":"133.709931ms","start":"2026-04-06T21:19:20.528178Z","end":"2026-04-06T21:19:20.661888Z","steps":["trace[1161797512] 'process raft request' (duration: 133.537566ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:21.22352Z","caller":"traceutil/trace.go:171","msg":"trace[1975832930] linearizableReadLoop","detail":"{readStateIndex:8439; appliedIndex:8438; }","duration":"142.156596ms","start":"2026-04-06T21:19:21.08134Z","end":"2026-04-06T21:19:21.223497Z","steps":["trace[1975832930] 'read index received' (duration: 141.918208ms)","trace[1975832930] 'applied index is now lower than readState.Index' (duration: 237.208µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:19:21.223546Z","caller":"traceutil/trace.go:171","msg":"trace[277875462] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"205.71049ms","start":"2026-04-06T21:19:21.017811Z","end":"2026-04-06T21:19:21.223522Z","steps":["trace[277875462] 'process raft request' (duration: 205.521724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:21.223647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.293219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:19:21.22368Z","caller":"traceutil/trace.go:171","msg":"trace[749036231] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8147; }","duration":"142.383222ms","start":"2026-04-06T21:19:21.081287Z","end":"2026-04-06T21:19:21.223671Z","steps":["trace[749036231] 'agreement among raft nodes before linearized reading' (duration: 142.303189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:21.4447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.03434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-06T21:19:21.444787Z","caller":"traceutil/trace.go:171","msg":"trace[1548006869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8147; }","duration":"143.184315ms","start":"2026-04-06T21:19:21.301584Z","end":"2026-04-06T21:19:21.444759Z","steps":["trace[1548006869] 'range keys from in-memory index tree' (duration: 142.838185ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:19:47.096965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6150} {"level":"info","ts":"2026-04-06T21:19:47.189227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6150,"took":"90.537454ms","hash":190754966,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":23142400,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-06T21:19:47.189306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":190754966,"revision":6150,"compact-revision":2985} {"level":"info","ts":"2026-04-06T21:19:48.599002Z","caller":"traceutil/trace.go:171","msg":"trace[1228754264] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"385.506853ms","start":"2026-04-06T21:19:48.213469Z","end":"2026-04-06T21:19:48.598976Z","steps":["trace[1228754264] 'process raft request' (duration: 384.208015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:48.599706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:19:48.213453Z","time spent":"385.624866ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T21:19:48.600628Z","caller":"traceutil/trace.go:171","msg":"trace[2041684315] linearizableReadLoop","detail":"{readStateIndex:8641; appliedIndex:8641; }","duration":"197.487432ms","start":"2026-04-06T21:19:48.403125Z","end":"2026-04-06T21:19:48.600613Z","steps":["trace[2041684315] 'read index received' (duration: 197.480942ms)","trace[2041684315] 'applied index is now lower than readState.Index' (duration: 5.27µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:19:48.600908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.77294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-06T21:19:48.60099Z","caller":"traceutil/trace.go:171","msg":"trace[1551746315] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8341; }","duration":"197.883684ms","start":"2026-04-06T21:19:48.403098Z","end":"2026-04-06T21:19:48.600982Z","steps":["trace[1551746315] 'agreement among raft nodes before linearized reading' (duration: 197.645617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:19:48.604692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.411286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129859"} {"level":"info","ts":"2026-04-06T21:19:48.604773Z","caller":"traceutil/trace.go:171","msg":"trace[2127494353] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8342; }","duration":"201.507268ms","start":"2026-04-06T21:19:48.403247Z","end":"2026-04-06T21:19:48.604754Z","steps":["trace[2127494353] 'agreement among raft nodes before linearized reading' (duration: 201.290982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:20:00.961699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.413787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:20:00.961843Z","caller":"traceutil/trace.go:171","msg":"trace[661311029] linearizableReadLoop","detail":"{readStateIndex:8761; appliedIndex:8759; }","duration":"188.298728ms","start":"2026-04-06T21:20:00.773531Z","end":"2026-04-06T21:20:00.96183Z","steps":["trace[661311029] 'read index received' (duration: 84.639444ms)","trace[661311029] 'applied index is now lower than readState.Index' (duration: 103.658484ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:20:00.961937Z","caller":"traceutil/trace.go:171","msg":"trace[733906207] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"200.517471ms","start":"2026-04-06T21:20:00.761413Z","end":"2026-04-06T21:20:00.96193Z","steps":["trace[733906207] 'process raft request' (duration: 200.369716ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:20:00.961906Z","caller":"traceutil/trace.go:171","msg":"trace[1353465195] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"277.02005ms","start":"2026-04-06T21:20:00.684848Z","end":"2026-04-06T21:20:00.961868Z","steps":["trace[1353465195] 'process raft request' (duration: 173.361286ms)","trace[1353465195] 'compare' (duration: 103.199681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:20:00.962093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.570325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sb2lh\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-06T21:20:00.962117Z","caller":"traceutil/trace.go:171","msg":"trace[1790773630] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sb2lh; range_end:; response_count:1; response_revision:8453; }","duration":"188.624837ms","start":"2026-04-06T21:20:00.773487Z","end":"2026-04-06T21:20:00.962112Z","steps":["trace[1790773630] 'agreement among raft nodes before linearized reading' (duration: 188.538074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:20:00.962269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.411307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-06T21:20:00.962372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.824171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-06T21:20:00.962441Z","caller":"traceutil/trace.go:171","msg":"trace[70883375] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8453; }","duration":"127.916384ms","start":"2026-04-06T21:20:00.834514Z","end":"2026-04-06T21:20:00.96243Z","steps":["trace[70883375] 'agreement among raft nodes before linearized reading' (duration: 127.813721ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:20:00.962439Z","caller":"traceutil/trace.go:171","msg":"trace[1993586824] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8453; }","duration":"129.681544ms","start":"2026-04-06T21:20:00.832732Z","end":"2026-04-06T21:20:00.962413Z","steps":["trace[1993586824] 'agreement among raft nodes before linearized reading' (duration: 129.245822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:20:00.96245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.364932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-06T21:20:00.962509Z","caller":"traceutil/trace.go:171","msg":"trace[796529456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8453; }","duration":"114.521287ms","start":"2026-04-06T21:20:00.847973Z","end":"2026-04-06T21:20:00.962494Z","steps":["trace[796529456] 'agreement among raft nodes before linearized reading' (duration: 114.304431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:20:00.962488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.419174ms","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-06T21:20:00.962629Z","caller":"traceutil/trace.go:171","msg":"trace[594987451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8453; }","duration":"114.550518ms","start":"2026-04-06T21:20:00.848029Z","end":"2026-04-06T21:20:00.96258Z","steps":["trace[594987451] 'agreement among raft nodes before linearized reading' (duration: 114.327462ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:20:01.084895Z","caller":"traceutil/trace.go:171","msg":"trace[1308580969] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"115.581008ms","start":"2026-04-06T21:20:00.96928Z","end":"2026-04-06T21:20:01.084861Z","steps":["trace[1308580969] 'process raft request' (duration: 115.407493ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:20:01.085017Z","caller":"traceutil/trace.go:171","msg":"trace[406026240] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"115.752663ms","start":"2026-04-06T21:20:00.969239Z","end":"2026-04-06T21:20:01.084992Z","steps":["trace[406026240] 'process raft request' (duration: 103.21307ms)","trace[406026240] 'compare' (duration: 11.663627ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:20:01.104469Z","caller":"traceutil/trace.go:171","msg":"trace[53505439] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"135.114662ms","start":"2026-04-06T21:20:00.969331Z","end":"2026-04-06T21:20:01.104446Z","steps":["trace[53505439] 'process raft request' (duration: 134.954817ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:20:01.104671Z","caller":"traceutil/trace.go:171","msg":"trace[500858980] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"135.283437ms","start":"2026-04-06T21:20:00.969377Z","end":"2026-04-06T21:20:01.104661Z","steps":["trace[500858980] 'process raft request' (duration: 135.02822ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:20:01.104705Z","caller":"traceutil/trace.go:171","msg":"trace[1606433336] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"131.792396ms","start":"2026-04-06T21:20:00.972903Z","end":"2026-04-06T21:20:01.104696Z","steps":["trace[1606433336] 'process raft request' (duration: 131.663842ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:20:50.960458Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002428.wal"} {"level":"info","ts":"2026-04-06T21:22:21.879075Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-06T21:22:21.886515Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-06T21:22:21.886606Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-06T21:24:47.104093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8339} {"level":"info","ts":"2026-04-06T21:24:47.182976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8339,"took":"76.661466ms","hash":2996502750,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":24813568,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-06T21:24:47.183054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996502750,"revision":8339,"compact-revision":6150} {"level":"info","ts":"2026-04-06T21:29:47.109185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10683} {"level":"info","ts":"2026-04-06T21:29:47.184665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10683,"took":"73.526014ms","hash":3354348245,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":20119552,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-06T21:29:47.184719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3354348245,"revision":10683,"compact-revision":8339}