{"level":"warn","ts":"2026-08-18T18:51:58.984917Z","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-18T18:51:58.985006Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.61:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.61:2380","--initial-cluster=instance=https://199.204.45.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.61: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-18T18:51:58.985077Z","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-18T18:51:58.985094Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-18T18:51:58.98512Z","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-18T18:51:58.9859Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"]} {"level":"info","ts":"2026-08-18T18:51:58.986017Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.61: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-18T18:51:58.992296Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.962396ms"} {"level":"info","ts":"2026-08-18T18:51:59.002567Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca256638ea38eb43","cluster-id":"dfd7ac400ae20102"} {"level":"info","ts":"2026-08-18T18:51:59.002686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=()"} {"level":"info","ts":"2026-08-18T18:51:59.002713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 0"} {"level":"info","ts":"2026-08-18T18:51:59.002728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca256638ea38eb43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-18T18:51:59.00274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 1"} {"level":"info","ts":"2026-08-18T18:51:59.002776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"warn","ts":"2026-08-18T18:51:59.007856Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-18T18:51:59.010974Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-18T18:51:59.012581Z","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-18T18:51:59.015233Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca256638ea38eb43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-18T18:51:59.018247Z","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-18T18:51:59.018305Z","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-18T18:51:59.018313Z","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-18T18:51:59.018593Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca256638ea38eb43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-18T18:51:59.020954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"info","ts":"2026-08-18T18:51:59.021118Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","added-peer-id":"ca256638ea38eb43","added-peer-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-18T18:51:59.02125Z","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-18T18:51:59.021508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca256638ea38eb43","initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-18T18:51:59.021561Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-18T18:51:59.021843Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-18T18:51:59.02188Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-18T18:52:00.003068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 is starting a new election at term 1"} {"level":"info","ts":"2026-08-18T18:52:00.003128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-18T18:52:00.003157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgPreVoteResp from ca256638ea38eb43 at term 1"} {"level":"info","ts":"2026-08-18T18:52:00.00318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became candidate at term 2"} {"level":"info","ts":"2026-08-18T18:52:00.003196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgVoteResp from ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-18T18:52:00.003219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became leader at term 2"} {"level":"info","ts":"2026-08-18T18:52:00.003237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca256638ea38eb43 elected leader ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-18T18:52:00.004275Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca256638ea38eb43","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.61:2379]}","request-path":"/0/members/ca256638ea38eb43/attributes","cluster-id":"dfd7ac400ae20102","publish-timeout":"7s"} {"level":"info","ts":"2026-08-18T18:52:00.004441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T18:52:00.004504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T18:52:00.004716Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T18:52:00.005385Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T18:52:00.005488Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T18:52:00.005521Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T18:52:00.006863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.61:2379"} {"level":"info","ts":"2026-08-18T18:52:00.007163Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-18T18:52:00.007191Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-18T18:52:00.009398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-18T18:52:25.955496Z","caller":"traceutil/trace.go:171","msg":"trace[362409724] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"280.326915ms","start":"2026-08-18T18:52:25.675132Z","end":"2026-08-18T18:52:25.955459Z","steps":["trace[362409724] 'process raft request' (duration: 186.323506ms)","trace[362409724] 'compare' (duration: 93.624102ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:52:42.923959Z","caller":"traceutil/trace.go:171","msg":"trace[1093637347] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"135.10699ms","start":"2026-08-18T18:52:42.788808Z","end":"2026-08-18T18:52:42.923915Z","steps":["trace[1093637347] 'process raft request' (duration: 107.188961ms)","trace[1093637347] 'compare' (duration: 27.689504ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:52:42.924034Z","caller":"traceutil/trace.go:171","msg":"trace[1123154488] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"135.170291ms","start":"2026-08-18T18:52:42.788834Z","end":"2026-08-18T18:52:42.924004Z","steps":["trace[1123154488] 'process raft request' (duration: 134.987268ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:52:42.924073Z","caller":"traceutil/trace.go:171","msg":"trace[471993013] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"106.643931ms","start":"2026-08-18T18:52:42.817407Z","end":"2026-08-18T18:52:42.924051Z","steps":["trace[471993013] 'process raft request' (duration: 106.57008ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:52:42.924172Z","caller":"traceutil/trace.go:171","msg":"trace[48676474] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"135.143201ms","start":"2026-08-18T18:52:42.788992Z","end":"2026-08-18T18:52:42.924136Z","steps":["trace[48676474] 'process raft request' (duration: 134.914896ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:52:43.190121Z","caller":"traceutil/trace.go:171","msg":"trace[582164864] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"118.090808ms","start":"2026-08-18T18:52:43.072011Z","end":"2026-08-18T18:52:43.190102Z","steps":["trace[582164864] 'process raft request' (duration: 74.549851ms)","trace[582164864] 'compare' (duration: 43.448136ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:52:43.194009Z","caller":"traceutil/trace.go:171","msg":"trace[842445215] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"118.435454ms","start":"2026-08-18T18:52:43.075561Z","end":"2026-08-18T18:52:43.193997Z","steps":["trace[842445215] 'process raft request' (duration: 118.156869ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:52:43.194024Z","caller":"traceutil/trace.go:171","msg":"trace[2033409589] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"110.014558ms","start":"2026-08-18T18:52:43.083989Z","end":"2026-08-18T18:52:43.194004Z","steps":["trace[2033409589] 'process raft request' (duration: 109.966468ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:52:48.542653Z","caller":"traceutil/trace.go:171","msg":"trace[847299262] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"201.630634ms","start":"2026-08-18T18:52:48.34097Z","end":"2026-08-18T18:52:48.5426Z","steps":["trace[847299262] 'process raft request' (duration: 111.390971ms)","trace[847299262] 'compare' (duration: 90.063249ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:52:49.160843Z","caller":"traceutil/trace.go:171","msg":"trace[897831463] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"187.250434ms","start":"2026-08-18T18:52:48.973563Z","end":"2026-08-18T18:52:49.160813Z","steps":["trace[897831463] 'process raft request' (duration: 181.164133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:52:49.805142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.521988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-18T18:52:49.805263Z","caller":"traceutil/trace.go:171","msg":"trace[876660332] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"142.120037ms","start":"2026-08-18T18:52:49.663128Z","end":"2026-08-18T18:52:49.805248Z","steps":["trace[876660332] 'read index received' (duration: 76.241µs)","trace[876660332] 'applied index is now lower than readState.Index' (duration: 142.042756ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:52:49.805348Z","caller":"traceutil/trace.go:171","msg":"trace[1494692858] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"582.327912ms","start":"2026-08-18T18:52:49.22301Z","end":"2026-08-18T18:52:49.805337Z","steps":["trace[1494692858] 'process raft request' (duration: 348.155452ms)","trace[1494692858] 'compare' (duration: 233.381445ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T18:52:49.805401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:52:49.222993Z","time spent":"582.385042ms","remote":"127.0.0.1:46376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T18:52:49.805533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.402193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T18:52:49.805836Z","caller":"traceutil/trace.go:171","msg":"trace[128623591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"142.754518ms","start":"2026-08-18T18:52:49.663067Z","end":"2026-08-18T18:52:49.805821Z","steps":["trace[128623591] 'agreement among raft nodes before linearized reading' (duration: 142.3102ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:53:31.746321Z","caller":"traceutil/trace.go:171","msg":"trace[778307525] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"269.493779ms","start":"2026-08-18T18:53:31.476793Z","end":"2026-08-18T18:53:31.746287Z","steps":["trace[778307525] 'process raft request' (duration: 269.219334ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:53:46.309213Z","caller":"traceutil/trace.go:171","msg":"trace[1686314640] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"140.860479ms","start":"2026-08-18T18:53:46.16833Z","end":"2026-08-18T18:53:46.309191Z","steps":["trace[1686314640] 'process raft request' (duration: 140.726917ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:53:51.030491Z","caller":"traceutil/trace.go:171","msg":"trace[311278344] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"177.561941ms","start":"2026-08-18T18:53:50.852711Z","end":"2026-08-18T18:53:51.030273Z","steps":["trace[311278344] 'process raft request' (duration: 91.305903ms)","trace[311278344] 'compare' (duration: 86.017405ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:53:51.031017Z","caller":"traceutil/trace.go:171","msg":"trace[1769067703] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"133.66487ms","start":"2026-08-18T18:53:50.897333Z","end":"2026-08-18T18:53:51.030998Z","steps":["trace[1769067703] 'process raft request' (duration: 133.596468ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:53:51.031139Z","caller":"traceutil/trace.go:171","msg":"trace[748490828] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"133.943684ms","start":"2026-08-18T18:53:50.897158Z","end":"2026-08-18T18:53:51.031102Z","steps":["trace[748490828] 'process raft request' (duration: 133.352794ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:53:51.302605Z","caller":"traceutil/trace.go:171","msg":"trace[1512813175] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"100.726519ms","start":"2026-08-18T18:53:51.201848Z","end":"2026-08-18T18:53:51.302574Z","steps":["trace[1512813175] 'process raft request' (duration: 100.382733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:53:51.302989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.903705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf\" ","response":"range_response_count:1 size:4169"} {"level":"info","ts":"2026-08-18T18:53:51.303144Z","caller":"traceutil/trace.go:171","msg":"trace[351209456] range","detail":"{range_begin:/registry/replicasets/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf; range_end:; response_count:1; response_revision:1378; }","duration":"109.147149ms","start":"2026-08-18T18:53:51.193971Z","end":"2026-08-18T18:53:51.303118Z","steps":["trace[351209456] 'agreement among raft nodes before linearized reading' (duration: 108.583869ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:53:51.545562Z","caller":"traceutil/trace.go:171","msg":"trace[378836426] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"158.41594ms","start":"2026-08-18T18:53:51.387125Z","end":"2026-08-18T18:53:51.545541Z","steps":["trace[378836426] 'read index received' (duration: 89.360949ms)","trace[378836426] 'applied index is now lower than readState.Index' (duration: 69.053861ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T18:53:51.545766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.634334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-lp86m\" ","response":"range_response_count:1 size:4217"} {"level":"info","ts":"2026-08-18T18:53:51.545798Z","caller":"traceutil/trace.go:171","msg":"trace[276761540] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-lp86m; range_end:; response_count:1; response_revision:1381; }","duration":"158.721895ms","start":"2026-08-18T18:53:51.387067Z","end":"2026-08-18T18:53:51.545789Z","steps":["trace[276761540] 'agreement among raft nodes before linearized reading' (duration: 158.585103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:53:51.54578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.216077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T18:53:51.54565Z","caller":"traceutil/trace.go:171","msg":"trace[1543912335] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"168.781543ms","start":"2026-08-18T18:53:51.376838Z","end":"2026-08-18T18:53:51.545619Z","steps":["trace[1543912335] 'process raft request' (duration: 99.557439ms)","trace[1543912335] 'compare' (duration: 68.98481ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:53:51.545936Z","caller":"traceutil/trace.go:171","msg":"trace[77474152] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx-admission; range_end:; response_count:0; response_revision:1381; }","duration":"149.289948ms","start":"2026-08-18T18:53:51.396539Z","end":"2026-08-18T18:53:51.545829Z","steps":["trace[77474152] 'agreement among raft nodes before linearized reading' (duration: 149.184936ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:53:51.78384Z","caller":"traceutil/trace.go:171","msg":"trace[1694789225] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"120.262605ms","start":"2026-08-18T18:53:51.663557Z","end":"2026-08-18T18:53:51.783819Z","steps":["trace[1694789225] 'read index received' (duration: 34.814041ms)","trace[1694789225] 'applied index is now lower than readState.Index' (duration: 85.447603ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T18:53:51.783971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.409727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T18:53:51.78397Z","caller":"traceutil/trace.go:171","msg":"trace[1374776205] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"186.097441ms","start":"2026-08-18T18:53:51.59784Z","end":"2026-08-18T18:53:51.783938Z","steps":["trace[1374776205] 'process raft request' (duration: 100.712919ms)","trace[1374776205] 'compare' (duration: 85.101098ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:53:51.784003Z","caller":"traceutil/trace.go:171","msg":"trace[1065604703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1382; }","duration":"120.496278ms","start":"2026-08-18T18:53:51.663496Z","end":"2026-08-18T18:53:51.783992Z","steps":["trace[1065604703] 'agreement among raft nodes before linearized reading' (duration: 120.413397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:17.406152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.759951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-18T18:54:17.406224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.740806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00c46b9b-4bb7-4e57-b99e-1f7f3f750e92\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-18T18:54:17.40651Z","caller":"traceutil/trace.go:171","msg":"trace[1891866099] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-00c46b9b-4bb7-4e57-b99e-1f7f3f750e92; range_end:; response_count:1; response_revision:1827; }","duration":"265.056511ms","start":"2026-08-18T18:54:17.141431Z","end":"2026-08-18T18:54:17.406487Z","steps":["trace[1891866099] 'range keys from in-memory index tree' (duration: 264.602123ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:54:17.406259Z","caller":"traceutil/trace.go:171","msg":"trace[1490002175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"167.980935ms","start":"2026-08-18T18:54:17.238248Z","end":"2026-08-18T18:54:17.406229Z","steps":["trace[1490002175] 'range keys from in-memory index tree' (duration: 167.524138ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:54:17.592532Z","caller":"traceutil/trace.go:171","msg":"trace[772226729] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"179.059456ms","start":"2026-08-18T18:54:17.413432Z","end":"2026-08-18T18:54:17.592491Z","steps":["trace[772226729] 'process raft request' (duration: 178.832332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.55119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569439630436516,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-18T18:54:18.8121Z","caller":"traceutil/trace.go:171","msg":"trace[2040786638] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"844.380467ms","start":"2026-08-18T18:54:17.967695Z","end":"2026-08-18T18:54:18.812075Z","steps":["trace[2040786638] 'process raft request' (duration: 844.245575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.812474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:17.96767Z","time spent":"844.49949ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T18:54:18.893604Z","caller":"traceutil/trace.go:171","msg":"trace[2143706147] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"749.57014ms","start":"2026-08-18T18:54:18.143998Z","end":"2026-08-18T18:54:18.893568Z","steps":["trace[2143706147] 'process raft request' (duration: 749.294955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.8938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.143972Z","time spent":"749.728832ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T18:54:18.983673Z","caller":"traceutil/trace.go:171","msg":"trace[226574314] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"424.197275ms","start":"2026-08-18T18:54:18.559446Z","end":"2026-08-18T18:54:18.983643Z","steps":["trace[226574314] 'process raft request' (duration: 424.040193ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:54:18.983666Z","caller":"traceutil/trace.go:171","msg":"trace[34346558] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1896; }","duration":"932.808211ms","start":"2026-08-18T18:54:18.05083Z","end":"2026-08-18T18:54:18.983639Z","steps":["trace[34346558] 'read index received' (duration: 761.115188ms)","trace[34346558] 'applied index is now lower than readState.Index' (duration: 171.691663ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T18:54:18.983832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.559422Z","time spent":"424.326268ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T18:54:18.983829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.965504ms","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:7"} {"level":"info","ts":"2026-08-18T18:54:18.983911Z","caller":"traceutil/trace.go:171","msg":"trace[1421739203] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1831; }","duration":"933.100267ms","start":"2026-08-18T18:54:18.050792Z","end":"2026-08-18T18:54:18.983892Z","steps":["trace[1421739203] 'agreement among raft nodes before linearized reading' (duration: 932.931524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.983974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.050772Z","time spent":"933.187348ms","remote":"127.0.0.1:46016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":15,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-18T18:54:18.984048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.960204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-18T18:54:18.984118Z","caller":"traceutil/trace.go:171","msg":"trace[421297849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1831; }","duration":"734.065936ms","start":"2026-08-18T18:54:18.250033Z","end":"2026-08-18T18:54:18.984099Z","steps":["trace[421297849] 'agreement among raft nodes before linearized reading' (duration: 733.886043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.984157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.250015Z","time spent":"734.133597ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-18T18:54:18.984294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.325022ms","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-18T18:54:18.984337Z","caller":"traceutil/trace.go:171","msg":"trace[876374411] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"570.408884ms","start":"2026-08-18T18:54:18.413913Z","end":"2026-08-18T18:54:18.984322Z","steps":["trace[876374411] 'agreement among raft nodes before linearized reading' (duration: 570.260991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.984367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.413892Z","time spent":"570.469114ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-18T18:54:18.9844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.397143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00c46b9b-4bb7-4e57-b99e-1f7f3f750e92\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-18T18:54:18.98444Z","caller":"traceutil/trace.go:171","msg":"trace[722484906] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-00c46b9b-4bb7-4e57-b99e-1f7f3f750e92; range_end:; response_count:1; response_revision:1831; }","duration":"573.433923ms","start":"2026-08-18T18:54:18.410993Z","end":"2026-08-18T18:54:18.984427Z","steps":["trace[722484906] 'agreement among raft nodes before linearized reading' (duration: 573.336652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.984473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.410979Z","time spent":"573.487374ms","remote":"127.0.0.1:46044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-00c46b9b-4bb7-4e57-b99e-1f7f3f750e92\" "} {"level":"warn","ts":"2026-08-18T18:54:18.984537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.409564ms","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-18T18:54:18.98459Z","caller":"traceutil/trace.go:171","msg":"trace[1032811780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1831; }","duration":"409.502576ms","start":"2026-08-18T18:54:18.575075Z","end":"2026-08-18T18:54:18.984577Z","steps":["trace[1032811780] 'agreement among raft nodes before linearized reading' (duration: 409.097009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.984646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.556678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-18T18:54:18.984628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.575059Z","time spent":"409.561496ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-18T18:54:18.984685Z","caller":"traceutil/trace.go:171","msg":"trace[1628699349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1831; }","duration":"183.595358ms","start":"2026-08-18T18:54:18.801081Z","end":"2026-08-18T18:54:18.984676Z","steps":["trace[1628699349] 'agreement among raft nodes before linearized reading' (duration: 183.489777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.98469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.453766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-18T18:54:18.984723Z","caller":"traceutil/trace.go:171","msg":"trace[835237066] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1831; }","duration":"730.499158ms","start":"2026-08-18T18:54:18.254212Z","end":"2026-08-18T18:54:18.984712Z","steps":["trace[835237066] 'agreement among raft nodes before linearized reading' (duration: 730.419387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.984758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.254204Z","time spent":"730.547119ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-18T18:54:18.984761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.990363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-08-18T18:54:18.984812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.214238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-18T18:54:18.98487Z","caller":"traceutil/trace.go:171","msg":"trace[1659618644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1831; }","duration":"358.307269ms","start":"2026-08-18T18:54:18.626547Z","end":"2026-08-18T18:54:18.984855Z","steps":["trace[1659618644] 'agreement among raft nodes before linearized reading' (duration: 358.129536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.984921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.626527Z","time spent":"358.384711ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-18T18:54:18.984868Z","caller":"traceutil/trace.go:171","msg":"trace[1361004161] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1831; }","duration":"522.134195ms","start":"2026-08-18T18:54:18.462713Z","end":"2026-08-18T18:54:18.984847Z","steps":["trace[1361004161] 'agreement among raft nodes before linearized reading' (duration: 521.79281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:54:18.985045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:54:18.462687Z","time spent":"522.300798ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-18T18:55:06.456925Z","caller":"traceutil/trace.go:171","msg":"trace[237621036] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"114.331199ms","start":"2026-08-18T18:55:06.34257Z","end":"2026-08-18T18:55:06.456901Z","steps":["trace[237621036] 'process raft request' (duration: 114.197447ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:55:07.149276Z","caller":"traceutil/trace.go:171","msg":"trace[1576907431] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"115.003379ms","start":"2026-08-18T18:55:07.034248Z","end":"2026-08-18T18:55:07.149252Z","steps":["trace[1576907431] 'process raft request' (duration: 114.843447ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:55:07.180591Z","caller":"traceutil/trace.go:171","msg":"trace[1963895513] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"120.962714ms","start":"2026-08-18T18:55:07.05961Z","end":"2026-08-18T18:55:07.180572Z","steps":["trace[1963895513] 'process raft request' (duration: 120.869542ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:55:07.306255Z","caller":"traceutil/trace.go:171","msg":"trace[1538873799] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"113.517265ms","start":"2026-08-18T18:55:07.192713Z","end":"2026-08-18T18:55:07.30623Z","steps":["trace[1538873799] 'process raft request' (duration: 110.843672ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:56:41.76164Z","caller":"traceutil/trace.go:171","msg":"trace[1882871600] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"112.448859ms","start":"2026-08-18T18:56:41.649162Z","end":"2026-08-18T18:56:41.761611Z","steps":["trace[1882871600] 'process raft request' (duration: 72.581425ms)","trace[1882871600] 'compare' (duration: 39.598704ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:56:49.865524Z","caller":"traceutil/trace.go:171","msg":"trace[356532860] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"309.399278ms","start":"2026-08-18T18:56:49.556096Z","end":"2026-08-18T18:56:49.865495Z","steps":["trace[356532860] 'process raft request' (duration: 309.163629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:49.866338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:56:49.556075Z","time spent":"309.643707ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T18:56:50.000404Z","caller":"traceutil/trace.go:171","msg":"trace[1776237111] linearizableReadLoop","detail":"{readStateIndex:2890; appliedIndex:2888; }","duration":"336.977249ms","start":"2026-08-18T18:56:49.663405Z","end":"2026-08-18T18:56:50.000382Z","steps":["trace[1776237111] 'read index received' (duration: 201.823222ms)","trace[1776237111] 'applied index is now lower than readState.Index' (duration: 135.152897ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T18:56:50.000712Z","caller":"traceutil/trace.go:171","msg":"trace[1574012901] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"366.762774ms","start":"2026-08-18T18:56:49.633939Z","end":"2026-08-18T18:56:50.000702Z","steps":["trace[1574012901] 'process raft request' (duration: 363.101693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:50.000861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:56:49.633915Z","time spent":"366.861958ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T18:56:50.001096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.696136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T18:56:50.001147Z","caller":"traceutil/trace.go:171","msg":"trace[856322062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2788; }","duration":"337.777529ms","start":"2026-08-18T18:56:49.663361Z","end":"2026-08-18T18:56:50.001139Z","steps":["trace[856322062] 'agreement among raft nodes before linearized reading' (duration: 337.707076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:50.00121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:56:49.663349Z","time spent":"337.854732ms","remote":"127.0.0.1:45850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-18T18:56:50.001447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.500389ms","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-18T18:56:50.001495Z","caller":"traceutil/trace.go:171","msg":"trace[1498418691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2788; }","duration":"337.565861ms","start":"2026-08-18T18:56:49.663922Z","end":"2026-08-18T18:56:50.001488Z","steps":["trace[1498418691] 'agreement among raft nodes before linearized reading' (duration: 337.448737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:50.001551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T18:56:49.663914Z","time spent":"337.629644ms","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-18T18:56:50.001739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.011701ms","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-18T18:56:50.001786Z","caller":"traceutil/trace.go:171","msg":"trace[5564771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2788; }","duration":"142.103185ms","start":"2026-08-18T18:56:49.859675Z","end":"2026-08-18T18:56:50.001779Z","steps":["trace[5564771] 'agreement among raft nodes before linearized reading' (duration: 141.999701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:50.001798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.192984ms","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-18T18:56:50.00188Z","caller":"traceutil/trace.go:171","msg":"trace[1172973970] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2788; }","duration":"138.275217ms","start":"2026-08-18T18:56:49.863569Z","end":"2026-08-18T18:56:50.001844Z","steps":["trace[1172973970] 'agreement among raft nodes before linearized reading' (duration: 138.09222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:50.002079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.371621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-08-18T18:56:50.002081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.407457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-18T18:56:50.002115Z","caller":"traceutil/trace.go:171","msg":"trace[836455210] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2788; }","duration":"138.445914ms","start":"2026-08-18T18:56:49.863659Z","end":"2026-08-18T18:56:50.002105Z","steps":["trace[836455210] 'agreement among raft nodes before linearized reading' (duration: 138.35111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:50.002159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.343602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-18T18:56:50.002215Z","caller":"traceutil/trace.go:171","msg":"trace[1430931161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2788; }","duration":"182.472137ms","start":"2026-08-18T18:56:49.819736Z","end":"2026-08-18T18:56:50.002208Z","steps":["trace[1430931161] 'agreement among raft nodes before linearized reading' (duration: 182.309291ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:56:50.002161Z","caller":"traceutil/trace.go:171","msg":"trace[518172246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2788; }","duration":"142.529762ms","start":"2026-08-18T18:56:49.859613Z","end":"2026-08-18T18:56:50.002142Z","steps":["trace[518172246] 'agreement among raft nodes before linearized reading' (duration: 142.167868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T18:56:50.002684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.184922ms","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-18T18:56:50.002906Z","caller":"traceutil/trace.go:171","msg":"trace[389696036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2788; }","duration":"146.331848ms","start":"2026-08-18T18:56:49.856417Z","end":"2026-08-18T18:56:50.002749Z","steps":["trace[389696036] 'agreement among raft nodes before linearized reading' (duration: 145.283448ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:57:09.265583Z","caller":"traceutil/trace.go:171","msg":"trace[1281502416] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"113.210034ms","start":"2026-08-18T18:57:09.152313Z","end":"2026-08-18T18:57:09.265523Z","steps":["trace[1281502416] 'process raft request' (duration: 112.924924ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T18:58:42.446043Z","caller":"traceutil/trace.go:171","msg":"trace[1567632016] transaction","detail":"{read_only:false; response_revision:3454; number_of_response:1; }","duration":"146.262012ms","start":"2026-08-18T18:58:42.299758Z","end":"2026-08-18T18:58:42.44602Z","steps":["trace[1567632016] 'process raft request' (duration: 146.122479ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:00:31.248645Z","caller":"traceutil/trace.go:171","msg":"trace[1200183183] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4564; }","duration":"104.961473ms","start":"2026-08-18T19:00:31.143634Z","end":"2026-08-18T19:00:31.248595Z","steps":["trace[1200183183] 'process raft request' (duration: 103.840678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:37.44719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.422211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T19:00:37.447381Z","caller":"traceutil/trace.go:171","msg":"trace[1517014356] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4847; }","duration":"138.045747ms","start":"2026-08-18T19:00:37.30932Z","end":"2026-08-18T19:00:37.447366Z","steps":["trace[1517014356] 'read index received' (duration: 1.299509ms)","trace[1517014356] 'applied index is now lower than readState.Index' (duration: 136.744958ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:00:37.447472Z","caller":"traceutil/trace.go:171","msg":"trace[1767158548] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"141.530852ms","start":"2026-08-18T19:00:37.305931Z","end":"2026-08-18T19:00:37.447462Z","steps":["trace[1767158548] 'compare' (duration: 136.327439ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:00:37.447676Z","caller":"traceutil/trace.go:171","msg":"trace[1865249666] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"140.53105ms","start":"2026-08-18T19:00:37.307136Z","end":"2026-08-18T19:00:37.447667Z","steps":["trace[1865249666] 'process raft request' (duration: 140.173072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:37.447894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.565958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60478"} {"level":"info","ts":"2026-08-18T19:00:37.447929Z","caller":"traceutil/trace.go:171","msg":"trace[1992689296] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4676; }","duration":"138.617019ms","start":"2026-08-18T19:00:37.309304Z","end":"2026-08-18T19:00:37.447921Z","steps":["trace[1992689296] 'agreement among raft nodes before linearized reading' (duration: 138.450985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:37.448155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.125768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-18T19:00:37.44819Z","caller":"traceutil/trace.go:171","msg":"trace[2128585678] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4676; }","duration":"138.190089ms","start":"2026-08-18T19:00:37.309992Z","end":"2026-08-18T19:00:37.448182Z","steps":["trace[2128585678] 'agreement among raft nodes before linearized reading' (duration: 138.100167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:37.44863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.025816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-18T19:00:37.448759Z","caller":"traceutil/trace.go:171","msg":"trace[1624393684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4676; }","duration":"110.171199ms","start":"2026-08-18T19:00:37.338568Z","end":"2026-08-18T19:00:37.44874Z","steps":["trace[1624393684] 'agreement among raft nodes before linearized reading' (duration: 109.927373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:37.449609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.329824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-18T19:00:37.449767Z","caller":"traceutil/trace.go:171","msg":"trace[478419193] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4676; }","duration":"111.483018ms","start":"2026-08-18T19:00:37.338268Z","end":"2026-08-18T19:00:37.449751Z","steps":["trace[478419193] 'agreement among raft nodes before linearized reading' (duration: 111.272313ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:00:38.960577Z","caller":"traceutil/trace.go:171","msg":"trace[624699860] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"192.577378ms","start":"2026-08-18T19:00:38.767968Z","end":"2026-08-18T19:00:38.960546Z","steps":["trace[624699860] 'process raft request' (duration: 98.479072ms)","trace[624699860] 'compare' (duration: 93.514863ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T19:00:38.960722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.582275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T19:00:38.960759Z","caller":"traceutil/trace.go:171","msg":"trace[399484106] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4706; }","duration":"190.654966ms","start":"2026-08-18T19:00:38.770093Z","end":"2026-08-18T19:00:38.960748Z","steps":["trace[399484106] 'agreement among raft nodes before linearized reading' (duration: 190.580095ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:00:38.960632Z","caller":"traceutil/trace.go:171","msg":"trace[1759784630] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"190.488223ms","start":"2026-08-18T19:00:38.770125Z","end":"2026-08-18T19:00:38.960614Z","steps":["trace[1759784630] 'read index received' (duration: 96.276784ms)","trace[1759784630] 'applied index is now lower than readState.Index' (duration: 94.210239ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:00:41.319528Z","caller":"traceutil/trace.go:171","msg":"trace[185737816] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"109.548054ms","start":"2026-08-18T19:00:41.209949Z","end":"2026-08-18T19:00:41.319497Z","steps":["trace[185737816] 'process raft request' (duration: 109.38124ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:00:44.141548Z","caller":"traceutil/trace.go:171","msg":"trace[1662831268] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"104.809613ms","start":"2026-08-18T19:00:44.036712Z","end":"2026-08-18T19:00:44.141522Z","steps":["trace[1662831268] 'process raft request' (duration: 104.605529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:44.395704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.717208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T19:00:44.395827Z","caller":"traceutil/trace.go:171","msg":"trace[1648310174] linearizableReadLoop","detail":"{readStateIndex:4974; appliedIndex:4973; }","duration":"211.62941ms","start":"2026-08-18T19:00:44.184179Z","end":"2026-08-18T19:00:44.395808Z","steps":["trace[1648310174] 'read index received' (duration: 20.718567ms)","trace[1648310174] 'applied index is now lower than readState.Index' (duration: 190.908943ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:00:44.395889Z","caller":"traceutil/trace.go:171","msg":"trace[441175089] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"237.196483ms","start":"2026-08-18T19:00:44.158645Z","end":"2026-08-18T19:00:44.395841Z","steps":["trace[441175089] 'process raft request' (duration: 46.241919ms)","trace[441175089] 'compare' (duration: 190.497664ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T19:00:44.396127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.925686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7f864986d9-xlx2r.18ccfb7794ae7c7e\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-08-18T19:00:44.396209Z","caller":"traceutil/trace.go:171","msg":"trace[2088497245] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7f864986d9-xlx2r.18ccfb7794ae7c7e; range_end:; response_count:1; response_revision:4797; }","duration":"212.016238ms","start":"2026-08-18T19:00:44.184172Z","end":"2026-08-18T19:00:44.396189Z","steps":["trace[2088497245] 'agreement among raft nodes before linearized reading' (duration: 211.796833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:44.403554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.021541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-18T19:00:44.403605Z","caller":"traceutil/trace.go:171","msg":"trace[159196912] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4799; }","duration":"207.082142ms","start":"2026-08-18T19:00:44.196509Z","end":"2026-08-18T19:00:44.403591Z","steps":["trace[159196912] 'agreement among raft nodes before linearized reading' (duration: 206.919219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:56.585051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.532212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T19:00:56.585165Z","caller":"traceutil/trace.go:171","msg":"trace[1926047340] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4931; }","duration":"611.669795ms","start":"2026-08-18T19:00:55.973468Z","end":"2026-08-18T19:00:56.585138Z","steps":["trace[1926047340] 'range keys from in-memory index tree' (duration: 611.486141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:56.585514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T19:00:55.973461Z","time spent":"612.037842ms","remote":"127.0.0.1:45938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" "} {"level":"info","ts":"2026-08-18T19:00:56.586476Z","caller":"traceutil/trace.go:171","msg":"trace[550762049] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5111; }","duration":"399.134796ms","start":"2026-08-18T19:00:56.187324Z","end":"2026-08-18T19:00:56.586459Z","steps":["trace[550762049] 'read index received' (duration: 398.966412ms)","trace[550762049] 'applied index is now lower than readState.Index' (duration: 167.274µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:00:56.586611Z","caller":"traceutil/trace.go:171","msg":"trace[595569883] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"604.536643ms","start":"2026-08-18T19:00:55.982049Z","end":"2026-08-18T19:00:56.586585Z","steps":["trace[595569883] 'process raft request' (duration: 603.079742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:56.586795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T19:00:55.982037Z","time spent":"604.687567ms","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T19:00:56.587375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.038175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362732"} {"level":"info","ts":"2026-08-18T19:00:56.587423Z","caller":"traceutil/trace.go:171","msg":"trace[41487765] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4932; }","duration":"400.127517ms","start":"2026-08-18T19:00:56.187284Z","end":"2026-08-18T19:00:56.587411Z","steps":["trace[41487765] 'agreement among raft nodes before linearized reading' (duration: 399.237168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T19:00:56.587459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T19:00:56.187265Z","time spent":"400.183538ms","remote":"127.0.0.1:45952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2362756,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-08-18T19:00:56.588024Z","caller":"traceutil/trace.go:171","msg":"trace[96495461] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"214.959991ms","start":"2026-08-18T19:00:56.373041Z","end":"2026-08-18T19:00:56.588001Z","steps":["trace[96495461] 'process raft request' (duration: 214.861519ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:01:29.650686Z","caller":"traceutil/trace.go:171","msg":"trace[1965116839] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"114.923323ms","start":"2026-08-18T19:01:29.53573Z","end":"2026-08-18T19:01:29.650654Z","steps":["trace[1965116839] 'process raft request' (duration: 62.767806ms)","trace[1965116839] 'compare' (duration: 51.567095ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:01:40.649983Z","caller":"traceutil/trace.go:171","msg":"trace[8639373] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"122.737362ms","start":"2026-08-18T19:01:40.52722Z","end":"2026-08-18T19:01:40.649957Z","steps":["trace[8639373] 'process raft request' (duration: 122.563919ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:01:53.318035Z","caller":"traceutil/trace.go:171","msg":"trace[160757838] linearizableReadLoop","detail":"{readStateIndex:5662; appliedIndex:5659; }","duration":"132.289762ms","start":"2026-08-18T19:01:53.185717Z","end":"2026-08-18T19:01:53.318007Z","steps":["trace[160757838] 'read index received' (duration: 19.543717ms)","trace[160757838] 'applied index is now lower than readState.Index' (duration: 112.742525ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T19:01:53.318226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.494205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-18T19:01:53.318283Z","caller":"traceutil/trace.go:171","msg":"trace[551779568] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5468; }","duration":"132.632148ms","start":"2026-08-18T19:01:53.185635Z","end":"2026-08-18T19:01:53.318267Z","steps":["trace[551779568] 'agreement among raft nodes before linearized reading' (duration: 132.493575ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:01:53.318453Z","caller":"traceutil/trace.go:171","msg":"trace[1922950796] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"156.282318ms","start":"2026-08-18T19:01:53.162154Z","end":"2026-08-18T19:01:53.318437Z","steps":["trace[1922950796] 'process raft request' (duration: 155.508152ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:01:53.318429Z","caller":"traceutil/trace.go:171","msg":"trace[1890500482] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"159.699067ms","start":"2026-08-18T19:01:53.158692Z","end":"2026-08-18T19:01:53.318391Z","steps":["trace[1890500482] 'process raft request' (duration: 117.745506ms)","trace[1890500482] 'compare' (duration: 41.016762ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:01:59.452369Z","caller":"traceutil/trace.go:171","msg":"trace[793357749] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"111.417569ms","start":"2026-08-18T19:01:59.340928Z","end":"2026-08-18T19:01:59.452346Z","steps":["trace[793357749] 'process raft request' (duration: 111.262246ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:01:59.454139Z","caller":"traceutil/trace.go:171","msg":"trace[1798716659] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"102.256584ms","start":"2026-08-18T19:01:59.351855Z","end":"2026-08-18T19:01:59.454112Z","steps":["trace[1798716659] 'process raft request' (duration: 102.135742ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:02:00.03468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2842} {"level":"info","ts":"2026-08-18T19:02:00.107911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2842,"took":"71.87921ms","hash":1392417666,"current-db-size-bytes":53186560,"current-db-size":"53 MB","current-db-size-in-use-bytes":29356032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-18T19:02:00.108Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1392417666,"revision":2842,"compact-revision":-1} {"level":"info","ts":"2026-08-18T19:02:18.31135Z","caller":"traceutil/trace.go:171","msg":"trace[283635891] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"108.417992ms","start":"2026-08-18T19:02:18.202904Z","end":"2026-08-18T19:02:18.311322Z","steps":["trace[283635891] 'process raft request' (duration: 50.557058ms)","trace[283635891] 'compare' (duration: 57.738322ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:02:18.31154Z","caller":"traceutil/trace.go:171","msg":"trace[459310273] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"107.285009ms","start":"2026-08-18T19:02:18.204244Z","end":"2026-08-18T19:02:18.311529Z","steps":["trace[459310273] 'process raft request' (duration: 107.040044ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T19:04:21.096683Z","caller":"traceutil/trace.go:171","msg":"trace[1915794366] transaction","detail":"{read_only:false; response_revision:6479; number_of_response:1; }","duration":"113.904825ms","start":"2026-08-18T19:04:20.98275Z","end":"2026-08-18T19:04:21.096655Z","steps":["trace[1915794366] 'process raft request' (duration: 100.093332ms)","trace[1915794366] 'compare' (duration: 13.587078ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T19:07:00.040822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5518} {"level":"info","ts":"2026-08-18T19:07:00.12311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5518,"took":"80.841559ms","hash":2380548585,"current-db-size-bytes":53186560,"current-db-size":"53 MB","current-db-size-in-use-bytes":18518016,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-18T19:07:00.123169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2380548585,"revision":5518,"compact-revision":2842}