{"level":"warn","ts":"2026-08-05T21:42:28.386644Z","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-05T21:42:28.386842Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.99: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.19.213.99:2380","--initial-cluster=instance=https://199.19.213.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.99: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-05T21:42:28.386942Z","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-05T21:42:28.386964Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-08-05T21:42:28.386992Z","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-05T21:42:28.388079Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"]} {"level":"info","ts":"2026-08-05T21:42:28.388239Z","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.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.99: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-05T21:42:28.391936Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.409798ms"} {"level":"info","ts":"2026-08-05T21:42:28.403473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4da37caf00b87dfe","cluster-id":"221bed930aa36d2e"} {"level":"info","ts":"2026-08-05T21:42:28.403615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T21:42:28.403643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 0"} {"level":"info","ts":"2026-08-05T21:42:28.403657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4da37caf00b87dfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T21:42:28.403667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 1"} {"level":"info","ts":"2026-08-05T21:42:28.403723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"warn","ts":"2026-08-05T21:42:28.45654Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T21:42:28.467667Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T21:42:28.472665Z","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-05T21:42:28.476237Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4da37caf00b87dfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T21:42:28.47664Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4da37caf00b87dfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T21:42:28.476799Z","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-05T21:42:28.476909Z","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-05T21:42:28.476922Z","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-05T21:42:28.483592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"info","ts":"2026-08-05T21:42:28.483854Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","added-peer-id":"4da37caf00b87dfe","added-peer-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-08-05T21:42:28.48527Z","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-05T21:42:28.485433Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-08-05T21:42:28.485504Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-08-05T21:42:28.485626Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4da37caf00b87dfe","initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T21:42:28.48568Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T21:42:29.304862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T21:42:29.304928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T21:42:29.304962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgPreVoteResp from 4da37caf00b87dfe at term 1"} {"level":"info","ts":"2026-08-05T21:42:29.304977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became candidate at term 2"} {"level":"info","ts":"2026-08-05T21:42:29.304983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgVoteResp from 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-08-05T21:42:29.304993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became leader at term 2"} {"level":"info","ts":"2026-08-05T21:42:29.305003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4da37caf00b87dfe elected leader 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-08-05T21:42:29.309285Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4da37caf00b87dfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.99:2379]}","request-path":"/0/members/4da37caf00b87dfe/attributes","cluster-id":"221bed930aa36d2e","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T21:42:29.309305Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:42:29.309343Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:29.309385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:42:29.312971Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T21:42:29.3157Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:29.315946Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:29.31599Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:29.32016Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T21:42:29.320297Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T21:42:29.320387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.99:2379"} {"level":"info","ts":"2026-08-05T21:43:10.832266Z","caller":"traceutil/trace.go:171","msg":"trace[1434711834] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"110.452702ms","start":"2026-08-05T21:43:10.721769Z","end":"2026-08-05T21:43:10.832222Z","steps":["trace[1434711834] 'process raft request' (duration: 105.641836ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.832347Z","caller":"traceutil/trace.go:171","msg":"trace[29248674] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"110.208687ms","start":"2026-08-05T21:43:10.722116Z","end":"2026-08-05T21:43:10.832325Z","steps":["trace[29248674] 'process raft request' (duration: 110.059704ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.832444Z","caller":"traceutil/trace.go:171","msg":"trace[821769952] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"106.414192ms","start":"2026-08-05T21:43:10.725997Z","end":"2026-08-05T21:43:10.832412Z","steps":["trace[821769952] 'process raft request' (duration: 106.229338ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.832305Z","caller":"traceutil/trace.go:171","msg":"trace[587887951] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"110.401171ms","start":"2026-08-05T21:43:10.721866Z","end":"2026-08-05T21:43:10.832267Z","steps":["trace[587887951] 'process raft request' (duration: 110.255308ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.832482Z","caller":"traceutil/trace.go:171","msg":"trace[1888477635] transaction","detail":"{read_only:false; number_of_response:0; response_revision:556; }","duration":"105.977543ms","start":"2026-08-05T21:43:10.726454Z","end":"2026-08-05T21:43:10.832431Z","steps":["trace[1888477635] 'process raft request' (duration: 105.80081ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.832583Z","caller":"traceutil/trace.go:171","msg":"trace[681666979] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"104.80328ms","start":"2026-08-05T21:43:10.727753Z","end":"2026-08-05T21:43:10.832556Z","steps":["trace[681666979] 'process raft request' (duration: 104.542075ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.832759Z","caller":"traceutil/trace.go:171","msg":"trace[1987318460] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"102.728678ms","start":"2026-08-05T21:43:10.73002Z","end":"2026-08-05T21:43:10.832749Z","steps":["trace[1987318460] 'process raft request' (duration: 102.475263ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.83289Z","caller":"traceutil/trace.go:171","msg":"trace[916379035] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"102.379491ms","start":"2026-08-05T21:43:10.730423Z","end":"2026-08-05T21:43:10.832802Z","steps":["trace[916379035] 'process raft request' (duration: 102.253318ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:10.833118Z","caller":"traceutil/trace.go:171","msg":"trace[894836154] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"102.504723ms","start":"2026-08-05T21:43:10.730583Z","end":"2026-08-05T21:43:10.833087Z","steps":["trace[894836154] 'process raft request' (duration: 102.146406ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:17.399716Z","caller":"traceutil/trace.go:171","msg":"trace[92963848] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"154.25024ms","start":"2026-08-05T21:43:17.245433Z","end":"2026-08-05T21:43:17.399683Z","steps":["trace[92963848] 'process raft request' (duration: 125.708033ms)","trace[92963848] 'compare' (duration: 28.1818ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:43:17.399799Z","caller":"traceutil/trace.go:171","msg":"trace[283588402] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"100.640545ms","start":"2026-08-05T21:43:17.299132Z","end":"2026-08-05T21:43:17.399772Z","steps":["trace[283588402] 'process raft request' (duration: 100.41069ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:17.988772Z","caller":"traceutil/trace.go:171","msg":"trace[1313164101] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"123.482158ms","start":"2026-08-05T21:43:17.865261Z","end":"2026-08-05T21:43:17.988743Z","steps":["trace[1313164101] 'read index received' (duration: 122.723692ms)","trace[1313164101] 'applied index is now lower than readState.Index' (duration: 757.236µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:43:17.988899Z","caller":"traceutil/trace.go:171","msg":"trace[1948012642] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"141.623215ms","start":"2026-08-05T21:43:17.847207Z","end":"2026-08-05T21:43:17.98883Z","steps":["trace[1948012642] 'process raft request' (duration: 140.850858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:17.989019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.729883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-68nks\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-05T21:43:17.989131Z","caller":"traceutil/trace.go:171","msg":"trace[1008485098] range","detail":"{range_begin:/registry/pods/kube-system/cilium-68nks; range_end:; response_count:1; response_revision:651; }","duration":"123.901417ms","start":"2026-08-05T21:43:17.865216Z","end":"2026-08-05T21:43:17.989117Z","steps":["trace[1008485098] 'agreement among raft nodes before linearized reading' (duration: 123.658962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:18.707731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.077989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:43:18.708607Z","caller":"traceutil/trace.go:171","msg":"trace[1125112825] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"770.105939ms","start":"2026-08-05T21:43:17.938468Z","end":"2026-08-05T21:43:18.708574Z","steps":["trace[1125112825] 'process raft request' (duration: 124.392546ms)","trace[1125112825] 'compare' (duration: 643.925516ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:43:18.708711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:43:17.938437Z","time spent":"770.227501ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:43:18.708889Z","caller":"traceutil/trace.go:171","msg":"trace[1011336379] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"704.075713ms","start":"2026-08-05T21:43:18.004732Z","end":"2026-08-05T21:43:18.708807Z","steps":["trace[1011336379] 'process raft request' (duration: 703.773797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:18.709224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:43:18.004702Z","time spent":"704.38544ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:43:18.742372Z","caller":"traceutil/trace.go:171","msg":"trace[1981878084] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"696.372838ms","start":"2026-08-05T21:43:18.045965Z","end":"2026-08-05T21:43:18.742338Z","steps":["trace[1981878084] 'process raft request' (duration: 696.201624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:18.742565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:43:18.045933Z","time spent":"696.5477ms","remote":"127.0.0.1:48346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:43:33.507555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.862071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:43:33.507835Z","caller":"traceutil/trace.go:171","msg":"trace[615455572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"149.226009ms","start":"2026-08-05T21:43:33.358583Z","end":"2026-08-05T21:43:33.507809Z","steps":["trace[615455572] 'range keys from in-memory index tree' (duration: 148.719448ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:33.650221Z","caller":"traceutil/trace.go:171","msg":"trace[1959781347] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"138.003072ms","start":"2026-08-05T21:43:33.512187Z","end":"2026-08-05T21:43:33.65019Z","steps":["trace[1959781347] 'process raft request' (duration: 137.816278ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:21.402598Z","caller":"traceutil/trace.go:171","msg":"trace[16707915] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"133.422502ms","start":"2026-08-05T21:44:21.269148Z","end":"2026-08-05T21:44:21.40257Z","steps":["trace[16707915] 'process raft request' (duration: 133.225398ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:23.599967Z","caller":"traceutil/trace.go:171","msg":"trace[1513113856] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"101.579033ms","start":"2026-08-05T21:44:23.498352Z","end":"2026-08-05T21:44:23.599931Z","steps":["trace[1513113856] 'read index received' (duration: 47.349036ms)","trace[1513113856] 'applied index is now lower than readState.Index' (duration: 54.228417ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:23.600142Z","caller":"traceutil/trace.go:171","msg":"trace[1408301921] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"102.835398ms","start":"2026-08-05T21:44:23.497258Z","end":"2026-08-05T21:44:23.600093Z","steps":["trace[1408301921] 'process raft request' (duration: 48.391027ms)","trace[1408301921] 'compare' (duration: 54.130765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:44:23.600211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.848618ms","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-05T21:44:23.600253Z","caller":"traceutil/trace.go:171","msg":"trace[517198598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1466; }","duration":"101.96188ms","start":"2026-08-05T21:44:23.498278Z","end":"2026-08-05T21:44:23.60024Z","steps":["trace[517198598] 'agreement among raft nodes before linearized reading' (duration: 101.776417ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:31.561567Z","caller":"traceutil/trace.go:171","msg":"trace[2011487294] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"102.451651ms","start":"2026-08-05T21:44:31.459086Z","end":"2026-08-05T21:44:31.561538Z","steps":["trace[2011487294] 'process raft request' (duration: 102.257438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:49.027868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.428358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T21:44:49.02816Z","caller":"traceutil/trace.go:171","msg":"trace[1353636050] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"119.766954ms","start":"2026-08-05T21:44:48.908368Z","end":"2026-08-05T21:44:49.028135Z","steps":["trace[1353636050] 'range keys from in-memory index tree' (duration: 119.097081ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:49.520753Z","caller":"traceutil/trace.go:171","msg":"trace[2137898351] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"111.059227ms","start":"2026-08-05T21:44:49.409658Z","end":"2026-08-05T21:44:49.520717Z","steps":["trace[2137898351] 'process raft request' (duration: 90.66353ms)","trace[2137898351] 'compare' (duration: 20.246234ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:29.313592Z","caller":"traceutil/trace.go:171","msg":"trace[1388689285] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"345.734023ms","start":"2026-08-05T21:45:28.967807Z","end":"2026-08-05T21:45:29.313541Z","steps":["trace[1388689285] 'process raft request' (duration: 344.777632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:29.313873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:28.967775Z","time spent":"345.963518ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:29.687503Z","caller":"traceutil/trace.go:171","msg":"trace[643052178] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"101.138183ms","start":"2026-08-05T21:45:29.586332Z","end":"2026-08-05T21:45:29.68747Z","steps":["trace[643052178] 'process raft request' (duration: 100.817897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:35.476589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.403649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:45:35.476886Z","caller":"traceutil/trace.go:171","msg":"trace[788435966] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2179; }","duration":"153.767196ms","start":"2026-08-05T21:45:35.323082Z","end":"2026-08-05T21:45:35.47685Z","steps":["trace[788435966] 'count revisions from in-memory index tree' (duration: 153.246567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:35.476759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.126523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:45:35.477076Z","caller":"traceutil/trace.go:171","msg":"trace[1076089089] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2179; }","duration":"102.46236ms","start":"2026-08-05T21:45:35.37456Z","end":"2026-08-05T21:45:35.477022Z","steps":["trace[1076089089] 'count revisions from in-memory index tree' (duration: 101.96903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:35.477313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.017595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T21:45:35.477429Z","caller":"traceutil/trace.go:171","msg":"trace[977881761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2179; }","duration":"136.333622ms","start":"2026-08-05T21:45:35.341068Z","end":"2026-08-05T21:45:35.477402Z","steps":["trace[977881761] 'range keys from in-memory index tree' (duration: 135.470974ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:28.991189Z","caller":"traceutil/trace.go:171","msg":"trace[237945449] linearizableReadLoop","detail":"{readStateIndex:2739; appliedIndex:2738; }","duration":"123.272326ms","start":"2026-08-05T21:46:28.86789Z","end":"2026-08-05T21:46:28.991163Z","steps":["trace[237945449] 'read index received' (duration: 122.993019ms)","trace[237945449] 'applied index is now lower than readState.Index' (duration: 277.517µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:46:28.991265Z","caller":"traceutil/trace.go:171","msg":"trace[1583252915] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"159.571724ms","start":"2026-08-05T21:46:28.831644Z","end":"2026-08-05T21:46:28.991216Z","steps":["trace[1583252915] 'process raft request' (duration: 159.325569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:28.991499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.596192ms","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-05T21:46:28.991532Z","caller":"traceutil/trace.go:171","msg":"trace[315204180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2642; }","duration":"123.773866ms","start":"2026-08-05T21:46:28.867749Z","end":"2026-08-05T21:46:28.991523Z","steps":["trace[315204180] 'agreement among raft nodes before linearized reading' (duration: 123.601023ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:02.872089Z","caller":"traceutil/trace.go:171","msg":"trace[1742855629] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"164.543626ms","start":"2026-08-05T21:47:02.707518Z","end":"2026-08-05T21:47:02.872062Z","steps":["trace[1742855629] 'process raft request' (duration: 164.352212ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:49:12.886222Z","caller":"traceutil/trace.go:171","msg":"trace[345182966] transaction","detail":"{read_only:false; response_revision:3538; number_of_response:1; }","duration":"167.670743ms","start":"2026-08-05T21:49:12.718522Z","end":"2026-08-05T21:49:12.886193Z","steps":["trace[345182966] 'process raft request' (duration: 166.868057ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:49:59.424547Z","caller":"traceutil/trace.go:171","msg":"trace[1933872311] transaction","detail":"{read_only:false; response_revision:3983; number_of_response:1; }","duration":"101.218055ms","start":"2026-08-05T21:49:59.323274Z","end":"2026-08-05T21:49:59.424492Z","steps":["trace[1933872311] 'process raft request' (duration: 100.945019ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:51:25.639414Z","caller":"traceutil/trace.go:171","msg":"trace[1964651801] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"106.746929ms","start":"2026-08-05T21:51:25.532642Z","end":"2026-08-05T21:51:25.639389Z","steps":["trace[1964651801] 'process raft request' (duration: 106.570046ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:51:30.906764Z","caller":"traceutil/trace.go:171","msg":"trace[568829391] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"136.842731ms","start":"2026-08-05T21:51:30.769879Z","end":"2026-08-05T21:51:30.906721Z","steps":["trace[568829391] 'process raft request' (duration: 45.658977ms)","trace[568829391] 'compare' (duration: 91.01878ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:51:50.544019Z","caller":"traceutil/trace.go:171","msg":"trace[15734261] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"111.180729ms","start":"2026-08-05T21:51:50.432811Z","end":"2026-08-05T21:51:50.543992Z","steps":["trace[15734261] 'process raft request' (duration: 111.123148ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:51:50.544105Z","caller":"traceutil/trace.go:171","msg":"trace[2100001909] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"125.721155ms","start":"2026-08-05T21:51:50.418304Z","end":"2026-08-05T21:51:50.544025Z","steps":["trace[2100001909] 'process raft request' (duration: 84.322283ms)","trace[2100001909] 'compare' (duration: 41.139137ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:51:50.72012Z","caller":"traceutil/trace.go:171","msg":"trace[1162292311] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"169.579506ms","start":"2026-08-05T21:51:50.550485Z","end":"2026-08-05T21:51:50.720065Z","steps":["trace[1162292311] 'process raft request' (duration: 135.640367ms)","trace[1162292311] 'compare' (duration: 33.633863ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:51:50.720197Z","caller":"traceutil/trace.go:171","msg":"trace[693551384] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"169.352102ms","start":"2026-08-05T21:51:50.55079Z","end":"2026-08-05T21:51:50.720142Z","steps":["trace[693551384] 'process raft request' (duration: 169.191759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:51:51.338014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.472498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-vgdwh\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-08-05T21:51:51.338109Z","caller":"traceutil/trace.go:171","msg":"trace[880221864] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-vgdwh; range_end:; response_count:1; response_revision:5479; }","duration":"495.625372ms","start":"2026-08-05T21:51:50.84246Z","end":"2026-08-05T21:51:51.338086Z","steps":["trace[880221864] 'range keys from in-memory index tree' (duration: 495.226304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:51:51.338166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:51:50.842396Z","time spent":"495.757454ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7839,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-vgdwh\" "} {"level":"warn","ts":"2026-08-05T21:51:51.338287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.318862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:51:51.338403Z","caller":"traceutil/trace.go:171","msg":"trace[776779651] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:5479; }","duration":"455.519586ms","start":"2026-08-05T21:51:50.882858Z","end":"2026-08-05T21:51:51.338378Z","steps":["trace[776779651] 'count revisions from in-memory index tree' (duration: 455.21482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:51:51.338456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:51:50.882833Z","time spent":"455.610298ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:52:00.254319Z","caller":"traceutil/trace.go:171","msg":"trace[1090759554] linearizableReadLoop","detail":"{readStateIndex:5892; appliedIndex:5891; }","duration":"247.969569ms","start":"2026-08-05T21:52:00.006326Z","end":"2026-08-05T21:52:00.254296Z","steps":["trace[1090759554] 'read index received' (duration: 247.786295ms)","trace[1090759554] 'applied index is now lower than readState.Index' (duration: 182.284µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:52:00.254462Z","caller":"traceutil/trace.go:171","msg":"trace[630319608] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"276.804285ms","start":"2026-08-05T21:51:59.977616Z","end":"2026-08-05T21:52:00.25442Z","steps":["trace[630319608] 'process raft request' (duration: 276.486689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:52:00.254602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.256384ms","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-05T21:52:00.254637Z","caller":"traceutil/trace.go:171","msg":"trace[1878779687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5685; }","duration":"248.353896ms","start":"2026-08-05T21:52:00.006274Z","end":"2026-08-05T21:52:00.254628Z","steps":["trace[1878779687] 'agreement among raft nodes before linearized reading' (duration: 248.157082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:52:00.352022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.284075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-05T21:52:00.35214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.248009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T21:52:00.352224Z","caller":"traceutil/trace.go:171","msg":"trace[480480473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5687; }","duration":"202.390052ms","start":"2026-08-05T21:52:00.149815Z","end":"2026-08-05T21:52:00.352205Z","steps":["trace[480480473] 'agreement among raft nodes before linearized reading' (duration: 202.213419ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:52:00.352202Z","caller":"traceutil/trace.go:171","msg":"trace[591715831] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"323.494814ms","start":"2026-08-05T21:52:00.028341Z","end":"2026-08-05T21:52:00.351836Z","steps":["trace[591715831] 'process raft request' (duration: 276.028069ms)","trace[591715831] 'compare' (duration: 47.20941ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:52:00.352286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.01476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e474ef6-537b-4c7a-b5da-2f2f08f92812\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-08-05T21:52:00.352398Z","caller":"traceutil/trace.go:171","msg":"trace[1236772913] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7e474ef6-537b-4c7a-b5da-2f2f08f92812; range_end:; response_count:1; response_revision:5687; }","duration":"310.207823ms","start":"2026-08-05T21:52:00.042165Z","end":"2026-08-05T21:52:00.352373Z","steps":["trace[1236772913] 'agreement among raft nodes before linearized reading' (duration: 309.816056ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:52:00.352282Z","caller":"traceutil/trace.go:171","msg":"trace[861443501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5687; }","duration":"185.428488ms","start":"2026-08-05T21:52:00.166658Z","end":"2026-08-05T21:52:00.352087Z","steps":["trace[861443501] 'agreement among raft nodes before linearized reading' (duration: 185.240314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:52:00.352501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:52:00.042139Z","time spent":"310.348107ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7e474ef6-537b-4c7a-b5da-2f2f08f92812\" "} {"level":"warn","ts":"2026-08-05T21:52:00.352507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:52:00.02831Z","time spent":"324.006614ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:52:00.622615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.859829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:52:00.622805Z","caller":"traceutil/trace.go:171","msg":"trace[1264256771] transaction","detail":"{read_only:false; response_revision:5691; number_of_response:1; }","duration":"186.49031ms","start":"2026-08-05T21:52:00.436297Z","end":"2026-08-05T21:52:00.622787Z","steps":["trace[1264256771] 'process raft request' (duration: 59.367176ms)","trace[1264256771] 'compare' (duration: 126.719376ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:52:00.623014Z","caller":"traceutil/trace.go:171","msg":"trace[1887458998] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"186.50123ms","start":"2026-08-05T21:52:00.436485Z","end":"2026-08-05T21:52:00.622986Z","steps":["trace[1887458998] 'process raft request' (duration: 186.241495ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:52:29.362288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2995} {"level":"info","ts":"2026-08-05T21:52:29.437824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2995,"took":"73.943453ms","hash":1819524800,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":32882688,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T21:52:29.437911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1819524800,"revision":2995,"compact-revision":-1} {"level":"info","ts":"2026-08-05T21:52:52.949542Z","caller":"traceutil/trace.go:171","msg":"trace[35085071] linearizableReadLoop","detail":"{readStateIndex:6378; appliedIndex:6377; }","duration":"226.34216ms","start":"2026-08-05T21:52:52.723164Z","end":"2026-08-05T21:52:52.949506Z","steps":["trace[35085071] 'read index received' (duration: 205.847742ms)","trace[35085071] 'applied index is now lower than readState.Index' (duration: 20.492608ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:52:52.949619Z","caller":"traceutil/trace.go:171","msg":"trace[1204174717] transaction","detail":"{read_only:false; response_revision:6157; number_of_response:1; }","duration":"245.897907ms","start":"2026-08-05T21:52:52.703673Z","end":"2026-08-05T21:52:52.949571Z","steps":["trace[1204174717] 'process raft request' (duration: 225.357799ms)","trace[1204174717] 'compare' (duration: 20.12533ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:52:52.949883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.693926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T21:52:52.949966Z","caller":"traceutil/trace.go:171","msg":"trace[1915995953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6157; }","duration":"226.844059ms","start":"2026-08-05T21:52:52.723102Z","end":"2026-08-05T21:52:52.949946Z","steps":["trace[1915995953] 'agreement among raft nodes before linearized reading' (duration: 226.523963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:52:52.950747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.807621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-08-05T21:52:52.950843Z","caller":"traceutil/trace.go:171","msg":"trace[724085285] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:6157; }","duration":"140.959204ms","start":"2026-08-05T21:52:52.809863Z","end":"2026-08-05T21:52:52.950822Z","steps":["trace[724085285] 'agreement among raft nodes before linearized reading' (duration: 139.851402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:52:53.083082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.724487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-05T21:52:53.083286Z","caller":"traceutil/trace.go:171","msg":"trace[1392696353] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:6159; }","duration":"102.966952ms","start":"2026-08-05T21:52:52.980289Z","end":"2026-08-05T21:52:53.083256Z","steps":["trace[1392696353] 'agreement among raft nodes before linearized reading' (duration: 102.606175ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:52:54.010559Z","caller":"traceutil/trace.go:171","msg":"trace[1921386075] transaction","detail":"{read_only:false; response_revision:6163; number_of_response:1; }","duration":"123.966298ms","start":"2026-08-05T21:52:53.886545Z","end":"2026-08-05T21:52:54.010511Z","steps":["trace[1921386075] 'process raft request' (duration: 26.646782ms)","trace[1921386075] 'compare' (duration: 97.083092ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:52:54.010719Z","caller":"traceutil/trace.go:171","msg":"trace[1090628174] transaction","detail":"{read_only:false; response_revision:6164; number_of_response:1; }","duration":"111.964715ms","start":"2026-08-05T21:52:53.898711Z","end":"2026-08-05T21:52:54.010675Z","steps":["trace[1090628174] 'process raft request' (duration: 111.76103ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:29.369245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5925} {"level":"info","ts":"2026-08-05T21:57:29.456346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5925,"took":"84.664448ms","hash":2030257759,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":26423296,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-05T21:57:29.456421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2030257759,"revision":5925,"compact-revision":2995} {"level":"info","ts":"2026-08-05T21:58:11.457502Z","caller":"traceutil/trace.go:171","msg":"trace[639948429] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"141.650312ms","start":"2026-08-05T21:58:11.315794Z","end":"2026-08-05T21:58:11.457444Z","steps":["trace[639948429] 'process raft request' (duration: 141.454236ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:58:11.597054Z","caller":"traceutil/trace.go:171","msg":"trace[1732897706] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"251.702504ms","start":"2026-08-05T21:58:11.34532Z","end":"2026-08-05T21:58:11.597022Z","steps":["trace[1732897706] 'process raft request' (duration: 250.096134ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:58:25.12875Z","caller":"traceutil/trace.go:171","msg":"trace[401792094] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"128.274713ms","start":"2026-08-05T21:58:25.00044Z","end":"2026-08-05T21:58:25.128714Z","steps":["trace[401792094] 'process raft request' (duration: 59.585924ms)","trace[401792094] 'compare' (duration: 68.538944ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:58:48.210605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078869631182800450,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T21:58:48.23468Z","caller":"traceutil/trace.go:171","msg":"trace[1772306617] transaction","detail":"{read_only:false; response_revision:8726; number_of_response:1; }","duration":"525.823542ms","start":"2026-08-05T21:58:47.708836Z","end":"2026-08-05T21:58:48.23466Z","steps":["trace[1772306617] 'process raft request' (duration: 525.638788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:48.234844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:58:47.70882Z","time spent":"525.940564ms","remote":"127.0.0.1:34800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3059,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:58:48.236776Z","caller":"traceutil/trace.go:171","msg":"trace[1276265047] linearizableReadLoop","detail":"{readStateIndex:9036; appliedIndex:9033; }","duration":"526.464275ms","start":"2026-08-05T21:58:47.710283Z","end":"2026-08-05T21:58:48.236747Z","steps":["trace[1276265047] 'read index received' (duration: 524.223549ms)","trace[1276265047] 'applied index is now lower than readState.Index' (duration: 2.240016ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:58:48.236842Z","caller":"traceutil/trace.go:171","msg":"trace[406135592] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"524.674387ms","start":"2026-08-05T21:58:47.712137Z","end":"2026-08-05T21:58:48.236812Z","steps":["trace[406135592] 'process raft request' (duration: 524.415482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:48.237005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:58:47.712109Z","time spent":"524.81883ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-05T21:58:48.237125Z","caller":"traceutil/trace.go:171","msg":"trace[1525299528] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"456.82363ms","start":"2026-08-05T21:58:47.780279Z","end":"2026-08-05T21:58:48.237102Z","steps":["trace[1525299528] 'process raft request' (duration: 456.400131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:48.237321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:58:47.780247Z","time spent":"456.912382ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:58:48.237463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.168739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:58:48.237502Z","caller":"traceutil/trace.go:171","msg":"trace[1584698713] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:0; response_revision:8728; }","duration":"527.22698ms","start":"2026-08-05T21:58:47.710266Z","end":"2026-08-05T21:58:48.237493Z","steps":["trace[1584698713] 'agreement among raft nodes before linearized reading' (duration: 527.148038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:48.237533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:58:47.710259Z","time spent":"527.26567ms","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-08-05T21:58:48.241712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.287038ms","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-05T21:58:48.241802Z","caller":"traceutil/trace.go:171","msg":"trace[303079629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8729; }","duration":"408.450181ms","start":"2026-08-05T21:58:47.833336Z","end":"2026-08-05T21:58:48.241786Z","steps":["trace[303079629] 'agreement among raft nodes before linearized reading' (duration: 408.223025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:48.241846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:58:47.833311Z","time spent":"408.528153ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T21:58:48.242388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.872982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-05T21:58:48.242422Z","caller":"traceutil/trace.go:171","msg":"trace[2027327646] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8729; }","duration":"100.902952ms","start":"2026-08-05T21:58:48.141507Z","end":"2026-08-05T21:58:48.24241Z","steps":["trace[2027327646] 'agreement among raft nodes before linearized reading' (duration: 100.832211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:48.242546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.284478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-05T21:58:48.242569Z","caller":"traceutil/trace.go:171","msg":"trace[1076349752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8729; }","duration":"228.35034ms","start":"2026-08-05T21:58:48.014213Z","end":"2026-08-05T21:58:48.242563Z","steps":["trace[1076349752] 'agreement among raft nodes before linearized reading' (duration: 228.289548ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:58:54.399548Z","caller":"traceutil/trace.go:171","msg":"trace[1006788592] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"114.845588ms","start":"2026-08-05T21:58:54.284668Z","end":"2026-08-05T21:58:54.399514Z","steps":["trace[1006788592] 'process raft request' (duration: 114.628094ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:58:54.45222Z","caller":"traceutil/trace.go:171","msg":"trace[2032737523] linearizableReadLoop","detail":"{readStateIndex:9086; appliedIndex:9084; }","duration":"105.731211ms","start":"2026-08-05T21:58:54.34646Z","end":"2026-08-05T21:58:54.452192Z","steps":["trace[2032737523] 'read index received' (duration: 52.881321ms)","trace[2032737523] 'applied index is now lower than readState.Index' (duration: 52.84862ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:58:54.452355Z","caller":"traceutil/trace.go:171","msg":"trace[2060977966] transaction","detail":"{read_only:false; response_revision:8776; number_of_response:1; }","duration":"154.646883ms","start":"2026-08-05T21:58:54.297664Z","end":"2026-08-05T21:58:54.452311Z","steps":["trace[2060977966] 'process raft request' (duration: 150.224162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:54.452522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.032128ms","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-05T21:58:54.452653Z","caller":"traceutil/trace.go:171","msg":"trace[2054712129] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8776; }","duration":"106.249233ms","start":"2026-08-05T21:58:54.346375Z","end":"2026-08-05T21:58:54.452624Z","steps":["trace[2054712129] 'agreement among raft nodes before linearized reading' (duration: 105.950647ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:59:06.418115Z","caller":"traceutil/trace.go:171","msg":"trace[364156341] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"178.642183ms","start":"2026-08-05T21:59:06.239445Z","end":"2026-08-05T21:59:06.418087Z","steps":["trace[364156341] 'process raft request' (duration: 178.459109ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:59:06.423872Z","caller":"traceutil/trace.go:171","msg":"trace[1785010180] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8879; }","duration":"182.691365ms","start":"2026-08-05T21:59:06.241163Z","end":"2026-08-05T21:59:06.423854Z","steps":["trace[1785010180] 'process raft request' (duration: 182.347788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:59:07.205815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.94449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-08-05T21:59:07.205909Z","caller":"traceutil/trace.go:171","msg":"trace[1818142195] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8887; }","duration":"115.125993ms","start":"2026-08-05T21:59:07.090759Z","end":"2026-08-05T21:59:07.205885Z","steps":["trace[1818142195] 'range keys from in-memory index tree' (duration: 114.568123ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:59:07.920033Z","caller":"traceutil/trace.go:171","msg":"trace[1700280846] transaction","detail":"{read_only:false; response_revision:8891; number_of_response:1; }","duration":"171.2101ms","start":"2026-08-05T21:59:07.748792Z","end":"2026-08-05T21:59:07.920002Z","steps":["trace[1700280846] 'process raft request' (duration: 171.031486ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:59:28.000083Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002500.wal"} {"level":"info","ts":"2026-08-05T22:00:31.478084Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4da37caf00b87dfe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T22:00:31.483919Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T22:00:31.484015Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T22:02:29.375746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8096} {"level":"info","ts":"2026-08-05T22:02:29.440273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8096,"took":"62.044337ms","hash":2778747773,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":28442624,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T22:02:29.440368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2778747773,"revision":8096,"compact-revision":5925} {"level":"info","ts":"2026-08-05T22:07:29.382603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10544} {"level":"info","ts":"2026-08-05T22:07:29.46433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10544,"took":"78.747608ms","hash":4260494289,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":26996736,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-05T22:07:29.464401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4260494289,"revision":10544,"compact-revision":8096} {"level":"info","ts":"2026-08-05T22:09:28.150219Z","caller":"traceutil/trace.go:171","msg":"trace[104002480] transaction","detail":"{read_only:false; response_revision:13427; number_of_response:1; }","duration":"124.004952ms","start":"2026-08-05T22:09:28.026184Z","end":"2026-08-05T22:09:28.150189Z","steps":["trace[104002480] 'process raft request' (duration: 123.812529ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:09:28.236953Z","caller":"traceutil/trace.go:171","msg":"trace[34685386] transaction","detail":"{read_only:false; response_revision:13428; number_of_response:1; }","duration":"210.369036ms","start":"2026-08-05T22:09:28.026518Z","end":"2026-08-05T22:09:28.236887Z","steps":["trace[34685386] 'process raft request' (duration: 207.941736ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:15.123024Z","caller":"traceutil/trace.go:171","msg":"trace[1886581920] transaction","detail":"{read_only:false; response_revision:14625; number_of_response:1; }","duration":"214.018161ms","start":"2026-08-05T22:12:14.908958Z","end":"2026-08-05T22:12:15.122976Z","steps":["trace[1886581920] 'process raft request' (duration: 213.658423ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:12:29.390686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12723} {"level":"info","ts":"2026-08-05T22:12:29.471188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12723,"took":"77.487663ms","hash":2528772363,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":27717632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T22:12:29.471265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2528772363,"revision":12723,"compact-revision":10544} {"level":"warn","ts":"2026-08-05T22:14:03.244698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.214686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-05T22:14:03.244772Z","caller":"traceutil/trace.go:171","msg":"trace[59504191] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:15872; }","duration":"145.317047ms","start":"2026-08-05T22:14:03.099438Z","end":"2026-08-05T22:14:03.244755Z","steps":["trace[59504191] 'range keys from in-memory index tree' (duration: 145.130843ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:14:03.820237Z","caller":"traceutil/trace.go:171","msg":"trace[1075099976] transaction","detail":"{read_only:false; response_revision:15874; number_of_response:1; }","duration":"103.134715ms","start":"2026-08-05T22:14:03.717075Z","end":"2026-08-05T22:14:03.82021Z","steps":["trace[1075099976] 'process raft request' (duration: 102.939122ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:14:03.829455Z","caller":"traceutil/trace.go:171","msg":"trace[734227352] transaction","detail":"{read_only:false; response_revision:15875; number_of_response:1; }","duration":"110.110249ms","start":"2026-08-05T22:14:03.719321Z","end":"2026-08-05T22:14:03.829432Z","steps":["trace[734227352] 'process raft request' (duration: 109.65893ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:17:29.404213Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14899} {"level":"info","ts":"2026-08-05T22:17:29.508341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14899,"took":"100.453392ms","hash":2674874796,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":30920704,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:17:29.508393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2674874796,"revision":14899,"compact-revision":12723} {"level":"warn","ts":"2026-08-05T22:19:47.594675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.368775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:19:47.594787Z","caller":"traceutil/trace.go:171","msg":"trace[202999554] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:18464; }","duration":"117.555868ms","start":"2026-08-05T22:19:47.477207Z","end":"2026-08-05T22:19:47.594763Z","steps":["trace[202999554] 'count revisions from in-memory index tree' (duration: 117.231662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:47.594797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.579426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/heat-heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:19:47.594847Z","caller":"traceutil/trace.go:171","msg":"trace[1465960073] range","detail":"{range_begin:/registry/rolebindings/openstack/heat-heat-bootstrap; range_end:; response_count:0; response_revision:18464; }","duration":"208.651517ms","start":"2026-08-05T22:19:47.38618Z","end":"2026-08-05T22:19:47.594832Z","steps":["trace[1465960073] 'range keys from in-memory index tree' (duration: 208.515375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:47.595028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.03663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:19:47.595067Z","caller":"traceutil/trace.go:171","msg":"trace[2026474687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18464; }","duration":"197.099411ms","start":"2026-08-05T22:19:47.397957Z","end":"2026-08-05T22:19:47.595056Z","steps":["trace[2026474687] 'range keys from in-memory index tree' (duration: 196.844456ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:47.870622Z","caller":"traceutil/trace.go:171","msg":"trace[618576099] linearizableReadLoop","detail":"{readStateIndex:19095; appliedIndex:19094; }","duration":"270.54711ms","start":"2026-08-05T22:19:47.600046Z","end":"2026-08-05T22:19:47.870593Z","steps":["trace[618576099] 'read index received' (duration: 270.500639ms)","trace[618576099] 'applied index is now lower than readState.Index' (duration: 45.271µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:19:47.870728Z","caller":"traceutil/trace.go:171","msg":"trace[2052750293] transaction","detail":"{read_only:false; response_revision:18465; number_of_response:1; }","duration":"270.702303ms","start":"2026-08-05T22:19:47.599979Z","end":"2026-08-05T22:19:47.870681Z","steps":["trace[2052750293] 'process raft request' (duration: 270.452248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:47.870805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.735804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/heat-heat-db-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:19:47.870872Z","caller":"traceutil/trace.go:171","msg":"trace[272991480] range","detail":"{range_begin:/registry/rolebindings/openstack/heat-heat-db-init; range_end:; response_count:0; response_revision:18465; }","duration":"270.841225ms","start":"2026-08-05T22:19:47.600016Z","end":"2026-08-05T22:19:47.870857Z","steps":["trace[272991480] 'agreement among raft nodes before linearized reading' (duration: 270.726803ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:48.131902Z","caller":"traceutil/trace.go:171","msg":"trace[1848887433] transaction","detail":"{read_only:false; response_revision:18466; number_of_response:1; }","duration":"531.835501ms","start":"2026-08-05T22:19:47.600046Z","end":"2026-08-05T22:19:48.131882Z","steps":["trace[1848887433] 'process raft request' (duration: 531.661808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:48.132102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:19:47.600026Z","time spent":"532.011494ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:19:48.132269Z","caller":"traceutil/trace.go:171","msg":"trace[1340020319] transaction","detail":"{read_only:false; response_revision:18467; number_of_response:1; }","duration":"526.561353ms","start":"2026-08-05T22:19:47.605629Z","end":"2026-08-05T22:19:48.132191Z","steps":["trace[1340020319] 'process raft request' (duration: 526.215776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:48.132571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:19:47.60562Z","time spent":"526.869649ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:19:48.133201Z","caller":"traceutil/trace.go:171","msg":"trace[1963100624] transaction","detail":"{read_only:false; response_revision:18468; number_of_response:1; }","duration":"527.168536ms","start":"2026-08-05T22:19:47.605983Z","end":"2026-08-05T22:19:48.133151Z","steps":["trace[1963100624] 'process raft request' (duration: 526.092084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:48.133284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:19:47.605949Z","time spent":"527.294968ms","remote":"127.0.0.1:48376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:19:48.133561Z","caller":"traceutil/trace.go:171","msg":"trace[485216353] transaction","detail":"{read_only:false; response_revision:18469; number_of_response:1; }","duration":"516.998437ms","start":"2026-08-05T22:19:47.616545Z","end":"2026-08-05T22:19:48.133544Z","steps":["trace[485216353] 'process raft request' (duration: 516.044038ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:48.13351Z","caller":"traceutil/trace.go:171","msg":"trace[2006410554] linearizableReadLoop","detail":"{readStateIndex:19099; appliedIndex:19095; }","duration":"262.771311ms","start":"2026-08-05T22:19:47.870707Z","end":"2026-08-05T22:19:48.133478Z","steps":["trace[2006410554] 'read index received' (duration: 260.938904ms)","trace[2006410554] 'applied index is now lower than readState.Index' (duration: 1.831007ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:19:48.133637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:19:47.616493Z","time spent":"517.10909ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20867,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:19:48.1338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.605228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wcqmp\" ","response":"range_response_count:1 size:22121"} {"level":"warn","ts":"2026-08-05T22:19:48.133809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.166088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T22:19:48.133857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.345411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/heat-heat-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:19:48.1339Z","caller":"traceutil/trace.go:171","msg":"trace[830095695] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:18469; }","duration":"260.290411ms","start":"2026-08-05T22:19:47.873574Z","end":"2026-08-05T22:19:48.133865Z","steps":["trace[830095695] 'agreement among raft nodes before linearized reading' (duration: 260.154278ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:48.133934Z","caller":"traceutil/trace.go:171","msg":"trace[1969420480] range","detail":"{range_begin:/registry/rolebindings/openstack/heat-heat-db-sync; range_end:; response_count:0; response_revision:18469; }","duration":"259.449564ms","start":"2026-08-05T22:19:47.874463Z","end":"2026-08-05T22:19:48.133913Z","steps":["trace[1969420480] 'agreement among raft nodes before linearized reading' (duration: 259.323151ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:48.133844Z","caller":"traceutil/trace.go:171","msg":"trace[121085122] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wcqmp; range_end:; response_count:1; response_revision:18469; }","duration":"336.69687ms","start":"2026-08-05T22:19:47.797135Z","end":"2026-08-05T22:19:48.133832Z","steps":["trace[121085122] 'agreement among raft nodes before linearized reading' (duration: 336.549996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:48.13398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:19:47.797116Z","time spent":"336.854614ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22143,"request content":"key:\"/registry/pods/openstack/nova-compute-default-wcqmp\" "} {"level":"info","ts":"2026-08-05T22:19:56.200104Z","caller":"traceutil/trace.go:171","msg":"trace[1484914722] linearizableReadLoop","detail":"{readStateIndex:19345; appliedIndex:19343; }","duration":"126.97363ms","start":"2026-08-05T22:19:56.07311Z","end":"2026-08-05T22:19:56.200084Z","steps":["trace[1484914722] 'read index received' (duration: 19.756233ms)","trace[1484914722] 'applied index is now lower than readState.Index' (duration: 107.216637ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:19:56.200208Z","caller":"traceutil/trace.go:171","msg":"trace[1895607463] transaction","detail":"{read_only:false; response_revision:18710; number_of_response:1; }","duration":"121.881197ms","start":"2026-08-05T22:19:56.07832Z","end":"2026-08-05T22:19:56.200201Z","steps":["trace[1895607463] 'process raft request' (duration: 121.706944ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:19:56.200282Z","caller":"traceutil/trace.go:171","msg":"trace[1006554994] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18709; }","duration":"127.92012ms","start":"2026-08-05T22:19:56.072333Z","end":"2026-08-05T22:19:56.200254Z","steps":["trace[1006554994] 'process raft request' (duration: 127.612664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:19:56.200437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.312747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-08-05T22:19:56.200474Z","caller":"traceutil/trace.go:171","msg":"trace[663384445] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:18710; }","duration":"127.362088ms","start":"2026-08-05T22:19:56.073101Z","end":"2026-08-05T22:19:56.200463Z","steps":["trace[663384445] 'agreement among raft nodes before linearized reading' (duration: 127.269467ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:13.603887Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4da37caf00b87dfe","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T22:21:13.613717Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T22:21:13.613836Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T22:22:29.41427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17454} {"level":"info","ts":"2026-08-05T22:22:29.521531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17454,"took":"103.785907ms","hash":2168840966,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":34103296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T22:22:29.521621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2168840966,"revision":17454,"compact-revision":14899} {"level":"info","ts":"2026-08-05T22:24:17.418489Z","caller":"traceutil/trace.go:171","msg":"trace[1498812142] transaction","detail":"{read_only:false; response_revision:20644; number_of_response:1; }","duration":"177.410161ms","start":"2026-08-05T22:24:17.241036Z","end":"2026-08-05T22:24:17.418446Z","steps":["trace[1498812142] 'process raft request' (duration: 176.690765ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:24:33.842749Z","caller":"traceutil/trace.go:171","msg":"trace[1192483726] linearizableReadLoop","detail":"{readStateIndex:21432; appliedIndex:21431; }","duration":"162.055057ms","start":"2026-08-05T22:24:33.68067Z","end":"2026-08-05T22:24:33.842725Z","steps":["trace[1192483726] 'read index received' (duration: 76.736426ms)","trace[1192483726] 'applied index is now lower than readState.Index' (duration: 85.317701ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:24:33.84292Z","caller":"traceutil/trace.go:171","msg":"trace[413907401] transaction","detail":"{read_only:false; response_revision:20727; number_of_response:1; }","duration":"165.1634ms","start":"2026-08-05T22:24:33.677674Z","end":"2026-08-05T22:24:33.842838Z","steps":["trace[413907401] 'process raft request' (duration: 79.782968ms)","trace[413907401] 'compare' (duration: 85.131927ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:24:33.843046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.116951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T22:24:33.843067Z","caller":"traceutil/trace.go:171","msg":"trace[1760975585] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20727; }","duration":"123.134311ms","start":"2026-08-05T22:24:33.719923Z","end":"2026-08-05T22:24:33.843058Z","steps":["trace[1760975585] 'agreement among raft nodes before linearized reading' (duration: 123.08111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:24:33.843116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.757049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-05T22:24:33.842983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.303642ms","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-05T22:24:33.843196Z","caller":"traceutil/trace.go:171","msg":"trace[1782648211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20727; }","duration":"157.870522ms","start":"2026-08-05T22:24:33.68531Z","end":"2026-08-05T22:24:33.84318Z","steps":["trace[1782648211] 'agreement among raft nodes before linearized reading' (duration: 157.631747ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:24:33.843205Z","caller":"traceutil/trace.go:171","msg":"trace[2129752211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20727; }","duration":"162.567357ms","start":"2026-08-05T22:24:33.68062Z","end":"2026-08-05T22:24:33.843187Z","steps":["trace[2129752211] 'agreement among raft nodes before linearized reading' (duration: 162.2243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:24:33.843239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.355175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-05T22:24:33.843257Z","caller":"traceutil/trace.go:171","msg":"trace[582858006] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:20727; }","duration":"119.404976ms","start":"2026-08-05T22:24:33.723846Z","end":"2026-08-05T22:24:33.843251Z","steps":["trace[582858006] 'agreement among raft nodes before linearized reading' (duration: 119.348175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:24:33.843363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.506322ms","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-05T22:24:33.843416Z","caller":"traceutil/trace.go:171","msg":"trace[182015188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20727; }","duration":"128.643885ms","start":"2026-08-05T22:24:33.714751Z","end":"2026-08-05T22:24:33.843395Z","steps":["trace[182015188] 'agreement among raft nodes before linearized reading' (duration: 128.569674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:24:34.104992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.254647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:24:34.105088Z","caller":"traceutil/trace.go:171","msg":"trace[1574022951] transaction","detail":"{read_only:false; response_revision:20728; number_of_response:1; }","duration":"257.420042ms","start":"2026-08-05T22:24:33.847653Z","end":"2026-08-05T22:24:34.105073Z","steps":["trace[1574022951] 'process raft request' (duration: 152.014702ms)","trace[1574022951] 'compare' (duration: 105.086774ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:24:34.11572Z","caller":"traceutil/trace.go:171","msg":"trace[1044699669] transaction","detail":"{read_only:false; response_revision:20729; number_of_response:1; }","duration":"267.702161ms","start":"2026-08-05T22:24:33.847993Z","end":"2026-08-05T22:24:34.115695Z","steps":["trace[1044699669] 'process raft request' (duration: 267.464336ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:24:34.115736Z","caller":"traceutil/trace.go:171","msg":"trace[1788769029] linearizableReadLoop","detail":"{readStateIndex:21436; appliedIndex:21432; }","duration":"265.907255ms","start":"2026-08-05T22:24:33.849805Z","end":"2026-08-05T22:24:34.115712Z","steps":["trace[1788769029] 'read index received' (duration: 149.940169ms)","trace[1788769029] 'applied index is now lower than readState.Index' (duration: 115.965896ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:24:34.115795Z","caller":"traceutil/trace.go:171","msg":"trace[174699779] transaction","detail":"{read_only:false; response_revision:20730; number_of_response:1; }","duration":"267.762052ms","start":"2026-08-05T22:24:33.848Z","end":"2026-08-05T22:24:34.115762Z","steps":["trace[174699779] 'process raft request' (duration: 267.593579ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:24:34.115888Z","caller":"traceutil/trace.go:171","msg":"trace[1221783556] transaction","detail":"{read_only:false; response_revision:20732; number_of_response:1; }","duration":"186.355671ms","start":"2026-08-05T22:24:33.929517Z","end":"2026-08-05T22:24:34.115872Z","steps":["trace[1221783556] 'process raft request' (duration: 186.154287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:24:34.115949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.120349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-08-05T22:24:34.115866Z","caller":"traceutil/trace.go:171","msg":"trace[2013399511] transaction","detail":"{read_only:false; response_revision:20731; number_of_response:1; }","duration":"267.67202ms","start":"2026-08-05T22:24:33.848098Z","end":"2026-08-05T22:24:34.11577Z","steps":["trace[2013399511] 'process raft request' (duration: 267.534957ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:24:34.11599Z","caller":"traceutil/trace.go:171","msg":"trace[1870031656] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:20732; }","duration":"266.204641ms","start":"2026-08-05T22:24:33.849771Z","end":"2026-08-05T22:24:34.115975Z","steps":["trace[1870031656] 'agreement among raft nodes before linearized reading' (duration: 266.035998ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:24:34.522625Z","caller":"traceutil/trace.go:171","msg":"trace[604854715] linearizableReadLoop","detail":"{readStateIndex:21438; appliedIndex:21437; }","duration":"166.443105ms","start":"2026-08-05T22:24:34.356161Z","end":"2026-08-05T22:24:34.522604Z","steps":["trace[604854715] 'read index received' (duration: 166.354084ms)","trace[604854715] 'applied index is now lower than readState.Index' (duration: 88.311µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:24:34.522671Z","caller":"traceutil/trace.go:171","msg":"trace[467793296] transaction","detail":"{read_only:false; response_revision:20733; number_of_response:1; }","duration":"206.624965ms","start":"2026-08-05T22:24:34.316014Z","end":"2026-08-05T22:24:34.522639Z","steps":["trace[467793296] 'process raft request' (duration: 206.458462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:24:34.522734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.569099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:24:34.522774Z","caller":"traceutil/trace.go:171","msg":"trace[864716851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20733; }","duration":"166.670051ms","start":"2026-08-05T22:24:34.356093Z","end":"2026-08-05T22:24:34.522763Z","steps":["trace[864716851] 'agreement among raft nodes before linearized reading' (duration: 166.589699ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:24:34.523575Z","caller":"traceutil/trace.go:171","msg":"trace[1288492084] transaction","detail":"{read_only:false; response_revision:20734; number_of_response:1; }","duration":"121.930218ms","start":"2026-08-05T22:24:34.401575Z","end":"2026-08-05T22:24:34.523505Z","steps":["trace[1288492084] 'process raft request' (duration: 121.764325ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:27:29.420001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19892} {"level":"info","ts":"2026-08-05T22:27:29.531783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19892,"took":"108.935171ms","hash":2759541509,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":30134272,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T22:27:29.531835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2759541509,"revision":19892,"compact-revision":17454} {"level":"info","ts":"2026-08-05T22:28:15.337131Z","caller":"traceutil/trace.go:171","msg":"trace[439820906] linearizableReadLoop","detail":"{readStateIndex:23871; appliedIndex:23870; }","duration":"167.243758ms","start":"2026-08-05T22:28:15.169865Z","end":"2026-08-05T22:28:15.337109Z","steps":["trace[439820906] 'read index received' (duration: 156.003389ms)","trace[439820906] 'applied index is now lower than readState.Index' (duration: 11.239469ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:28:15.337241Z","caller":"traceutil/trace.go:171","msg":"trace[1090577979] transaction","detail":"{read_only:false; response_revision:23096; number_of_response:1; }","duration":"241.697756ms","start":"2026-08-05T22:28:15.095533Z","end":"2026-08-05T22:28:15.337231Z","steps":["trace[1090577979] 'process raft request' (duration: 230.368125ms)","trace[1090577979] 'compare' (duration: 11.100056ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:28:15.337429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.560024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T22:28:15.33746Z","caller":"traceutil/trace.go:171","msg":"trace[646335477] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:23096; }","duration":"167.642027ms","start":"2026-08-05T22:28:15.16981Z","end":"2026-08-05T22:28:15.337452Z","steps":["trace[646335477] 'agreement among raft nodes before linearized reading' (duration: 167.510455ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:32:29.426502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22235} {"level":"info","ts":"2026-08-05T22:32:29.531035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22235,"took":"100.708242ms","hash":3662540683,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":41590784,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-05T22:32:29.531118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3662540683,"revision":22235,"compact-revision":19892} {"level":"info","ts":"2026-08-05T22:37:29.432449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25310} {"level":"info","ts":"2026-08-05T22:37:29.566685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25310,"took":"128.987062ms","hash":1933659535,"current-db-size-bytes":57155584,"current-db-size":"57 MB","current-db-size-in-use-bytes":29319168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-05T22:37:29.566749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1933659535,"revision":25310,"compact-revision":22235}