{"level":"warn","ts":"2026-08-22T14:35:19.428123Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:35:19.428203Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-22T14:35:19.428256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:35:19.428263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-22T14:35:19.428287Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-22T14:35:19.429073Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-08-22T14:35:19.429155Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-22T14:35:19.431462Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.117317ms"} {"level":"info","ts":"2026-08-22T14:35:19.437725Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-08-22T14:35:19.437846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:35:19.437879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-08-22T14:35:19.4379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:35:19.437911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-08-22T14:35:19.437971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-08-22T14:35:19.443479Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:35:19.445282Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:35:19.446846Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-22T14:35:19.449113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:35:19.44929Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:35:19.449324Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T14:35:19.449447Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T14:35:19.449461Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-22T14:35:19.450485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-08-22T14:35:19.450753Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-22T14:35:19.45206Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-22T14:35:19.452137Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-22T14:35:19.452188Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-22T14:35:19.452341Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:35:19.452414Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:35:20.438218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:35:20.438284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:35:20.438305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-08-22T14:35:20.438317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:35:20.438322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-22T14:35:20.43833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-08-22T14:35:20.438338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-22T14:35:20.439028Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:20.440438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:35:20.44044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:35:20.440513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:35:20.441109Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:20.441223Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:35:20.441266Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:35:20.441383Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:20.441907Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:35:20.443753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:35:20.444486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-08-22T14:36:02.069182Z","caller":"traceutil/trace.go:171","msg":"trace[1292992238] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"204.430825ms","start":"2026-08-22T14:36:01.864721Z","end":"2026-08-22T14:36:02.069152Z","steps":["trace[1292992238] 'process raft request' (duration: 200.780649ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:02.523328Z","caller":"traceutil/trace.go:171","msg":"trace[1134320988] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"118.520554ms","start":"2026-08-22T14:36:02.404779Z","end":"2026-08-22T14:36:02.5233Z","steps":["trace[1134320988] 'process raft request' (duration: 118.273488ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:02.995359Z","caller":"traceutil/trace.go:171","msg":"trace[798615935] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"146.689943ms","start":"2026-08-22T14:36:02.848638Z","end":"2026-08-22T14:36:02.995328Z","steps":["trace[798615935] 'read index received' (duration: 145.82996ms)","trace[798615935] 'applied index is now lower than readState.Index' (duration: 858.933µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:02.995491Z","caller":"traceutil/trace.go:171","msg":"trace[430975063] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"156.149251ms","start":"2026-08-22T14:36:02.839318Z","end":"2026-08-22T14:36:02.995467Z","steps":["trace[430975063] 'process raft request' (duration: 155.952665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:02.995522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.873737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:02.995595Z","caller":"traceutil/trace.go:171","msg":"trace[1373329901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"147.010272ms","start":"2026-08-22T14:36:02.848576Z","end":"2026-08-22T14:36:02.995586Z","steps":["trace[1373329901] 'agreement among raft nodes before linearized reading' (duration: 146.885978ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:02.995409Z","caller":"traceutil/trace.go:171","msg":"trace[72888216] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"438.120907ms","start":"2026-08-22T14:36:02.557259Z","end":"2026-08-22T14:36:02.99538Z","steps":["trace[72888216] 'process raft request' (duration: 437.46993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:02.996404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:36:02.557229Z","time spent":"438.476307ms","remote":"127.0.0.1:58888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:36:59.651423Z","caller":"traceutil/trace.go:171","msg":"trace[822427518] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"116.202984ms","start":"2026-08-22T14:36:59.535199Z","end":"2026-08-22T14:36:59.651402Z","steps":["trace[822427518] 'process raft request' (duration: 116.076261ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:59.654698Z","caller":"traceutil/trace.go:171","msg":"trace[1483369480] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"113.434822ms","start":"2026-08-22T14:36:59.541244Z","end":"2026-08-22T14:36:59.654679Z","steps":["trace[1483369480] 'process raft request' (duration: 113.328819ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:32.915692Z","caller":"traceutil/trace.go:171","msg":"trace[1047827454] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"105.901493ms","start":"2026-08-22T14:37:32.809765Z","end":"2026-08-22T14:37:32.915666Z","steps":["trace[1047827454] 'read index received' (duration: 96.516425ms)","trace[1047827454] 'applied index is now lower than readState.Index' (duration: 9.383578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:32.915752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.381901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:37:32.915788Z","caller":"traceutil/trace.go:171","msg":"trace[1456438964] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"116.902093ms","start":"2026-08-22T14:37:32.79885Z","end":"2026-08-22T14:37:32.915752Z","steps":["trace[1456438964] 'process raft request' (duration: 107.486194ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:32.91586Z","caller":"traceutil/trace.go:171","msg":"trace[776052280] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1849; }","duration":"190.643728ms","start":"2026-08-22T14:37:32.725191Z","end":"2026-08-22T14:37:32.915835Z","steps":["trace[776052280] 'count revisions from in-memory index tree' (duration: 190.287898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:32.915975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.842081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:37:32.916013Z","caller":"traceutil/trace.go:171","msg":"trace[919281207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1850; }","duration":"105.910594ms","start":"2026-08-22T14:37:32.810092Z","end":"2026-08-22T14:37:32.916002Z","steps":["trace[919281207] 'agreement among raft nodes before linearized reading' (duration: 105.748239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:32.915997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.215982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T14:37:32.916078Z","caller":"traceutil/trace.go:171","msg":"trace[1547682218] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1850; }","duration":"106.351375ms","start":"2026-08-22T14:37:32.809704Z","end":"2026-08-22T14:37:32.916055Z","steps":["trace[1547682218] 'agreement among raft nodes before linearized reading' (duration: 106.126389ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:48.642686Z","caller":"traceutil/trace.go:171","msg":"trace[919779323] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"155.0761ms","start":"2026-08-22T14:37:48.487581Z","end":"2026-08-22T14:37:48.642657Z","steps":["trace[919779323] 'process raft request' (duration: 147.533761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:21.59377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.364481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:38:21.593949Z","caller":"traceutil/trace.go:171","msg":"trace[523206346] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2169; }","duration":"212.633197ms","start":"2026-08-22T14:38:21.38128Z","end":"2026-08-22T14:38:21.593913Z","steps":["trace[523206346] 'range keys from in-memory index tree' (duration: 212.208866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:39:11.36492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.201129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:39:11.365207Z","caller":"traceutil/trace.go:171","msg":"trace[669973699] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"172.472169ms","start":"2026-08-22T14:39:11.1927Z","end":"2026-08-22T14:39:11.365172Z","steps":["trace[669973699] 'process raft request' (duration: 15.812158ms)","trace[669973699] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 155.87975ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:39:45.980301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.433299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:39:45.980429Z","caller":"traceutil/trace.go:171","msg":"trace[533272084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2821; }","duration":"131.644614ms","start":"2026-08-22T14:39:45.848762Z","end":"2026-08-22T14:39:45.980407Z","steps":["trace[533272084] 'range keys from in-memory index tree' (duration: 131.301195ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:39:46.39432Z","caller":"traceutil/trace.go:171","msg":"trace[1918189635] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"171.278813ms","start":"2026-08-22T14:39:46.223009Z","end":"2026-08-22T14:39:46.394288Z","steps":["trace[1918189635] 'process raft request' (duration: 171.075368ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:22.476145Z","caller":"traceutil/trace.go:171","msg":"trace[2008579893] linearizableReadLoop","detail":"{readStateIndex:3981; appliedIndex:3980; }","duration":"137.985592ms","start":"2026-08-22T14:42:22.338117Z","end":"2026-08-22T14:42:22.476103Z","steps":["trace[2008579893] 'read index received' (duration: 121.996271ms)","trace[2008579893] 'applied index is now lower than readState.Index' (duration: 15.988491ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:42:22.476202Z","caller":"traceutil/trace.go:171","msg":"trace[950888545] transaction","detail":"{read_only:false; response_revision:3844; number_of_response:1; }","duration":"183.751126ms","start":"2026-08-22T14:42:22.292428Z","end":"2026-08-22T14:42:22.476179Z","steps":["trace[950888545] 'process raft request' (duration: 167.762305ms)","trace[950888545] 'compare' (duration: 15.813386ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:42:22.476386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.225778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-22T14:42:22.476377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.032123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:42:22.476431Z","caller":"traceutil/trace.go:171","msg":"trace[1253022467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3844; }","duration":"138.32926ms","start":"2026-08-22T14:42:22.338092Z","end":"2026-08-22T14:42:22.476421Z","steps":["trace[1253022467] 'agreement among raft nodes before linearized reading' (duration: 138.163056ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:22.476448Z","caller":"traceutil/trace.go:171","msg":"trace[79537282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3844; }","duration":"127.147246ms","start":"2026-08-22T14:42:22.349284Z","end":"2026-08-22T14:42:22.476431Z","steps":["trace[79537282] 'agreement among raft nodes before linearized reading' (duration: 127.009293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:43.465904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.282422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-22T14:43:43.466071Z","caller":"traceutil/trace.go:171","msg":"trace[1775781871] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5176; }","duration":"133.529299ms","start":"2026-08-22T14:43:43.332516Z","end":"2026-08-22T14:43:43.466045Z","steps":["trace[1775781871] 'read index received' (duration: 18.99358ms)","trace[1775781871] 'applied index is now lower than readState.Index' (duration: 114.533879ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:43.466078Z","caller":"traceutil/trace.go:171","msg":"trace[1023086213] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4995; }","duration":"196.091638ms","start":"2026-08-22T14:43:43.269957Z","end":"2026-08-22T14:43:43.466049Z","steps":["trace[1023086213] 'process raft request' (duration: 81.554849ms)","trace[1023086213] 'compare' (duration: 114.18585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:43:43.466405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.756275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f568b882-41fd-46ef-84b1-47e07d90f498\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-22T14:43:43.466509Z","caller":"traceutil/trace.go:171","msg":"trace[1282736982] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f568b882-41fd-46ef-84b1-47e07d90f498; range_end:; response_count:1; response_revision:4995; }","duration":"134.002892ms","start":"2026-08-22T14:43:43.332488Z","end":"2026-08-22T14:43:43.466491Z","steps":["trace[1282736982] 'agreement among raft nodes before linearized reading' (duration: 133.660623ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:43.470918Z","caller":"traceutil/trace.go:171","msg":"trace[1262545800] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"130.11671ms","start":"2026-08-22T14:43:43.34078Z","end":"2026-08-22T14:43:43.470897Z","steps":["trace[1262545800] 'process raft request' (duration: 129.883004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:43.860176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.449091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:43:43.860568Z","caller":"traceutil/trace.go:171","msg":"trace[1431838429] linearizableReadLoop","detail":"{readStateIndex:5190; appliedIndex:5187; }","duration":"169.036975ms","start":"2026-08-22T14:43:43.691503Z","end":"2026-08-22T14:43:43.860539Z","steps":["trace[1431838429] 'read index received' (duration: 28.193523ms)","trace[1431838429] 'applied index is now lower than readState.Index' (duration: 140.842092ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:43.86064Z","caller":"traceutil/trace.go:171","msg":"trace[1388531388] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"225.975895ms","start":"2026-08-22T14:43:43.634651Z","end":"2026-08-22T14:43:43.860627Z","steps":["trace[1388531388] 'process raft request' (duration: 225.837872ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:43.860552Z","caller":"traceutil/trace.go:171","msg":"trace[693464835] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"227.409304ms","start":"2026-08-22T14:43:43.63311Z","end":"2026-08-22T14:43:43.86052Z","steps":["trace[693464835] 'process raft request' (duration: 86.50164ms)","trace[693464835] 'compare' (duration: 140.120863ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:43.860716Z","caller":"traceutil/trace.go:171","msg":"trace[1658909215] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"226.196141ms","start":"2026-08-22T14:43:43.634384Z","end":"2026-08-22T14:43:43.86058Z","steps":["trace[1658909215] 'process raft request' (duration: 225.966335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:43.860834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.318142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:43:43.860902Z","caller":"traceutil/trace.go:171","msg":"trace[1730285486] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5007; }","duration":"169.436535ms","start":"2026-08-22T14:43:43.69145Z","end":"2026-08-22T14:43:43.860887Z","steps":["trace[1730285486] 'agreement among raft nodes before linearized reading' (duration: 169.285131ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:48.936661Z","caller":"traceutil/trace.go:171","msg":"trace[1571286058] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"105.157092ms","start":"2026-08-22T14:43:48.831471Z","end":"2026-08-22T14:43:48.936628Z","steps":["trace[1571286058] 'process raft request' (duration: 102.582364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:56.217448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.237691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-22T14:43:56.21751Z","caller":"traceutil/trace.go:171","msg":"trace[1768543324] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5291; }","duration":"254.61103ms","start":"2026-08-22T14:43:55.962886Z","end":"2026-08-22T14:43:56.217497Z","steps":["trace[1768543324] 'range keys from in-memory index tree' (duration: 254.019155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:56.217506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.546522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-xzb84\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-22T14:43:56.217581Z","caller":"traceutil/trace.go:171","msg":"trace[64870196] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-xzb84; range_end:; response_count:1; response_revision:5291; }","duration":"256.668255ms","start":"2026-08-22T14:43:55.960895Z","end":"2026-08-22T14:43:56.217563Z","steps":["trace[64870196] 'range keys from in-memory index tree' (duration: 256.177842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:56.218545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.558041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:43:56.218933Z","caller":"traceutil/trace.go:171","msg":"trace[757495099] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"150.840746ms","start":"2026-08-22T14:43:56.067995Z","end":"2026-08-22T14:43:56.218835Z","steps":["trace[757495099] 'process raft request' (duration: 48.782846ms)","trace[757495099] 'compare' (duration: 100.442527ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:43:56.728437Z","caller":"traceutil/trace.go:171","msg":"trace[1127608183] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"501.081426ms","start":"2026-08-22T14:43:56.227335Z","end":"2026-08-22T14:43:56.728416Z","steps":["trace[1127608183] 'process raft request' (duration: 500.925532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:56.728569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:56.22732Z","time spent":"501.182149ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:43:56.728902Z","caller":"traceutil/trace.go:171","msg":"trace[1121262135] linearizableReadLoop","detail":"{readStateIndex:5483; appliedIndex:5483; }","duration":"440.724076ms","start":"2026-08-22T14:43:56.288146Z","end":"2026-08-22T14:43:56.72887Z","steps":["trace[1121262135] 'read index received' (duration: 440.713176ms)","trace[1121262135] 'applied index is now lower than readState.Index' (duration: 9.59µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:43:56.729072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.926361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-54vh2.18ce27c2cea62077\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-08-22T14:43:56.729122Z","caller":"traceutil/trace.go:171","msg":"trace[1357690179] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-54vh2.18ce27c2cea62077; range_end:; response_count:1; response_revision:5293; }","duration":"441.001063ms","start":"2026-08-22T14:43:56.28811Z","end":"2026-08-22T14:43:56.729111Z","steps":["trace[1357690179] 'agreement among raft nodes before linearized reading' (duration: 440.86521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:56.72915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:56.288087Z","time spent":"441.056084ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":928,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-54vh2.18ce27c2cea62077\" "} {"level":"warn","ts":"2026-08-22T14:43:56.72982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.145376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:43:56.729862Z","caller":"traceutil/trace.go:171","msg":"trace[1544899264] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5294; }","duration":"167.191257ms","start":"2026-08-22T14:43:56.56266Z","end":"2026-08-22T14:43:56.729852Z","steps":["trace[1544899264] 'agreement among raft nodes before linearized reading' (duration: 167.100605ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:56.730084Z","caller":"traceutil/trace.go:171","msg":"trace[386904033] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"304.459504ms","start":"2026-08-22T14:43:56.425615Z","end":"2026-08-22T14:43:56.730075Z","steps":["trace[386904033] 'process raft request' (duration: 304.073374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:43:56.730143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:43:56.42559Z","time spent":"304.523586ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:45:20.472403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3010} {"level":"info","ts":"2026-08-22T14:45:20.564526Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3010,"took":"89.371859ms","hash":3204484047,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T14:45:20.564628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3204484047,"revision":3010,"compact-revision":-1} {"level":"info","ts":"2026-08-22T14:47:44.822524Z","caller":"traceutil/trace.go:171","msg":"trace[460260460] transaction","detail":"{read_only:false; response_revision:7057; number_of_response:1; }","duration":"104.662317ms","start":"2026-08-22T14:47:44.71783Z","end":"2026-08-22T14:47:44.822492Z","steps":["trace[460260460] 'process raft request' (duration: 104.439011ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:03.986511Z","caller":"traceutil/trace.go:171","msg":"trace[1924077346] transaction","detail":"{read_only:false; response_revision:8216; number_of_response:1; }","duration":"232.268733ms","start":"2026-08-22T14:50:03.754212Z","end":"2026-08-22T14:50:03.98648Z","steps":["trace[1924077346] 'process raft request' (duration: 232.050617ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:04.058506Z","caller":"traceutil/trace.go:171","msg":"trace[1815489617] linearizableReadLoop","detail":"{readStateIndex:8500; appliedIndex:8498; }","duration":"121.326801ms","start":"2026-08-22T14:50:03.937157Z","end":"2026-08-22T14:50:04.058484Z","steps":["trace[1815489617] 'read index received' (duration: 49.234516ms)","trace[1815489617] 'applied index is now lower than readState.Index' (duration: 72.091625ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:04.058647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.469036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:50:04.058675Z","caller":"traceutil/trace.go:171","msg":"trace[35091180] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8217; }","duration":"121.564208ms","start":"2026-08-22T14:50:03.937103Z","end":"2026-08-22T14:50:04.058667Z","steps":["trace[35091180] 'agreement among raft nodes before linearized reading' (duration: 121.462106ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:04.05863Z","caller":"traceutil/trace.go:171","msg":"trace[500106629] transaction","detail":"{read_only:false; response_revision:8217; number_of_response:1; }","duration":"209.829166ms","start":"2026-08-22T14:50:03.848769Z","end":"2026-08-22T14:50:04.058598Z","steps":["trace[500106629] 'process raft request' (duration: 209.59318ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:17.083477Z","caller":"traceutil/trace.go:171","msg":"trace[1232939539] linearizableReadLoop","detail":"{readStateIndex:8570; appliedIndex:8568; }","duration":"144.170919ms","start":"2026-08-22T14:50:16.939278Z","end":"2026-08-22T14:50:17.083449Z","steps":["trace[1232939539] 'read index received' (duration: 83.027111ms)","trace[1232939539] 'applied index is now lower than readState.Index' (duration: 61.142648ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:17.083728Z","caller":"traceutil/trace.go:171","msg":"trace[1409136896] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"118.842617ms","start":"2026-08-22T14:50:16.964838Z","end":"2026-08-22T14:50:17.08368Z","steps":["trace[1409136896] 'process raft request' (duration: 118.55144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:17.083832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.53099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T14:50:17.084006Z","caller":"traceutil/trace.go:171","msg":"trace[211412967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8285; }","duration":"144.743485ms","start":"2026-08-22T14:50:16.939239Z","end":"2026-08-22T14:50:17.083982Z","steps":["trace[211412967] 'agreement among raft nodes before linearized reading' (duration: 144.467918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:17.08401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.196378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b54f4d57d-kwz9p.18ce281aa2183782\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-08-22T14:50:17.084118Z","caller":"traceutil/trace.go:171","msg":"trace[931371700] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b54f4d57d-kwz9p.18ce281aa2183782; range_end:; response_count:1; response_revision:8285; }","duration":"116.335252ms","start":"2026-08-22T14:50:16.967758Z","end":"2026-08-22T14:50:17.084093Z","steps":["trace[931371700] 'agreement among raft nodes before linearized reading' (duration: 116.082306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:17.084128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.892426ms","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-08-22T14:50:17.084273Z","caller":"traceutil/trace.go:171","msg":"trace[224709032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8285; }","duration":"143.184125ms","start":"2026-08-22T14:50:16.941074Z","end":"2026-08-22T14:50:17.084258Z","steps":["trace[224709032] 'agreement among raft nodes before linearized reading' (duration: 142.695072ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:17.083763Z","caller":"traceutil/trace.go:171","msg":"trace[1266247382] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"145.416332ms","start":"2026-08-22T14:50:16.938266Z","end":"2026-08-22T14:50:17.083683Z","steps":["trace[1266247382] 'process raft request' (duration: 101.029832ms)","trace[1266247382] 'compare' (duration: 43.918068ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:20.480067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6115} {"level":"info","ts":"2026-08-22T14:50:20.579956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6115,"took":"97.12949ms","hash":4176897450,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":23281664,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T14:50:20.580036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4176897450,"revision":6115,"compact-revision":3010} {"level":"info","ts":"2026-08-22T14:50:44.355372Z","caller":"traceutil/trace.go:171","msg":"trace[142985682] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"158.909085ms","start":"2026-08-22T14:50:44.196426Z","end":"2026-08-22T14:50:44.355335Z","steps":["trace[142985682] 'process raft request' (duration: 158.31548ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:45.45486Z","caller":"traceutil/trace.go:171","msg":"trace[736731541] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"185.032588ms","start":"2026-08-22T14:50:45.2698Z","end":"2026-08-22T14:50:45.454833Z","steps":["trace[736731541] 'process raft request' (duration: 103.804344ms)","trace[736731541] 'compare' (duration: 80.935016ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:45.454862Z","caller":"traceutil/trace.go:171","msg":"trace[825130328] linearizableReadLoop","detail":"{readStateIndex:8786; appliedIndex:8785; }","duration":"184.382102ms","start":"2026-08-22T14:50:45.270444Z","end":"2026-08-22T14:50:45.454826Z","steps":["trace[825130328] 'read index received' (duration: 103.056535ms)","trace[825130328] 'applied index is now lower than readState.Index' (duration: 81.324017ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:45.455113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.645708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T14:50:45.455167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.171824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T14:50:45.455197Z","caller":"traceutil/trace.go:171","msg":"trace[1351414490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8491; }","duration":"184.770931ms","start":"2026-08-22T14:50:45.27041Z","end":"2026-08-22T14:50:45.455181Z","steps":["trace[1351414490] 'agreement among raft nodes before linearized reading' (duration: 184.521634ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:45.455249Z","caller":"traceutil/trace.go:171","msg":"trace[473403576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8491; }","duration":"182.283906ms","start":"2026-08-22T14:50:45.272947Z","end":"2026-08-22T14:50:45.455231Z","steps":["trace[473403576] 'agreement among raft nodes before linearized reading' (duration: 182.095531ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:51.379024Z","caller":"traceutil/trace.go:171","msg":"trace[282369855] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"110.767746ms","start":"2026-08-22T14:50:51.268221Z","end":"2026-08-22T14:50:51.378989Z","steps":["trace[282369855] 'process raft request' (duration: 13.990326ms)","trace[282369855] 'compare' (duration: 96.40131ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:51.645042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.706614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:50:51.645161Z","caller":"traceutil/trace.go:171","msg":"trace[680637174] linearizableReadLoop","detail":"{readStateIndex:8819; appliedIndex:8818; }","duration":"162.769586ms","start":"2026-08-22T14:50:51.482373Z","end":"2026-08-22T14:50:51.645142Z","steps":["trace[680637174] 'read index received' (duration: 79.152µs)","trace[680637174] 'applied index is now lower than readState.Index' (duration: 162.689274ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:51.645199Z","caller":"traceutil/trace.go:171","msg":"trace[1651455084] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"259.056554ms","start":"2026-08-22T14:50:51.386107Z","end":"2026-08-22T14:50:51.645163Z","steps":["trace[1651455084] 'process raft request' (duration: 96.134514ms)","trace[1651455084] 'compare' (duration: 162.467958ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:51.645386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.020643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T14:50:51.645418Z","caller":"traceutil/trace.go:171","msg":"trace[1139732963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8523; }","duration":"163.126235ms","start":"2026-08-22T14:50:51.482283Z","end":"2026-08-22T14:50:51.645409Z","steps":["trace[1139732963] 'agreement among raft nodes before linearized reading' (duration: 162.951311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:51.645587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.787477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T14:50:51.645611Z","caller":"traceutil/trace.go:171","msg":"trace[415611647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8523; }","duration":"162.875099ms","start":"2026-08-22T14:50:51.482729Z","end":"2026-08-22T14:50:51.645604Z","steps":["trace[415611647] 'agreement among raft nodes before linearized reading' (duration: 162.810097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:51.64579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.769801ms","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-08-22T14:50:51.645814Z","caller":"traceutil/trace.go:171","msg":"trace[2009392895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8523; }","duration":"158.828223ms","start":"2026-08-22T14:50:51.486979Z","end":"2026-08-22T14:50:51.645808Z","steps":["trace[2009392895] 'agreement among raft nodes before linearized reading' (duration: 158.757681ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.073874Z","caller":"traceutil/trace.go:171","msg":"trace[669865805] linearizableReadLoop","detail":"{readStateIndex:8882; appliedIndex:8881; }","duration":"176.435192ms","start":"2026-08-22T14:50:56.897408Z","end":"2026-08-22T14:50:57.073843Z","steps":["trace[669865805] 'read index received' (duration: 101.525924ms)","trace[669865805] 'applied index is now lower than readState.Index' (duration: 74.907918ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:57.07411Z","caller":"traceutil/trace.go:171","msg":"trace[1167915675] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"190.972643ms","start":"2026-08-22T14:50:56.883121Z","end":"2026-08-22T14:50:57.074094Z","steps":["trace[1167915675] 'process raft request' (duration: 115.845629ms)","trace[1167915675] 'compare' (duration: 74.763134ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:50:57.075162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.729717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112197"} {"level":"info","ts":"2026-08-22T14:50:57.075227Z","caller":"traceutil/trace.go:171","msg":"trace[2080239962] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8585; }","duration":"177.844839ms","start":"2026-08-22T14:50:56.897368Z","end":"2026-08-22T14:50:57.075213Z","steps":["trace[2080239962] 'agreement among raft nodes before linearized reading' (duration: 177.428548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:57.104491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.856324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vlsfw\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-08-22T14:50:57.104847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.797616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b54f4d57d-kwz9p.18ce281aa2183782\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-08-22T14:50:57.104888Z","caller":"traceutil/trace.go:171","msg":"trace[573287374] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b54f4d57d-kwz9p.18ce281aa2183782; range_end:; response_count:1; response_revision:8586; }","duration":"136.853087ms","start":"2026-08-22T14:50:56.968024Z","end":"2026-08-22T14:50:57.104877Z","steps":["trace[573287374] 'agreement among raft nodes before linearized reading' (duration: 136.753835ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.104553Z","caller":"traceutil/trace.go:171","msg":"trace[411191401] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vlsfw; range_end:; response_count:1; response_revision:8586; }","duration":"163.971357ms","start":"2026-08-22T14:50:56.940568Z","end":"2026-08-22T14:50:57.104539Z","steps":["trace[411191401] 'agreement among raft nodes before linearized reading' (duration: 163.832813ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.105377Z","caller":"traceutil/trace.go:171","msg":"trace[1316578235] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"205.301517ms","start":"2026-08-22T14:50:56.900065Z","end":"2026-08-22T14:50:57.105366Z","steps":["trace[1316578235] 'process raft request' (duration: 204.026324ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.328967Z","caller":"traceutil/trace.go:171","msg":"trace[926842325] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8883; }","duration":"216.296075ms","start":"2026-08-22T14:50:57.112643Z","end":"2026-08-22T14:50:57.328939Z","steps":["trace[926842325] 'read index received' (duration: 215.978247ms)","trace[926842325] 'applied index is now lower than readState.Index' (duration: 316.498µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:57.329184Z","caller":"traceutil/trace.go:171","msg":"trace[580253823] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"220.689911ms","start":"2026-08-22T14:50:57.10846Z","end":"2026-08-22T14:50:57.32915Z","steps":["trace[580253823] 'process raft request' (duration: 220.183408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:57.329465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.099189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:50:57.329554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.271361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-22T14:50:57.329594Z","caller":"traceutil/trace.go:171","msg":"trace[917844275] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:0; response_revision:8587; }","duration":"214.265283ms","start":"2026-08-22T14:50:57.115297Z","end":"2026-08-22T14:50:57.329563Z","steps":["trace[917844275] 'agreement among raft nodes before linearized reading' (duration: 214.035947ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.329607Z","caller":"traceutil/trace.go:171","msg":"trace[812793232] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8587; }","duration":"178.393525ms","start":"2026-08-22T14:50:57.151193Z","end":"2026-08-22T14:50:57.329586Z","steps":["trace[812793232] 'agreement among raft nodes before linearized reading' (duration: 178.19213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:57.329723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.070266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228490"} {"level":"info","ts":"2026-08-22T14:50:57.32978Z","caller":"traceutil/trace.go:171","msg":"trace[1788154769] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8587; }","duration":"217.163598ms","start":"2026-08-22T14:50:57.112604Z","end":"2026-08-22T14:50:57.329767Z","steps":["trace[1788154769] 'agreement among raft nodes before linearized reading' (duration: 216.721946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:57.329934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.149896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:50:57.329977Z","caller":"traceutil/trace.go:171","msg":"trace[1201851863] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8587; }","duration":"163.247049ms","start":"2026-08-22T14:50:57.166715Z","end":"2026-08-22T14:50:57.329962Z","steps":["trace[1201851863] 'agreement among raft nodes before linearized reading' (duration: 163.153157ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.555559Z","caller":"traceutil/trace.go:171","msg":"trace[414624101] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"221.43261ms","start":"2026-08-22T14:50:57.334099Z","end":"2026-08-22T14:50:57.555531Z","steps":["trace[414624101] 'process raft request' (duration: 221.240945ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.569748Z","caller":"traceutil/trace.go:171","msg":"trace[397443662] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"234.963404ms","start":"2026-08-22T14:50:57.334748Z","end":"2026-08-22T14:50:57.569711Z","steps":["trace[397443662] 'process raft request' (duration: 234.721008ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.569873Z","caller":"traceutil/trace.go:171","msg":"trace[643155142] linearizableReadLoop","detail":"{readStateIndex:8886; appliedIndex:8884; }","duration":"217.408464ms","start":"2026-08-22T14:50:57.352431Z","end":"2026-08-22T14:50:57.56984Z","steps":["trace[643155142] 'read index received' (duration: 202.979897ms)","trace[643155142] 'applied index is now lower than readState.Index' (duration: 14.427077ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:50:57.569919Z","caller":"traceutil/trace.go:171","msg":"trace[1892141599] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"171.34314ms","start":"2026-08-22T14:50:57.39856Z","end":"2026-08-22T14:50:57.569903Z","steps":["trace[1892141599] 'process raft request' (duration: 171.081673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:50:57.570086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.953607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:50:57.570091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.658741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-08-22T14:50:57.570126Z","caller":"traceutil/trace.go:171","msg":"trace[949563133] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8590; }","duration":"217.775365ms","start":"2026-08-22T14:50:57.352342Z","end":"2026-08-22T14:50:57.570117Z","steps":["trace[949563133] 'agreement among raft nodes before linearized reading' (duration: 217.631141ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:50:57.570129Z","caller":"traceutil/trace.go:171","msg":"trace[941372624] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8590; }","duration":"125.05735ms","start":"2026-08-22T14:50:57.445057Z","end":"2026-08-22T14:50:57.570115Z","steps":["trace[941372624] 'agreement among raft nodes before linearized reading' (duration: 124.964467ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:51:35.782851Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002430.wal"} {"level":"info","ts":"2026-08-22T14:52:15.972843Z","caller":"traceutil/trace.go:171","msg":"trace[1135215287] transaction","detail":"{read_only:false; response_revision:9290; number_of_response:1; }","duration":"111.451264ms","start":"2026-08-22T14:52:15.861365Z","end":"2026-08-22T14:52:15.972816Z","steps":["trace[1135215287] 'process raft request' (duration: 111.29663ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:52:56.965424Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T14:52:56.976956Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T14:52:56.977121Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T14:55:20.497426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8310} {"level":"info","ts":"2026-08-22T14:55:20.578972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8310,"took":"78.758386ms","hash":2495424524,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":25722880,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T14:55:20.579082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2495424524,"revision":8310,"compact-revision":6115} {"level":"warn","ts":"2026-08-22T14:55:53.65799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.336513ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:55:53.658165Z","caller":"traceutil/trace.go:171","msg":"trace[1090073237] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:11209; }","duration":"112.529468ms","start":"2026-08-22T14:55:53.545614Z","end":"2026-08-22T14:55:53.658144Z","steps":["trace[1090073237] 'range keys from in-memory index tree' (duration: 112.318642ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:55:53.782264Z","caller":"traceutil/trace.go:171","msg":"trace[1503638380] transaction","detail":"{read_only:false; response_revision:11213; number_of_response:1; }","duration":"119.595593ms","start":"2026-08-22T14:55:53.662638Z","end":"2026-08-22T14:55:53.782234Z","steps":["trace[1503638380] 'process raft request' (duration: 105.151026ms)","trace[1503638380] 'compare' (duration: 14.131839ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:00:20.504008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10836} {"level":"info","ts":"2026-08-22T15:00:20.592071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10836,"took":"84.123517ms","hash":575507827,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":27713536,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T15:00:20.592205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":575507827,"revision":10836,"compact-revision":8310} {"level":"info","ts":"2026-08-22T15:02:14.455394Z","caller":"traceutil/trace.go:171","msg":"trace[859736139] transaction","detail":"{read_only:false; response_revision:13764; number_of_response:1; }","duration":"174.984969ms","start":"2026-08-22T15:02:14.280392Z","end":"2026-08-22T15:02:14.455377Z","steps":["trace[859736139] 'process raft request' (duration: 174.890036ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:14.522946Z","caller":"traceutil/trace.go:171","msg":"trace[243843328] linearizableReadLoop","detail":"{readStateIndex:14232; appliedIndex:14230; }","duration":"148.938679ms","start":"2026-08-22T15:02:14.373991Z","end":"2026-08-22T15:02:14.52293Z","steps":["trace[243843328] 'read index received' (duration: 81.263422ms)","trace[243843328] 'applied index is now lower than readState.Index' (duration: 67.674807ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:02:14.523031Z","caller":"traceutil/trace.go:171","msg":"trace[2113295167] transaction","detail":"{read_only:false; response_revision:13765; number_of_response:1; }","duration":"219.446481ms","start":"2026-08-22T15:02:14.303563Z","end":"2026-08-22T15:02:14.52301Z","steps":["trace[2113295167] 'process raft request' (duration: 217.799428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:02:14.523094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.097794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vlsfw\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T15:02:14.523113Z","caller":"traceutil/trace.go:171","msg":"trace[1319468985] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vlsfw; range_end:; response_count:1; response_revision:13765; }","duration":"149.161255ms","start":"2026-08-22T15:02:14.373947Z","end":"2026-08-22T15:02:14.523108Z","steps":["trace[1319468985] 'agreement among raft nodes before linearized reading' (duration: 149.049452ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:28.745687Z","caller":"traceutil/trace.go:171","msg":"trace[1851774230] transaction","detail":"{read_only:false; response_revision:13851; number_of_response:1; }","duration":"258.146478ms","start":"2026-08-22T15:02:28.487514Z","end":"2026-08-22T15:02:28.74566Z","steps":["trace[1851774230] 'process raft request' (duration: 257.981293ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:28.747859Z","caller":"traceutil/trace.go:171","msg":"trace[1691576396] linearizableReadLoop","detail":"{readStateIndex:14322; appliedIndex:14320; }","duration":"117.432305ms","start":"2026-08-22T15:02:28.630399Z","end":"2026-08-22T15:02:28.747831Z","steps":["trace[1691576396] 'read index received' (duration: 115.131245ms)","trace[1691576396] 'applied index is now lower than readState.Index' (duration: 2.29972ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:02:28.748138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.716832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vlsfw\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T15:02:28.748197Z","caller":"traceutil/trace.go:171","msg":"trace[819045170] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vlsfw; range_end:; response_count:1; response_revision:13852; }","duration":"117.840975ms","start":"2026-08-22T15:02:28.630338Z","end":"2026-08-22T15:02:28.748179Z","steps":["trace[819045170] 'agreement among raft nodes before linearized reading' (duration: 117.614199ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:28.748623Z","caller":"traceutil/trace.go:171","msg":"trace[336744612] transaction","detail":"{read_only:false; response_revision:13852; number_of_response:1; }","duration":"180.797358ms","start":"2026-08-22T15:02:28.567807Z","end":"2026-08-22T15:02:28.748605Z","steps":["trace[336744612] 'process raft request' (duration: 179.905395ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:49.872126Z","caller":"traceutil/trace.go:171","msg":"trace[836321747] transaction","detail":"{read_only:false; response_revision:14159; number_of_response:1; }","duration":"111.961152ms","start":"2026-08-22T15:02:49.760137Z","end":"2026-08-22T15:02:49.872098Z","steps":["trace[836321747] 'process raft request' (duration: 111.812738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:02:50.102837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.03685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e736219d-bd81-4525-a556-4907c968fd25\" ","response":"range_response_count:1 size:3754"} {"level":"info","ts":"2026-08-22T15:02:50.102977Z","caller":"traceutil/trace.go:171","msg":"trace[1576355568] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e736219d-bd81-4525-a556-4907c968fd25; range_end:; response_count:1; response_revision:14159; }","duration":"159.189634ms","start":"2026-08-22T15:02:49.943725Z","end":"2026-08-22T15:02:50.102914Z","steps":["trace[1576355568] 'range keys from in-memory index tree' (duration: 158.948698ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:47.544171Z","caller":"traceutil/trace.go:171","msg":"trace[2041803969] transaction","detail":"{read_only:false; response_revision:14812; number_of_response:1; }","duration":"270.425859ms","start":"2026-08-22T15:03:47.273712Z","end":"2026-08-22T15:03:47.544137Z","steps":["trace[2041803969] 'process raft request' (duration: 270.214383ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:47.584636Z","caller":"traceutil/trace.go:171","msg":"trace[1167076868] linearizableReadLoop","detail":"{readStateIndex:15314; appliedIndex:15312; }","duration":"290.02396ms","start":"2026-08-22T15:03:47.294587Z","end":"2026-08-22T15:03:47.584611Z","steps":["trace[1167076868] 'read index received' (duration: 249.242726ms)","trace[1167076868] 'applied index is now lower than readState.Index' (duration: 40.779704ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:03:47.584816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.195764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:03:47.584886Z","caller":"traceutil/trace.go:171","msg":"trace[819137307] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14813; }","duration":"290.291437ms","start":"2026-08-22T15:03:47.294583Z","end":"2026-08-22T15:03:47.584874Z","steps":["trace[819137307] 'agreement among raft nodes before linearized reading' (duration: 290.134072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:03:47.584988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.448837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:03:47.585064Z","caller":"traceutil/trace.go:171","msg":"trace[1405693143] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:14813; }","duration":"173.54745ms","start":"2026-08-22T15:03:47.411498Z","end":"2026-08-22T15:03:47.585045Z","steps":["trace[1405693143] 'agreement among raft nodes before linearized reading' (duration: 173.437187ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:47.584802Z","caller":"traceutil/trace.go:171","msg":"trace[1565973446] transaction","detail":"{read_only:false; response_revision:14813; number_of_response:1; }","duration":"292.600627ms","start":"2026-08-22T15:03:47.292083Z","end":"2026-08-22T15:03:47.584684Z","steps":["trace[1565973446] 'process raft request' (duration: 262.960593ms)","trace[1565973446] 'compare' (duration: 29.337366ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:03:47.585088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.288347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-22T15:03:47.585204Z","caller":"traceutil/trace.go:171","msg":"trace[1967475233] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14813; }","duration":"290.452821ms","start":"2026-08-22T15:03:47.294728Z","end":"2026-08-22T15:03:47.585181Z","steps":["trace[1967475233] 'agreement among raft nodes before linearized reading' (duration: 290.093051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:03:47.58559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.55694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-22T15:03:47.585634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.365132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:03:47.585697Z","caller":"traceutil/trace.go:171","msg":"trace[1421884584] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14813; }","duration":"284.446454ms","start":"2026-08-22T15:03:47.301239Z","end":"2026-08-22T15:03:47.585686Z","steps":["trace[1421884584] 'agreement among raft nodes before linearized reading' (duration: 284.365302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:03:47.585914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.581372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-08-22T15:03:47.585953Z","caller":"traceutil/trace.go:171","msg":"trace[1750295026] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14813; }","duration":"288.628763ms","start":"2026-08-22T15:03:47.297313Z","end":"2026-08-22T15:03:47.585942Z","steps":["trace[1750295026] 'agreement among raft nodes before linearized reading' (duration: 288.529191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:03:47.58606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.209374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T15:03:47.585636Z","caller":"traceutil/trace.go:171","msg":"trace[1055511498] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14813; }","duration":"278.614852ms","start":"2026-08-22T15:03:47.307008Z","end":"2026-08-22T15:03:47.585623Z","steps":["trace[1055511498] 'agreement among raft nodes before linearized reading' (duration: 278.469348ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:47.586131Z","caller":"traceutil/trace.go:171","msg":"trace[1064391316] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:14813; }","duration":"180.319367ms","start":"2026-08-22T15:03:47.405792Z","end":"2026-08-22T15:03:47.586112Z","steps":["trace[1064391316] 'agreement among raft nodes before linearized reading' (duration: 179.870015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:03:47.586466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.099707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vlsfw\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T15:03:47.586526Z","caller":"traceutil/trace.go:171","msg":"trace[1777419009] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vlsfw; range_end:; response_count:1; response_revision:14813; }","duration":"204.193599ms","start":"2026-08-22T15:03:47.382317Z","end":"2026-08-22T15:03:47.586511Z","steps":["trace[1777419009] 'agreement among raft nodes before linearized reading' (duration: 204.051306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:03:47.589557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.850651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T15:03:47.58963Z","caller":"traceutil/trace.go:171","msg":"trace[1753936476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14813; }","duration":"113.962634ms","start":"2026-08-22T15:03:47.475649Z","end":"2026-08-22T15:03:47.589612Z","steps":["trace[1753936476] 'agreement among raft nodes before linearized reading' (duration: 111.028258ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:05:20.51434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13014} {"level":"info","ts":"2026-08-22T15:05:20.591843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13014,"took":"75.059139ms","hash":2249792026,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":30724096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T15:05:20.591899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2249792026,"revision":13014,"compact-revision":10836} {"level":"info","ts":"2026-08-22T15:09:33.573041Z","caller":"traceutil/trace.go:171","msg":"trace[2115503874] transaction","detail":"{read_only:false; response_revision:17425; number_of_response:1; }","duration":"159.023331ms","start":"2026-08-22T15:09:33.413985Z","end":"2026-08-22T15:09:33.573008Z","steps":["trace[2115503874] 'process raft request' (duration: 158.547698ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:09:33.573039Z","caller":"traceutil/trace.go:171","msg":"trace[408491189] transaction","detail":"{read_only:false; response_revision:17426; number_of_response:1; }","duration":"158.900908ms","start":"2026-08-22T15:09:33.414105Z","end":"2026-08-22T15:09:33.573006Z","steps":["trace[408491189] 'process raft request' (duration: 158.782925ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:09:33.573507Z","caller":"traceutil/trace.go:171","msg":"trace[599397793] transaction","detail":"{read_only:false; response_revision:17427; number_of_response:1; }","duration":"157.11234ms","start":"2026-08-22T15:09:33.416376Z","end":"2026-08-22T15:09:33.573489Z","steps":["trace[599397793] 'process raft request' (duration: 156.579016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:09:34.249531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.742406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:09:34.24965Z","caller":"traceutil/trace.go:171","msg":"trace[768128001] linearizableReadLoop","detail":"{readStateIndex:18016; appliedIndex:18015; }","duration":"402.038214ms","start":"2026-08-22T15:09:33.847594Z","end":"2026-08-22T15:09:34.249633Z","steps":["trace[768128001] 'read index received' (duration: 195.082732ms)","trace[768128001] 'applied index is now lower than readState.Index' (duration: 206.954062ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:09:34.249767Z","caller":"traceutil/trace.go:171","msg":"trace[1273442705] transaction","detail":"{read_only:false; response_revision:17430; number_of_response:1; }","duration":"419.734885ms","start":"2026-08-22T15:09:33.829988Z","end":"2026-08-22T15:09:34.249723Z","steps":["trace[1273442705] 'process raft request' (duration: 212.699601ms)","trace[1273442705] 'compare' (duration: 206.169771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:09:34.249976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:09:33.829963Z","time spent":"419.90834ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:09:34.25017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.557457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:09:34.250714Z","caller":"traceutil/trace.go:171","msg":"trace[272718064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17430; }","duration":"403.150962ms","start":"2026-08-22T15:09:33.84754Z","end":"2026-08-22T15:09:34.250691Z","steps":["trace[272718064] 'agreement among raft nodes before linearized reading' (duration: 402.523046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:09:34.250902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:09:33.847518Z","time spent":"403.368688ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T15:09:34.343039Z","caller":"traceutil/trace.go:171","msg":"trace[105244905] transaction","detail":"{read_only:false; response_revision:17431; number_of_response:1; }","duration":"268.469878ms","start":"2026-08-22T15:09:34.074529Z","end":"2026-08-22T15:09:34.342999Z","steps":["trace[105244905] 'process raft request' (duration: 268.369905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:09:34.343478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.745941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-58l7f\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-08-22T15:09:34.343609Z","caller":"traceutil/trace.go:171","msg":"trace[1097429989] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-58l7f; range_end:; response_count:1; response_revision:17431; }","duration":"151.909485ms","start":"2026-08-22T15:09:34.191673Z","end":"2026-08-22T15:09:34.343582Z","steps":["trace[1097429989] 'agreement among raft nodes before linearized reading' (duration: 151.485064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:09:34.34386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.984446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lcfr9\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-08-22T15:09:34.343918Z","caller":"traceutil/trace.go:171","msg":"trace[509116461] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lcfr9; range_end:; response_count:1; response_revision:17431; }","duration":"465.096619ms","start":"2026-08-22T15:09:33.878807Z","end":"2026-08-22T15:09:34.343904Z","steps":["trace[509116461] 'agreement among raft nodes before linearized reading' (duration: 464.856903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:09:34.343952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:09:33.878784Z","time spent":"465.16051ms","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22155,"request content":"key:\"/registry/pods/openstack/nova-compute-default-lcfr9\" "} {"level":"warn","ts":"2026-08-22T15:09:34.390387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.402046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:09:34.390486Z","caller":"traceutil/trace.go:171","msg":"trace[1918972402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17432; }","duration":"137.55935ms","start":"2026-08-22T15:09:34.252904Z","end":"2026-08-22T15:09:34.390464Z","steps":["trace[1918972402] 'agreement among raft nodes before linearized reading' (duration: 137.317024ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:10:20.521831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15427} {"level":"info","ts":"2026-08-22T15:10:20.621322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15427,"took":"96.281013ms","hash":945010010,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":33669120,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T15:10:20.621447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":945010010,"revision":15427,"compact-revision":13014} {"level":"info","ts":"2026-08-22T15:13:25.342122Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T15:13:25.350864Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T15:13:25.350997Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T15:15:00.235095Z","caller":"traceutil/trace.go:171","msg":"trace[1213201541] transaction","detail":"{read_only:false; response_revision:19967; number_of_response:1; }","duration":"125.41478ms","start":"2026-08-22T15:15:00.109647Z","end":"2026-08-22T15:15:00.235062Z","steps":["trace[1213201541] 'process raft request' (duration: 125.191914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:15:03.456028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.426694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:15:03.456102Z","caller":"traceutil/trace.go:171","msg":"trace[318560184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19996; }","duration":"137.568628ms","start":"2026-08-22T15:15:03.318518Z","end":"2026-08-22T15:15:03.456086Z","steps":["trace[318560184] 'range keys from in-memory index tree' (duration: 137.251719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:15:03.456095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.831794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T15:15:03.456199Z","caller":"traceutil/trace.go:171","msg":"trace[766580995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19996; }","duration":"127.999818ms","start":"2026-08-22T15:15:03.328175Z","end":"2026-08-22T15:15:03.456174Z","steps":["trace[766580995] 'range keys from in-memory index tree' (duration: 127.576837ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:15:03.633119Z","caller":"traceutil/trace.go:171","msg":"trace[1697606531] transaction","detail":"{read_only:false; response_revision:19997; number_of_response:1; }","duration":"172.433137ms","start":"2026-08-22T15:15:03.460656Z","end":"2026-08-22T15:15:03.633089Z","steps":["trace[1697606531] 'process raft request' (duration: 172.229461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:15:03.907897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.499159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:15:03.908066Z","caller":"traceutil/trace.go:171","msg":"trace[1868601210] linearizableReadLoop","detail":"{readStateIndex:20667; appliedIndex:20665; }","duration":"352.212815ms","start":"2026-08-22T15:15:03.555827Z","end":"2026-08-22T15:15:03.908039Z","steps":["trace[1868601210] 'read index received' (duration: 77.07842ms)","trace[1868601210] 'applied index is now lower than readState.Index' (duration: 275.132475ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:15:03.908113Z","caller":"traceutil/trace.go:171","msg":"trace[693992695] transaction","detail":"{read_only:false; response_revision:19998; number_of_response:1; }","duration":"446.811182ms","start":"2026-08-22T15:15:03.461246Z","end":"2026-08-22T15:15:03.908058Z","steps":["trace[693992695] 'process raft request' (duration: 292.004095ms)","trace[693992695] 'compare' (duration: 154.024727ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:15:03.908244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.4025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T15:15:03.908319Z","caller":"traceutil/trace.go:171","msg":"trace[2146331062] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:19998; }","duration":"352.517822ms","start":"2026-08-22T15:15:03.555783Z","end":"2026-08-22T15:15:03.908301Z","steps":["trace[2146331062] 'agreement among raft nodes before linearized reading' (duration: 352.329127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:15:03.908322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:15:03.461223Z","time spent":"446.989956ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:15:03.908369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:15:03.555733Z","time spent":"352.628566ms","remote":"127.0.0.1:58816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"info","ts":"2026-08-22T15:15:03.90945Z","caller":"traceutil/trace.go:171","msg":"trace[460597593] transaction","detail":"{read_only:false; response_revision:19999; number_of_response:1; }","duration":"127.341481ms","start":"2026-08-22T15:15:03.782089Z","end":"2026-08-22T15:15:03.90943Z","steps":["trace[460597593] 'process raft request' (duration: 127.166416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:15:03.90963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.489342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T15:15:03.909662Z","caller":"traceutil/trace.go:171","msg":"trace[229689732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19999; }","duration":"329.547434ms","start":"2026-08-22T15:15:03.580104Z","end":"2026-08-22T15:15:03.909652Z","steps":["trace[229689732] 'agreement among raft nodes before linearized reading' (duration: 329.300007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:15:03.909683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:15:03.580091Z","time spent":"329.587524ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T15:15:04.1491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.27189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:15:04.149343Z","caller":"traceutil/trace.go:171","msg":"trace[704336393] transaction","detail":"{read_only:false; response_revision:20001; number_of_response:1; }","duration":"204.986345ms","start":"2026-08-22T15:15:03.944338Z","end":"2026-08-22T15:15:04.149324Z","steps":["trace[704336393] 'process raft request' (duration: 204.876883ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:15:04.149396Z","caller":"traceutil/trace.go:171","msg":"trace[1336850825] transaction","detail":"{read_only:false; response_revision:20000; number_of_response:1; }","duration":"234.983828ms","start":"2026-08-22T15:15:03.914314Z","end":"2026-08-22T15:15:04.149298Z","steps":["trace[1336850825] 'process raft request' (duration: 102.145244ms)","trace[1336850825] 'compare' (duration: 132.068134ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:15:04.353707Z","caller":"traceutil/trace.go:171","msg":"trace[5740263] transaction","detail":"{read_only:false; response_revision:20002; number_of_response:1; }","duration":"101.22919ms","start":"2026-08-22T15:15:04.252453Z","end":"2026-08-22T15:15:04.353683Z","steps":["trace[5740263] 'process raft request' (duration: 64.405379ms)","trace[5740263] 'compare' (duration: 36.729288ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:15:04.587798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.067611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T15:15:04.587913Z","caller":"traceutil/trace.go:171","msg":"trace[2075662882] linearizableReadLoop","detail":"{readStateIndex:20673; appliedIndex:20671; }","duration":"290.29872ms","start":"2026-08-22T15:15:04.297595Z","end":"2026-08-22T15:15:04.587894Z","steps":["trace[2075662882] 'read index received' (duration: 19.246092ms)","trace[2075662882] 'applied index is now lower than readState.Index' (duration: 271.051258ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:15:04.587971Z","caller":"traceutil/trace.go:171","msg":"trace[518443259] transaction","detail":"{read_only:false; response_revision:20003; number_of_response:1; }","duration":"335.227452ms","start":"2026-08-22T15:15:04.252694Z","end":"2026-08-22T15:15:04.587922Z","steps":["trace[518443259] 'process raft request' (duration: 219.931316ms)","trace[518443259] 'compare' (duration: 114.919476ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:15:04.588102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.504525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-22T15:15:04.588141Z","caller":"traceutil/trace.go:171","msg":"trace[352447566] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20003; }","duration":"290.566577ms","start":"2026-08-22T15:15:04.297563Z","end":"2026-08-22T15:15:04.58813Z","steps":["trace[352447566] 'agreement among raft nodes before linearized reading' (duration: 290.425563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:15:04.588185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:15:04.252673Z","time spent":"335.390145ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:15:04.588287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.832353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T15:15:04.588403Z","caller":"traceutil/trace.go:171","msg":"trace[512776543] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:20003; }","duration":"276.046789ms","start":"2026-08-22T15:15:04.312335Z","end":"2026-08-22T15:15:04.588382Z","steps":["trace[512776543] 'agreement among raft nodes before linearized reading' (duration: 275.869954ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:15:20.528121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17989} {"level":"info","ts":"2026-08-22T15:15:20.651816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17989,"took":"119.378983ms","hash":2034553845,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":29650944,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T15:15:20.651895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2034553845,"revision":17989,"compact-revision":15427} {"level":"info","ts":"2026-08-22T15:18:49.167911Z","caller":"traceutil/trace.go:171","msg":"trace[1456367549] linearizableReadLoop","detail":"{readStateIndex:23121; appliedIndex:23120; }","duration":"164.570561ms","start":"2026-08-22T15:18:49.003312Z","end":"2026-08-22T15:18:49.167882Z","steps":["trace[1456367549] 'read index received' (duration: 164.303284ms)","trace[1456367549] 'applied index is now lower than readState.Index' (duration: 266.327µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:18:49.168487Z","caller":"traceutil/trace.go:171","msg":"trace[99814374] transaction","detail":"{read_only:false; response_revision:22377; number_of_response:1; }","duration":"309.85204ms","start":"2026-08-22T15:18:48.858566Z","end":"2026-08-22T15:18:49.168418Z","steps":["trace[99814374] 'process raft request' (duration: 309.133761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:18:49.168861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:18:48.858533Z","time spent":"310.049115ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T15:18:49.168962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.594239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417594"} {"level":"info","ts":"2026-08-22T15:18:49.169148Z","caller":"traceutil/trace.go:171","msg":"trace[1365234303] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:22377; }","duration":"165.843465ms","start":"2026-08-22T15:18:49.00326Z","end":"2026-08-22T15:18:49.169103Z","steps":["trace[1365234303] 'agreement among raft nodes before linearized reading' (duration: 165.186148ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:20:20.538813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20100} {"level":"info","ts":"2026-08-22T15:20:20.658171Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20100,"took":"113.733474ms","hash":3478330382,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":43307008,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-22T15:20:20.658269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3478330382,"revision":20100,"compact-revision":17989} {"level":"info","ts":"2026-08-22T15:25:20.544791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23363} {"level":"info","ts":"2026-08-22T15:25:20.693732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23363,"took":"144.666401ms","hash":2937614120,"current-db-size-bytes":58208256,"current-db-size":"58 MB","current-db-size-in-use-bytes":31297536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T15:25:20.69383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2937614120,"revision":23363,"compact-revision":20100}