{"level":"warn","ts":"2026-05-04T01:39:30.518805Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:39:30.518883Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-04T01:39:30.518942Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-04T01:39:30.518953Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-04T01:39:30.518969Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-04T01:39:30.519918Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-05-04T01:39:30.519993Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-04T01:39:30.524162Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.954581ms"} {"level":"info","ts":"2026-05-04T01:39:30.53032Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-05-04T01:39:30.530425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T01:39:30.530456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-05-04T01:39:30.530491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T01:39:30.530514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-05-04T01:39:30.530565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-05-04T01:39:30.540654Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T01:39:30.542628Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T01:39:30.543724Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-04T01:39:30.545667Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T01:39:30.545854Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T01:39:30.545825Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T01:39:30.546016Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T01:39:30.546063Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-04T01:39:30.547947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-05-04T01:39:30.548136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-05-04T01:39:30.550442Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-04T01:39:30.550512Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-04T01:39:30.550745Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-05-04T01:39:30.551002Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T01:39:30.551074Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T01:39:30.631843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T01:39:30.631938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T01:39:30.631975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-05-04T01:39:30.632001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-05-04T01:39:30.632016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-04T01:39:30.632037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-05-04T01:39:30.632053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-05-04T01:39:30.632922Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T01:39:30.632975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:39:30.633059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T01:39:30.633058Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:30.633324Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T01:39:30.633382Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T01:39:30.633818Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:30.633932Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:30.633971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T01:39:30.636566Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T01:39:30.636843Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-05-04T01:39:53.602911Z","caller":"traceutil/trace.go:171","msg":"trace[822887174] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"139.915787ms","start":"2026-05-04T01:39:53.462964Z","end":"2026-05-04T01:39:53.60288Z","steps":["trace[822887174] 'process raft request' (duration: 139.752622ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:07.044612Z","caller":"traceutil/trace.go:171","msg":"trace[536396305] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"126.375578ms","start":"2026-05-04T01:40:06.918207Z","end":"2026-05-04T01:40:07.044583Z","steps":["trace[536396305] 'read index received' (duration: 125.549745ms)","trace[536396305] 'applied index is now lower than readState.Index' (duration: 824.523µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:40:07.044749Z","caller":"traceutil/trace.go:171","msg":"trace[1231682786] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"130.848243ms","start":"2026-05-04T01:40:06.913851Z","end":"2026-05-04T01:40:07.044699Z","steps":["trace[1231682786] 'process raft request' (duration: 130.146634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:07.044942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.660325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-04T01:40:07.045016Z","caller":"traceutil/trace.go:171","msg":"trace[2003197704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"126.848101ms","start":"2026-05-04T01:40:06.918156Z","end":"2026-05-04T01:40:07.045004Z","steps":["trace[2003197704] 'agreement among raft nodes before linearized reading' (duration: 126.591014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:07.628064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.063291ms","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-05-04T01:40:07.628152Z","caller":"traceutil/trace.go:171","msg":"trace[1269782574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"240.208465ms","start":"2026-05-04T01:40:07.387923Z","end":"2026-05-04T01:40:07.628132Z","steps":["trace[1269782574] 'range keys from in-memory index tree' (duration: 239.824975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:22.305698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.784453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nbcjw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-04T01:40:22.305838Z","caller":"traceutil/trace.go:171","msg":"trace[604304324] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nbcjw; range_end:; response_count:1; response_revision:776; }","duration":"218.984759ms","start":"2026-05-04T01:40:22.086816Z","end":"2026-05-04T01:40:22.305801Z","steps":["trace[604304324] 'range keys from in-memory index tree' (duration: 218.557647ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:40:51.546207Z","caller":"traceutil/trace.go:171","msg":"trace[595763547] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"140.301111ms","start":"2026-05-04T01:40:51.405885Z","end":"2026-05-04T01:40:51.546186Z","steps":["trace[595763547] 'read index received' (duration: 140.145307ms)","trace[595763547] 'applied index is now lower than readState.Index' (duration: 154.954µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:40:51.546297Z","caller":"traceutil/trace.go:171","msg":"trace[46282624] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"162.728939ms","start":"2026-05-04T01:40:51.383538Z","end":"2026-05-04T01:40:51.546267Z","steps":["trace[46282624] 'process raft request' (duration: 162.531783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:40:51.546482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.573619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-04T01:40:51.546597Z","caller":"traceutil/trace.go:171","msg":"trace[1771272901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"140.705192ms","start":"2026-05-04T01:40:51.405833Z","end":"2026-05-04T01:40:51.546538Z","steps":["trace[1771272901] 'agreement among raft nodes before linearized reading' (duration: 140.492876ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:33.588547Z","caller":"traceutil/trace.go:171","msg":"trace[938793794] linearizableReadLoop","detail":"{readStateIndex:1863; appliedIndex:1862; }","duration":"163.408276ms","start":"2026-05-04T01:41:33.425112Z","end":"2026-05-04T01:41:33.588521Z","steps":["trace[938793794] 'read index received' (duration: 156.29129ms)","trace[938793794] 'applied index is now lower than readState.Index' (duration: 7.115756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:33.588836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.709845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a192700-599b-477e-8b6a-ed73a86ccaff\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-04T01:41:33.588876Z","caller":"traceutil/trace.go:171","msg":"trace[921388397] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4a192700-599b-477e-8b6a-ed73a86ccaff; range_end:; response_count:1; response_revision:1807; }","duration":"163.791327ms","start":"2026-05-04T01:41:33.425072Z","end":"2026-05-04T01:41:33.588863Z","steps":["trace[921388397] 'agreement among raft nodes before linearized reading' (duration: 163.630592ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:33.588974Z","caller":"traceutil/trace.go:171","msg":"trace[1970824918] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"230.333249ms","start":"2026-05-04T01:41:33.358609Z","end":"2026-05-04T01:41:33.588943Z","steps":["trace[1970824918] 'process raft request' (duration: 222.81531ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:33.84346Z","caller":"traceutil/trace.go:171","msg":"trace[1784196182] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"247.879523ms","start":"2026-05-04T01:41:33.595548Z","end":"2026-05-04T01:41:33.843428Z","steps":["trace[1784196182] 'process raft request' (duration: 247.717968ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:33.848065Z","caller":"traceutil/trace.go:171","msg":"trace[58280139] linearizableReadLoop","detail":"{readStateIndex:1866; appliedIndex:1863; }","duration":"106.066872ms","start":"2026-05-04T01:41:33.741975Z","end":"2026-05-04T01:41:33.848042Z","steps":["trace[58280139] 'read index received' (duration: 101.299658ms)","trace[58280139] 'applied index is now lower than readState.Index' (duration: 4.765954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:41:33.848247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.261179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a192700-599b-477e-8b6a-ed73a86ccaff\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-04T01:41:33.848217Z","caller":"traceutil/trace.go:171","msg":"trace[443195061] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"157.176916ms","start":"2026-05-04T01:41:33.691003Z","end":"2026-05-04T01:41:33.84818Z","steps":["trace[443195061] 'process raft request' (duration: 156.9845ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:33.848259Z","caller":"traceutil/trace.go:171","msg":"trace[974230390] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"252.199643ms","start":"2026-05-04T01:41:33.596032Z","end":"2026-05-04T01:41:33.848232Z","steps":["trace[974230390] 'process raft request' (duration: 251.801571ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:41:33.848301Z","caller":"traceutil/trace.go:171","msg":"trace[382412614] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4a192700-599b-477e-8b6a-ed73a86ccaff; range_end:; response_count:1; response_revision:1810; }","duration":"106.372452ms","start":"2026-05-04T01:41:33.741917Z","end":"2026-05-04T01:41:33.84829Z","steps":["trace[382412614] 'agreement among raft nodes before linearized reading' (duration: 106.248158ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:42:15.717145Z","caller":"traceutil/trace.go:171","msg":"trace[1070782071] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"124.004768ms","start":"2026-05-04T01:42:15.5931Z","end":"2026-05-04T01:42:15.717105Z","steps":["trace[1070782071] 'process raft request' (duration: 106.840696ms)","trace[1070782071] 'compare' (duration: 16.935825ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:42:15.921526Z","caller":"traceutil/trace.go:171","msg":"trace[978480912] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"101.38724ms","start":"2026-05-04T01:42:15.820108Z","end":"2026-05-04T01:42:15.921496Z","steps":["trace[978480912] 'process raft request' (duration: 100.991768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:42:52.541706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.983276ms","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-05-04T01:42:52.541816Z","caller":"traceutil/trace.go:171","msg":"trace[2075970064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2448; }","duration":"136.148012ms","start":"2026-05-04T01:42:52.405646Z","end":"2026-05-04T01:42:52.541794Z","steps":["trace[2075970064] 'range keys from in-memory index tree' (duration: 135.73484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:42:52.541706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.011308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-04T01:42:52.54199Z","caller":"traceutil/trace.go:171","msg":"trace[622422357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2448; }","duration":"135.306587ms","start":"2026-05-04T01:42:52.406641Z","end":"2026-05-04T01:42:52.541947Z","steps":["trace[622422357] 'range keys from in-memory index tree' (duration: 134.879474ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:30.963594Z","caller":"traceutil/trace.go:171","msg":"trace[655338512] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"229.636511ms","start":"2026-05-04T01:43:30.733934Z","end":"2026-05-04T01:43:30.963571Z","steps":["trace[655338512] 'process raft request' (duration: 229.477487ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:30.96461Z","caller":"traceutil/trace.go:171","msg":"trace[481367797] linearizableReadLoop","detail":"{readStateIndex:2820; appliedIndex:2818; }","duration":"209.812324ms","start":"2026-05-04T01:43:30.754773Z","end":"2026-05-04T01:43:30.964585Z","steps":["trace[481367797] 'read index received' (duration: 208.647641ms)","trace[481367797] 'applied index is now lower than readState.Index' (duration: 1.163573ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:43:30.964799Z","caller":"traceutil/trace.go:171","msg":"trace[2080049572] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"229.265151ms","start":"2026-05-04T01:43:30.7355Z","end":"2026-05-04T01:43:30.964766Z","steps":["trace[2080049572] 'process raft request' (duration: 228.936591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:30.964981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.178985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-04T01:43:30.965157Z","caller":"traceutil/trace.go:171","msg":"trace[1365390381] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2713; }","duration":"210.406511ms","start":"2026-05-04T01:43:30.75473Z","end":"2026-05-04T01:43:30.965137Z","steps":["trace[1365390381] 'agreement among raft nodes before linearized reading' (duration: 210.040351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:30.965287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.051935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-04T01:43:30.965543Z","caller":"traceutil/trace.go:171","msg":"trace[911521025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2713; }","duration":"149.394635ms","start":"2026-05-04T01:43:30.816128Z","end":"2026-05-04T01:43:30.965522Z","steps":["trace[911521025] 'agreement among raft nodes before linearized reading' (duration: 148.770477ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:41.192444Z","caller":"traceutil/trace.go:171","msg":"trace[1647611185] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"109.601627ms","start":"2026-05-04T01:43:41.082815Z","end":"2026-05-04T01:43:41.192416Z","steps":["trace[1647611185] 'process raft request' (duration: 109.429502ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:41.194492Z","caller":"traceutil/trace.go:171","msg":"trace[1594363778] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"110.610625ms","start":"2026-05-04T01:43:41.083848Z","end":"2026-05-04T01:43:41.194458Z","steps":["trace[1594363778] 'process raft request' (duration: 110.118891ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:43:42.523283Z","caller":"traceutil/trace.go:171","msg":"trace[486383340] linearizableReadLoop","detail":"{readStateIndex:2897; appliedIndex:2896; }","duration":"228.326204ms","start":"2026-05-04T01:43:42.294933Z","end":"2026-05-04T01:43:42.52326Z","steps":["trace[486383340] 'read index received' (duration: 228.204691ms)","trace[486383340] 'applied index is now lower than readState.Index' (duration: 120.343µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:43:42.523393Z","caller":"traceutil/trace.go:171","msg":"trace[849954981] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"338.065065ms","start":"2026-05-04T01:43:42.185317Z","end":"2026-05-04T01:43:42.523382Z","steps":["trace[849954981] 'process raft request' (duration: 337.804577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:42.523654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.697274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-05-04T01:43:42.523838Z","caller":"traceutil/trace.go:171","msg":"trace[191408240] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2785; }","duration":"228.89346ms","start":"2026-05-04T01:43:42.294878Z","end":"2026-05-04T01:43:42.523771Z","steps":["trace[191408240] 'agreement among raft nodes before linearized reading' (duration: 228.54768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:42.524114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:43:42.185291Z","time spent":"338.129946ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:43:42.564178Z","caller":"traceutil/trace.go:171","msg":"trace[679256866] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"220.1567ms","start":"2026-05-04T01:43:42.343969Z","end":"2026-05-04T01:43:42.564126Z","steps":["trace[679256866] 'process raft request' (duration: 219.911663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:46.866139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.883305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-05-04T01:43:46.866205Z","caller":"traceutil/trace.go:171","msg":"trace[163106447] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2820; }","duration":"572.006048ms","start":"2026-05-04T01:43:46.294183Z","end":"2026-05-04T01:43:46.866189Z","steps":["trace[163106447] 'range keys from in-memory index tree' (duration: 571.68192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:46.866324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:43:46.294163Z","time spent":"572.06642ms","remote":"127.0.0.1:44574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1318,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-04T01:43:46.866361Z","caller":"traceutil/trace.go:171","msg":"trace[2055298712] linearizableReadLoop","detail":"{readStateIndex:2935; appliedIndex:2934; }","duration":"286.975772ms","start":"2026-05-04T01:43:46.579347Z","end":"2026-05-04T01:43:46.866322Z","steps":["trace[2055298712] 'read index received' (duration: 282.415702ms)","trace[2055298712] 'applied index is now lower than readState.Index' (duration: 4.55803ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:43:46.866559Z","caller":"traceutil/trace.go:171","msg":"trace[1555178870] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"290.806241ms","start":"2026-05-04T01:43:46.575737Z","end":"2026-05-04T01:43:46.866544Z","steps":["trace[1555178870] 'process raft request' (duration: 286.166619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:46.86658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.22292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-04T01:43:46.866738Z","caller":"traceutil/trace.go:171","msg":"trace[640711781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2821; }","duration":"287.406215ms","start":"2026-05-04T01:43:46.579291Z","end":"2026-05-04T01:43:46.866697Z","steps":["trace[640711781] 'agreement among raft nodes before linearized reading' (duration: 287.138487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:46.866309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.643638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-04T01:43:46.866891Z","caller":"traceutil/trace.go:171","msg":"trace[436085155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2820; }","duration":"319.253256ms","start":"2026-05-04T01:43:46.547615Z","end":"2026-05-04T01:43:46.866868Z","steps":["trace[436085155] 'range keys from in-memory index tree' (duration: 318.34396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:43:46.866939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:43:46.547593Z","time spent":"319.336428ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-04T01:44:15.776614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869160778366399,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-04T01:44:15.819803Z","caller":"traceutil/trace.go:171","msg":"trace[522011419] linearizableReadLoop","detail":"{readStateIndex:3310; appliedIndex:3309; }","duration":"543.570405ms","start":"2026-05-04T01:44:15.276205Z","end":"2026-05-04T01:44:15.819775Z","steps":["trace[522011419] 'read index received' (duration: 543.315647ms)","trace[522011419] 'applied index is now lower than readState.Index' (duration: 253.508µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:44:15.819874Z","caller":"traceutil/trace.go:171","msg":"trace[475750996] transaction","detail":"{read_only:false; response_revision:3182; number_of_response:1; }","duration":"651.03664ms","start":"2026-05-04T01:44:15.168812Z","end":"2026-05-04T01:44:15.819849Z","steps":["trace[475750996] 'process raft request' (duration: 650.764442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.820022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.168782Z","time spent":"651.155163ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:44:15.820045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.226383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-04T01:44:15.820106Z","caller":"traceutil/trace.go:171","msg":"trace[1282869548] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3182; }","duration":"277.328516ms","start":"2026-05-04T01:44:15.542761Z","end":"2026-05-04T01:44:15.82009Z","steps":["trace[1282869548] 'agreement among raft nodes before linearized reading' (duration: 277.225493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.820115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.366003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T01:44:15.820135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.381373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-04T01:44:15.820154Z","caller":"traceutil/trace.go:171","msg":"trace[2013898044] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:3182; }","duration":"439.428775ms","start":"2026-05-04T01:44:15.380709Z","end":"2026-05-04T01:44:15.820138Z","steps":["trace[2013898044] 'agreement among raft nodes before linearized reading' (duration: 439.373394ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:44:15.820167Z","caller":"traceutil/trace.go:171","msg":"trace[290532262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3182; }","duration":"461.441815ms","start":"2026-05-04T01:44:15.358716Z","end":"2026-05-04T01:44:15.820158Z","steps":["trace[290532262] 'agreement among raft nodes before linearized reading' (duration: 461.370733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.820197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.358702Z","time spent":"461.487046ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T01:44:15.820205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.380696Z","time spent":"439.498407ms","remote":"127.0.0.1:35662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-05-04T01:44:15.820055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.108587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-04T01:44:15.820284Z","caller":"traceutil/trace.go:171","msg":"trace[1549625078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3182; }","duration":"467.364864ms","start":"2026-05-04T01:44:15.352911Z","end":"2026-05-04T01:44:15.820276Z","steps":["trace[1549625078] 'agreement among raft nodes before linearized reading' (duration: 466.994863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.82031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.352896Z","time spent":"467.407425ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T01:44:15.820324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.72969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-04T01:44:15.820361Z","caller":"traceutil/trace.go:171","msg":"trace[792110985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3182; }","duration":"463.800812ms","start":"2026-05-04T01:44:15.356551Z","end":"2026-05-04T01:44:15.820352Z","steps":["trace[792110985] 'agreement among raft nodes before linearized reading' (duration: 463.692069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.820388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.356532Z","time spent":"463.848093ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-04T01:44:15.820041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.822671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-05-04T01:44:15.820449Z","caller":"traceutil/trace.go:171","msg":"trace[1431997984] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3182; }","duration":"544.257414ms","start":"2026-05-04T01:44:15.276186Z","end":"2026-05-04T01:44:15.820443Z","steps":["trace[1431997984] 'agreement among raft nodes before linearized reading' (duration: 543.703428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:15.820472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:44:15.27611Z","time spent":"544.355087ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11518,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-04T01:44:15.820512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.622595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-04T01:44:15.820536Z","caller":"traceutil/trace.go:171","msg":"trace[1642568629] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3182; }","duration":"270.665295ms","start":"2026-05-04T01:44:15.549863Z","end":"2026-05-04T01:44:15.820528Z","steps":["trace[1642568629] 'agreement among raft nodes before linearized reading' (duration: 270.601633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:30.131758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.10834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-04T01:44:30.131918Z","caller":"traceutil/trace.go:171","msg":"trace[1802808516] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3281; }","duration":"145.315786ms","start":"2026-05-04T01:44:29.986576Z","end":"2026-05-04T01:44:30.131892Z","steps":["trace[1802808516] 'range keys from in-memory index tree' (duration: 144.886354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:30.132133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.471302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-05-04T01:44:30.132223Z","caller":"traceutil/trace.go:171","msg":"trace[48320685] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3281; }","duration":"114.551825ms","start":"2026-05-04T01:44:30.017639Z","end":"2026-05-04T01:44:30.132191Z","steps":["trace[48320685] 'range keys from in-memory index tree' (duration: 114.224545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:44:30.132372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.815853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-04T01:44:30.132616Z","caller":"traceutil/trace.go:171","msg":"trace[936190516] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3281; }","duration":"132.077341ms","start":"2026-05-04T01:44:30.000484Z","end":"2026-05-04T01:44:30.132561Z","steps":["trace[936190516] 'range keys from in-memory index tree' (duration: 131.532345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:45:45.537323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.431553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:45:45.537413Z","caller":"traceutil/trace.go:171","msg":"trace[1795044050] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4482; }","duration":"115.582047ms","start":"2026-05-04T01:45:45.421813Z","end":"2026-05-04T01:45:45.537395Z","steps":["trace[1795044050] 'count revisions from in-memory index tree' (duration: 115.34243ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:57.995626Z","caller":"traceutil/trace.go:171","msg":"trace[1897265657] linearizableReadLoop","detail":"{readStateIndex:4917; appliedIndex:4916; }","duration":"123.417236ms","start":"2026-05-04T01:45:57.872188Z","end":"2026-05-04T01:45:57.995605Z","steps":["trace[1897265657] 'read index received' (duration: 123.272022ms)","trace[1897265657] 'applied index is now lower than readState.Index' (duration: 144.454µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:45:57.995727Z","caller":"traceutil/trace.go:171","msg":"trace[1532605493] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"288.495679ms","start":"2026-05-04T01:45:57.707211Z","end":"2026-05-04T01:45:57.995706Z","steps":["trace[1532605493] 'process raft request' (duration: 288.275763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:45:57.995755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.560671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:45:57.995779Z","caller":"traceutil/trace.go:171","msg":"trace[256457635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4716; }","duration":"123.633973ms","start":"2026-05-04T01:45:57.872139Z","end":"2026-05-04T01:45:57.995773Z","steps":["trace[256457635] 'agreement among raft nodes before linearized reading' (duration: 123.580681ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:58.206907Z","caller":"traceutil/trace.go:171","msg":"trace[1128050598] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"207.388787ms","start":"2026-05-04T01:45:57.999485Z","end":"2026-05-04T01:45:58.206874Z","steps":["trace[1128050598] 'process raft request' (duration: 207.190871ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:58.21128Z","caller":"traceutil/trace.go:171","msg":"trace[1600962939] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"209.574578ms","start":"2026-05-04T01:45:58.001686Z","end":"2026-05-04T01:45:58.211261Z","steps":["trace[1600962939] 'process raft request' (duration: 209.502816ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:58.211297Z","caller":"traceutil/trace.go:171","msg":"trace[1193664882] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"209.738472ms","start":"2026-05-04T01:45:58.00152Z","end":"2026-05-04T01:45:58.211259Z","steps":["trace[1193664882] 'process raft request' (duration: 209.557707ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:58.211492Z","caller":"traceutil/trace.go:171","msg":"trace[218447745] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"209.724102ms","start":"2026-05-04T01:45:58.001761Z","end":"2026-05-04T01:45:58.211485Z","steps":["trace[218447745] 'process raft request' (duration: 209.455344ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:45:58.213537Z","caller":"traceutil/trace.go:171","msg":"trace[851924192] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"211.365298ms","start":"2026-05-04T01:45:58.002142Z","end":"2026-05-04T01:45:58.213507Z","steps":["trace[851924192] 'process raft request' (duration: 209.305801ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:46:05.404138Z","caller":"traceutil/trace.go:171","msg":"trace[580636852] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"113.720864ms","start":"2026-05-04T01:46:05.290385Z","end":"2026-05-04T01:46:05.404106Z","steps":["trace[580636852] 'read index received' (duration: 100.71579ms)","trace[580636852] 'applied index is now lower than readState.Index' (duration: 13.004154ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:46:05.404176Z","caller":"traceutil/trace.go:171","msg":"trace[280173680] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"117.887201ms","start":"2026-05-04T01:46:05.286253Z","end":"2026-05-04T01:46:05.40414Z","steps":["trace[280173680] 'process raft request' (duration: 104.877886ms)","trace[280173680] 'compare' (duration: 12.763208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:46:05.404271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.869858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:46:05.404302Z","caller":"traceutil/trace.go:171","msg":"trace[913408418] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4893; }","duration":"113.9538ms","start":"2026-05-04T01:46:05.290338Z","end":"2026-05-04T01:46:05.404292Z","steps":["trace[913408418] 'agreement among raft nodes before linearized reading' (duration: 113.862138ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:49:31.159923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3291} {"level":"info","ts":"2026-05-04T01:49:31.242715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3291,"took":"81.405892ms","hash":3741755447,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":33992704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-04T01:49:31.242787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3741755447,"revision":3291,"compact-revision":-1} {"level":"warn","ts":"2026-05-04T01:51:09.265859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869160778377881,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T01:51:09.504551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.129559904s","expected-duration":"1s"} {"level":"info","ts":"2026-05-04T01:51:09.505681Z","caller":"traceutil/trace.go:171","msg":"trace[1672483798] transaction","detail":"{read_only:false; response_revision:7573; number_of_response:1; }","duration":"1.130734936s","start":"2026-05-04T01:51:08.374903Z","end":"2026-05-04T01:51:09.505637Z","steps":["trace[1672483798] 'process raft request' (duration: 1.130017966s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.505894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:08.374882Z","time spent":"1.130913131s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:51:09.513077Z","caller":"traceutil/trace.go:171","msg":"trace[310803581] transaction","detail":"{read_only:false; response_revision:7574; number_of_response:1; }","duration":"1.064828718s","start":"2026-05-04T01:51:08.448219Z","end":"2026-05-04T01:51:09.513047Z","steps":["trace[310803581] 'process raft request' (duration: 1.064469288s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.513363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:08.44819Z","time spent":"1.065099586s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:51:09.514844Z","caller":"traceutil/trace.go:171","msg":"trace[1547288683] linearizableReadLoop","detail":"{readStateIndex:7912; appliedIndex:7910; }","duration":"749.08187ms","start":"2026-05-04T01:51:08.765742Z","end":"2026-05-04T01:51:09.514824Z","steps":["trace[1547288683] 'read index received' (duration: 739.189897ms)","trace[1547288683] 'applied index is now lower than readState.Index' (duration: 9.890913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:51:09.515222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.475351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T01:51:09.515285Z","caller":"traceutil/trace.go:171","msg":"trace[1683228908] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:7574; }","duration":"749.579604ms","start":"2026-05-04T01:51:08.765694Z","end":"2026-05-04T01:51:09.515274Z","steps":["trace[1683228908] 'agreement among raft nodes before linearized reading' (duration: 749.425199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.515323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.163701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:51:09.515452Z","caller":"traceutil/trace.go:171","msg":"trace[265960384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7574; }","duration":"588.339237ms","start":"2026-05-04T01:51:08.927094Z","end":"2026-05-04T01:51:09.515433Z","steps":["trace[265960384] 'agreement among raft nodes before linearized reading' (duration: 588.153322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:51:09.51538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:08.765676Z","time spent":"749.694046ms","remote":"127.0.0.1:44732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":76,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-04T01:51:09.515489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:51:08.92707Z","time spent":"588.411858ms","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T01:51:53.02857Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020e2.wal"} {"level":"warn","ts":"2026-05-04T01:52:08.942655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.650205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:52:08.942762Z","caller":"traceutil/trace.go:171","msg":"trace[422884340] transaction","detail":"{read_only:false; response_revision:8167; number_of_response:1; }","duration":"376.161104ms","start":"2026-05-04T01:52:08.566582Z","end":"2026-05-04T01:52:08.942743Z","steps":["trace[422884340] 'process raft request' (duration: 56.134328ms)","trace[422884340] 'compare' (duration: 319.146821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:52:08.942831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:08.566561Z","time spent":"376.233375ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:08.946811Z","caller":"traceutil/trace.go:171","msg":"trace[1881827049] transaction","detail":"{read_only:false; response_revision:8168; number_of_response:1; }","duration":"379.813034ms","start":"2026-05-04T01:52:08.566978Z","end":"2026-05-04T01:52:08.946791Z","steps":["trace[1881827049] 'process raft request' (duration: 379.67529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:08.94699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:08.56696Z","time spent":"379.913407ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:08.947066Z","caller":"traceutil/trace.go:171","msg":"trace[1628956246] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"379.69524ms","start":"2026-05-04T01:52:08.567358Z","end":"2026-05-04T01:52:08.947053Z","steps":["trace[1628956246] 'process raft request' (duration: 379.596678ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:08.947105Z","caller":"traceutil/trace.go:171","msg":"trace[2090335368] linearizableReadLoop","detail":"{readStateIndex:8537; appliedIndex:8533; }","duration":"233.060538ms","start":"2026-05-04T01:52:08.714029Z","end":"2026-05-04T01:52:08.94709Z","steps":["trace[2090335368] 'read index received' (duration: 232.553054ms)","trace[2090335368] 'applied index is now lower than readState.Index' (duration: 506.693µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:52:08.947124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:08.567346Z","time spent":"379.750393ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:08.947119Z","caller":"traceutil/trace.go:171","msg":"trace[1571697341] transaction","detail":"{read_only:false; response_revision:8169; number_of_response:1; }","duration":"380.112993ms","start":"2026-05-04T01:52:08.566978Z","end":"2026-05-04T01:52:08.947091Z","steps":["trace[1571697341] 'process raft request' (duration: 379.778483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:08.947233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:08.566962Z","time spent":"380.214235ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T01:52:08.947516Z","caller":"traceutil/trace.go:171","msg":"trace[1845167220] transaction","detail":"{read_only:false; response_revision:8171; number_of_response:1; }","duration":"379.649499ms","start":"2026-05-04T01:52:08.567835Z","end":"2026-05-04T01:52:08.947485Z","steps":["trace[1845167220] 'process raft request' (duration: 379.194247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:08.947606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.563241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4h47z\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-04T01:52:08.94772Z","caller":"traceutil/trace.go:171","msg":"trace[1081753312] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4h47z; range_end:; response_count:1; response_revision:8171; }","duration":"233.740916ms","start":"2026-05-04T01:52:08.713958Z","end":"2026-05-04T01:52:08.947699Z","steps":["trace[1081753312] 'agreement among raft nodes before linearized reading' (duration: 233.408657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:08.947887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:52:08.567823Z","time spent":"379.828074ms","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T01:52:09.612289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.099825ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-04T01:52:10.370912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.30989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T01:52:10.371057Z","caller":"traceutil/trace.go:171","msg":"trace[414499242] linearizableReadLoop","detail":"{readStateIndex:8543; appliedIndex:8542; }","duration":"155.590581ms","start":"2026-05-04T01:52:10.21545Z","end":"2026-05-04T01:52:10.371041Z","steps":["trace[414499242] 'read index received' (duration: 44.098387ms)","trace[414499242] 'applied index is now lower than readState.Index' (duration: 111.491094ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:52:10.371166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.714334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T01:52:10.371215Z","caller":"traceutil/trace.go:171","msg":"trace[498301311] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8176; }","duration":"155.821177ms","start":"2026-05-04T01:52:10.215381Z","end":"2026-05-04T01:52:10.371202Z","steps":["trace[498301311] 'agreement among raft nodes before linearized reading' (duration: 155.706764ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:10.371209Z","caller":"traceutil/trace.go:171","msg":"trace[395062901] transaction","detail":"{read_only:false; response_revision:8176; number_of_response:1; }","duration":"186.102442ms","start":"2026-05-04T01:52:10.185055Z","end":"2026-05-04T01:52:10.371157Z","steps":["trace[395062901] 'process raft request' (duration: 74.459834ms)","trace[395062901] 'compare' (duration: 111.172235ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:52:36.110971Z","caller":"traceutil/trace.go:171","msg":"trace[1560480566] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"188.58331ms","start":"2026-05-04T01:52:35.922365Z","end":"2026-05-04T01:52:36.110948Z","steps":["trace[1560480566] 'process raft request' (duration: 188.430116ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:52:49.243654Z","caller":"traceutil/trace.go:171","msg":"trace[2084017909] linearizableReadLoop","detail":"{readStateIndex:8919; appliedIndex:8918; }","duration":"118.94145ms","start":"2026-05-04T01:52:49.124687Z","end":"2026-05-04T01:52:49.243628Z","steps":["trace[2084017909] 'read index received' (duration: 118.717274ms)","trace[2084017909] 'applied index is now lower than readState.Index' (duration: 222.926µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:52:49.243697Z","caller":"traceutil/trace.go:171","msg":"trace[554070205] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"126.58404ms","start":"2026-05-04T01:52:49.117084Z","end":"2026-05-04T01:52:49.243668Z","steps":["trace[554070205] 'process raft request' (duration: 126.406556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:49.243948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.697195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-04T01:52:49.24403Z","caller":"traceutil/trace.go:171","msg":"trace[2095157057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8534; }","duration":"117.834229ms","start":"2026-05-04T01:52:49.126181Z","end":"2026-05-04T01:52:49.244015Z","steps":["trace[2095157057] 'agreement among raft nodes before linearized reading' (duration: 117.634534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:49.244005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.970024ms","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-05-04T01:52:49.244098Z","caller":"traceutil/trace.go:171","msg":"trace[1872917929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8534; }","duration":"122.120938ms","start":"2026-05-04T01:52:49.121956Z","end":"2026-05-04T01:52:49.244077Z","steps":["trace[1872917929] 'agreement among raft nodes before linearized reading' (duration: 121.82255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:49.244217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.846398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-04T01:52:49.244255Z","caller":"traceutil/trace.go:171","msg":"trace[755895287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8534; }","duration":"105.89504ms","start":"2026-05-04T01:52:49.138348Z","end":"2026-05-04T01:52:49.244243Z","steps":["trace[755895287] 'agreement among raft nodes before linearized reading' (duration: 105.801458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:52:49.24434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.058765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-05-04T01:52:49.244391Z","caller":"traceutil/trace.go:171","msg":"trace[996933388] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8534; }","duration":"106.137337ms","start":"2026-05-04T01:52:49.138238Z","end":"2026-05-04T01:52:49.244376Z","steps":["trace[996933388] 'agreement among raft nodes before linearized reading' (duration: 105.988663ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T01:54:20.398111Z","caller":"traceutil/trace.go:171","msg":"trace[1463996781] transaction","detail":"{read_only:false; response_revision:9421; number_of_response:1; }","duration":"143.444774ms","start":"2026-05-04T01:54:20.254636Z","end":"2026-05-04T01:54:20.39808Z","steps":["trace[1463996781] 'process raft request' (duration: 80.250643ms)","trace[1463996781] 'compare' (duration: 62.995416ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:54:20.580688Z","caller":"traceutil/trace.go:171","msg":"trace[912532177] linearizableReadLoop","detail":"{readStateIndex:9849; appliedIndex:9848; }","duration":"176.472019ms","start":"2026-05-04T01:54:20.404154Z","end":"2026-05-04T01:54:20.580626Z","steps":["trace[912532177] 'read index received' (duration: 111.199711ms)","trace[912532177] 'applied index is now lower than readState.Index' (duration: 65.268358ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T01:54:20.580747Z","caller":"traceutil/trace.go:171","msg":"trace[1194536932] transaction","detail":"{read_only:false; number_of_response:0; response_revision:9421; }","duration":"325.765876ms","start":"2026-05-04T01:54:20.254937Z","end":"2026-05-04T01:54:20.580703Z","steps":["trace[1194536932] 'process raft request' (duration: 260.405925ms)","trace[1194536932] 'compare' (duration: 64.794296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T01:54:20.58098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.807849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-05-04T01:54:20.581033Z","caller":"traceutil/trace.go:171","msg":"trace[1204804359] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9422; }","duration":"176.922922ms","start":"2026-05-04T01:54:20.404093Z","end":"2026-05-04T01:54:20.581016Z","steps":["trace[1204804359] 'agreement among raft nodes before linearized reading' (duration: 176.696376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T01:54:20.581255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T01:54:20.254925Z","time spent":"325.90476ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T01:54:31.166627Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6652} {"level":"info","ts":"2026-05-04T01:54:31.257908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6652,"took":"89.287494ms","hash":562293429,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":26873856,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-04T01:54:31.257973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":562293429,"revision":6652,"compact-revision":3291} {"level":"info","ts":"2026-05-04T01:54:35.007039Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T01:54:35.012932Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-04T01:54:35.013089Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-04T01:59:31.173304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9520} {"level":"info","ts":"2026-05-04T01:59:31.253805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9520,"took":"77.682614ms","hash":2131070883,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":27111424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-04T01:59:31.253873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2131070883,"revision":9520,"compact-revision":6652} {"level":"info","ts":"2026-05-04T02:04:31.180441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12220} {"level":"info","ts":"2026-05-04T02:04:31.271169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12220,"took":"87.787122ms","hash":431096466,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":25292800,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-04T02:04:31.271248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":431096466,"revision":12220,"compact-revision":9520} {"level":"info","ts":"2026-05-04T02:05:10.427793Z","caller":"traceutil/trace.go:171","msg":"trace[962272547] transaction","detail":"{read_only:false; response_revision:15016; number_of_response:1; }","duration":"111.074601ms","start":"2026-05-04T02:05:10.316694Z","end":"2026-05-04T02:05:10.427769Z","steps":["trace[962272547] 'process raft request' (duration: 110.888486ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:09:31.187351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14664} {"level":"info","ts":"2026-05-04T02:09:31.275698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14664,"took":"85.965425ms","hash":1351689512,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":23248896,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-04T02:09:31.275755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1351689512,"revision":14664,"compact-revision":12220} {"level":"info","ts":"2026-05-04T02:12:05.802382Z","caller":"traceutil/trace.go:171","msg":"trace[127417946] transaction","detail":"{read_only:false; response_revision:17719; number_of_response:1; }","duration":"169.756556ms","start":"2026-05-04T02:12:05.632596Z","end":"2026-05-04T02:12:05.802353Z","steps":["trace[127417946] 'process raft request' (duration: 169.55949ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:14:31.193675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16718} {"level":"info","ts":"2026-05-04T02:14:31.276245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16718,"took":"80.149822ms","hash":1954757272,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":22257664,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-04T02:14:31.276347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1954757272,"revision":16718,"compact-revision":14664} {"level":"info","ts":"2026-05-04T02:15:30.256472Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T02:15:30.261113Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-04T02:15:30.261234Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-04T02:19:31.198737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18658} {"level":"info","ts":"2026-05-04T02:19:31.263344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18658,"took":"62.313099ms","hash":2517565802,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24047616,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:19:31.26341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2517565802,"revision":18658,"compact-revision":16718} {"level":"info","ts":"2026-05-04T02:24:31.20528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20599} {"level":"info","ts":"2026-05-04T02:24:31.273956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20599,"took":"66.08627ms","hash":1148285268,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24109056,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:24:31.274029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1148285268,"revision":20599,"compact-revision":18658} {"level":"warn","ts":"2026-05-04T02:24:49.062239Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869160778421602,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:49.816183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869160778421602,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:50.317226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869160778421602,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:50.817364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880869160778421602,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T02:24:50.946197Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.557774263s","expected-duration":"1s"} {"level":"info","ts":"2026-05-04T02:24:50.9467Z","caller":"traceutil/trace.go:171","msg":"trace[2052126225] transaction","detail":"{read_only:false; response_revision:22648; number_of_response:1; }","duration":"2.558355189s","start":"2026-05-04T02:24:48.38831Z","end":"2026-05-04T02:24:50.946665Z","steps":["trace[2052126225] 'process raft request' (duration: 2.558239156s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.946843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.388294Z","time spent":"2.558477633s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T02:24:50.987923Z","caller":"traceutil/trace.go:171","msg":"trace[653582290] transaction","detail":"{read_only:false; response_revision:22649; number_of_response:1; }","duration":"2.190242863s","start":"2026-05-04T02:24:48.797645Z","end":"2026-05-04T02:24:50.987888Z","steps":["trace[653582290] 'process raft request' (duration: 2.189984196s)"],"step_count":1} {"level":"info","ts":"2026-05-04T02:24:50.987977Z","caller":"traceutil/trace.go:171","msg":"trace[591851588] transaction","detail":"{read_only:false; response_revision:22650; number_of_response:1; }","duration":"1.360321129s","start":"2026-05-04T02:24:49.627618Z","end":"2026-05-04T02:24:50.987939Z","steps":["trace[591851588] 'process raft request' (duration: 1.360190215s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.988091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.79762Z","time spent":"2.190374746s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T02:24:50.988131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.627602Z","time spent":"1.360448593s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T02:24:50.990711Z","caller":"traceutil/trace.go:171","msg":"trace[958098866] linearizableReadLoop","detail":"{readStateIndex:23836; appliedIndex:23832; }","duration":"2.428569205s","start":"2026-05-04T02:24:48.562124Z","end":"2026-05-04T02:24:50.990694Z","steps":["trace[958098866] 'read index received' (duration: 2.384450371s)","trace[958098866] 'applied index is now lower than readState.Index' (duration: 44.118034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T02:24:50.990878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548958063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T02:24:50.990901Z","caller":"traceutil/trace.go:171","msg":"trace[778537683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22650; }","duration":"1.549005775s","start":"2026-05-04T02:24:49.441886Z","end":"2026-05-04T02:24:50.990892Z","steps":["trace[778537683] 'agreement among raft nodes before linearized reading' (duration: 1.548945863s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.990918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.441877Z","time spent":"1.549036666s","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T02:24:50.991021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.893121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4h47z\" ","response":"range_response_count:1 size:6532"} {"level":"warn","ts":"2026-05-04T02:24:50.991037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123884208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-04T02:24:50.991087Z","caller":"traceutil/trace.go:171","msg":"trace[2102358571] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4h47z; range_end:; response_count:1; response_revision:22650; }","duration":"847.014274ms","start":"2026-05-04T02:24:50.144058Z","end":"2026-05-04T02:24:50.991072Z","steps":["trace[2102358571] 'agreement among raft nodes before linearized reading' (duration: 846.807258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.991065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426178122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-04T02:24:50.991102Z","caller":"traceutil/trace.go:171","msg":"trace[1665127949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22650; }","duration":"1.123989771s","start":"2026-05-04T02:24:49.867096Z","end":"2026-05-04T02:24:50.991086Z","steps":["trace[1665127949] 'agreement among raft nodes before linearized reading' (duration: 1.123834587s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.991134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:50.144041Z","time spent":"847.081155ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-4h47z\" "} {"level":"warn","ts":"2026-05-04T02:24:50.991156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.867079Z","time spent":"1.124063034s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-04T02:24:50.991156Z","caller":"traceutil/trace.go:171","msg":"trace[217909066] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:22650; }","duration":"1.426313017s","start":"2026-05-04T02:24:49.564819Z","end":"2026-05-04T02:24:50.991132Z","steps":["trace[217909066] 'agreement among raft nodes before linearized reading' (duration: 1.426144882s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.991203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.564809Z","time spent":"1.426383438s","remote":"127.0.0.1:41800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-04T02:24:50.991238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.252881707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-05-04T02:24:50.991339Z","caller":"traceutil/trace.go:171","msg":"trace[1466710056] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:22650; }","duration":"2.253020201s","start":"2026-05-04T02:24:48.738291Z","end":"2026-05-04T02:24:50.991311Z","steps":["trace[1466710056] 'agreement among raft nodes before linearized reading' (duration: 2.252739244s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.991424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.738285Z","time spent":"2.253118444s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-05-04T02:24:50.991459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.254575355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-04T02:24:50.991538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.113579081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-04T02:24:50.991566Z","caller":"traceutil/trace.go:171","msg":"trace[699774786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22650; }","duration":"2.254719038s","start":"2026-05-04T02:24:48.736827Z","end":"2026-05-04T02:24:50.991546Z","steps":["trace[699774786] 'agreement among raft nodes before linearized reading' (duration: 2.25441851s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.99128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55607859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-05-04T02:24:50.991588Z","caller":"traceutil/trace.go:171","msg":"trace[100855354] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:22650; }","duration":"2.113670314s","start":"2026-05-04T02:24:48.877907Z","end":"2026-05-04T02:24:50.991577Z","steps":["trace[100855354] 'agreement among raft nodes before linearized reading' (duration: 2.113598892s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.991624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.736815Z","time spent":"2.254799831s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T02:24:50.991627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.877887Z","time spent":"2.113732645s","remote":"127.0.0.1:44608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-04T02:24:50.991634Z","caller":"traceutil/trace.go:171","msg":"trace[1105088486] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:22650; }","duration":"1.556497761s","start":"2026-05-04T02:24:49.435116Z","end":"2026-05-04T02:24:50.991614Z","steps":["trace[1105088486] 'agreement among raft nodes before linearized reading' (duration: 1.556081759s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.991409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.261923966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-05-04T02:24:50.991969Z","caller":"traceutil/trace.go:171","msg":"trace[1518403017] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22650; }","duration":"2.262444501s","start":"2026-05-04T02:24:48.729417Z","end":"2026-05-04T02:24:50.991862Z","steps":["trace[1518403017] 'agreement among raft nodes before linearized reading' (duration: 2.261414652s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.991733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.435097Z","time spent":"1.556618385s","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-04T02:24:50.991774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.254491512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-04T02:24:50.99215Z","caller":"traceutil/trace.go:171","msg":"trace[288420152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22650; }","duration":"2.254860592s","start":"2026-05-04T02:24:48.737268Z","end":"2026-05-04T02:24:50.992129Z","steps":["trace[288420152] 'agreement among raft nodes before linearized reading' (duration: 2.25442064s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.737241Z","time spent":"2.254952315s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T02:24:50.992237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.430107857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-04T02:24:50.992312Z","caller":"traceutil/trace.go:171","msg":"trace[1751068292] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:22650; }","duration":"2.43021478s","start":"2026-05-04T02:24:48.562074Z","end":"2026-05-04T02:24:50.992289Z","steps":["trace[1751068292] 'agreement among raft nodes before linearized reading' (duration: 2.429286985s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.562057Z","time spent":"2.430296863s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-04T02:24:50.992376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154029928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-05-04T02:24:50.992417Z","caller":"traceutil/trace.go:171","msg":"trace[207777814] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22650; }","duration":"1.154097851s","start":"2026-05-04T02:24:49.838306Z","end":"2026-05-04T02:24:50.992404Z","steps":["trace[207777814] 'agreement among raft nodes before linearized reading' (duration: 1.152585889s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.992456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:49.838293Z","time spent":"1.154156222s","remote":"127.0.0.1:44494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-04T02:24:50.99204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.729394Z","time spent":"2.262627566s","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11519,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-04T02:24:50.994069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.2555061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-04T02:24:50.994165Z","caller":"traceutil/trace.go:171","msg":"trace[853528160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22650; }","duration":"2.258358008s","start":"2026-05-04T02:24:48.735787Z","end":"2026-05-04T02:24:50.994145Z","steps":["trace[853528160] 'agreement among raft nodes before linearized reading' (duration: 2.25551497s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T02:24:50.994208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T02:24:48.735769Z","time spent":"2.25842485s","remote":"127.0.0.1:44644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-04T02:29:31.216672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22539} {"level":"info","ts":"2026-05-04T02:29:31.282437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22539,"took":"63.763146ms","hash":3202477444,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24182784,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:29:31.282505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3202477444,"revision":22539,"compact-revision":20599} {"level":"info","ts":"2026-05-04T02:34:31.222762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24492} {"level":"info","ts":"2026-05-04T02:34:31.294579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24492,"took":"70.09045ms","hash":972430973,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24170496,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:34:31.294637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":972430973,"revision":24492,"compact-revision":22539} {"level":"info","ts":"2026-05-04T02:39:31.23094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26434} {"level":"info","ts":"2026-05-04T02:39:31.301616Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26434,"took":"68.320701ms","hash":2438270082,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":24104960,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-04T02:39:31.301686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2438270082,"revision":26434,"compact-revision":24492} {"level":"info","ts":"2026-05-04T02:39:43.939421Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-04T02:39:43.946169Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-04T02:39:43.946291Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}