{"level":"warn","ts":"2026-05-29T21:52:55.752979Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:52:55.753062Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T21:52:55.753139Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:52:55.75315Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-29T21:52:55.753166Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:52:55.753876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-29T21:52:55.753951Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T21:52:55.874981Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"65.143602ms"} {"level":"info","ts":"2026-05-29T21:52:55.910817Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-29T21:52:55.910944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:52:55.910978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-29T21:52:55.910997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:52:55.911015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-29T21:52:55.911285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-29T21:52:55.950987Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:52:55.957977Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:52:55.959977Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-29T21:52:55.970673Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:52:55.970974Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:52:55.971257Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:52:55.971405Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:52:55.971451Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-29T21:52:55.990459Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-29T21:52:55.990627Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-29T21:52:55.990732Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-29T21:52:55.990864Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:52:55.990892Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:52:55.99869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-29T21:52:55.99889Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-29T21:52:56.011771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:52:56.013385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:52:56.013503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-29T21:52:56.013551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:52:56.013594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-29T21:52:56.013835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-29T21:52:56.013875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-29T21:52:56.037044Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:52:56.049507Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:52:56.049579Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:52:56.05005Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:52:56.050279Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:52:56.050302Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:52:56.051671Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:52:56.051895Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:52:56.051976Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:52:56.053117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-05-29T21:52:56.056405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T21:53:18.307461Z","caller":"traceutil/trace.go:171","msg":"trace[1613302233] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"140.186609ms","start":"2026-05-29T21:53:18.167229Z","end":"2026-05-29T21:53:18.307416Z","steps":["trace[1613302233] 'process raft request' (duration: 139.943806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:23.342655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.52991ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:23.342942Z","caller":"traceutil/trace.go:171","msg":"trace[1863110379] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:425; }","duration":"260.832773ms","start":"2026-05-29T21:53:23.082058Z","end":"2026-05-29T21:53:23.342891Z","steps":["trace[1863110379] 'range keys from in-memory index tree' (duration: 260.50209ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:23.343008Z","caller":"traceutil/trace.go:171","msg":"trace[1329135539] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"247.576821ms","start":"2026-05-29T21:53:23.095372Z","end":"2026-05-29T21:53:23.342948Z","steps":["trace[1329135539] 'read index received' (duration: 236.173509ms)","trace[1329135539] 'applied index is now lower than readState.Index' (duration: 11.401612ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:23.34322Z","caller":"traceutil/trace.go:171","msg":"trace[1547127373] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"421.684449ms","start":"2026-05-29T21:53:22.921518Z","end":"2026-05-29T21:53:23.343202Z","steps":["trace[1547127373] 'process raft request' (duration: 410.127635ms)","trace[1547127373] 'compare' (duration: 10.713864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:23.343482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.126757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:23.343537Z","caller":"traceutil/trace.go:171","msg":"trace[1351146054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"248.261759ms","start":"2026-05-29T21:53:23.09526Z","end":"2026-05-29T21:53:23.343522Z","steps":["trace[1351146054] 'agreement among raft nodes before linearized reading' (duration: 247.999045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:23.34429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:22.92149Z","time spent":"421.77882ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:53:25.598343Z","caller":"traceutil/trace.go:171","msg":"trace[1503957148] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"244.359092ms","start":"2026-05-29T21:53:25.353963Z","end":"2026-05-29T21:53:25.598322Z","steps":["trace[1503957148] 'process raft request' (duration: 244.25251ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:34.076574Z","caller":"traceutil/trace.go:171","msg":"trace[507978341] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"215.90944ms","start":"2026-05-29T21:53:33.860633Z","end":"2026-05-29T21:53:34.076543Z","steps":["trace[507978341] 'process raft request' (duration: 215.707658ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.405916Z","caller":"traceutil/trace.go:171","msg":"trace[1601818685] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"136.054745ms","start":"2026-05-29T21:53:35.269815Z","end":"2026-05-29T21:53:35.405869Z","steps":["trace[1601818685] 'process raft request' (duration: 108.188067ms)","trace[1601818685] 'compare' (duration: 27.571994ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:35.406162Z","caller":"traceutil/trace.go:171","msg":"trace[1715693592] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"122.84263ms","start":"2026-05-29T21:53:35.283309Z","end":"2026-05-29T21:53:35.406152Z","steps":["trace[1715693592] 'process raft request' (duration: 122.488495ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.406251Z","caller":"traceutil/trace.go:171","msg":"trace[2092038215] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"136.237668ms","start":"2026-05-29T21:53:35.270005Z","end":"2026-05-29T21:53:35.406243Z","steps":["trace[2092038215] 'process raft request' (duration: 135.731191ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.406385Z","caller":"traceutil/trace.go:171","msg":"trace[1543818321] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"120.973059ms","start":"2026-05-29T21:53:35.285355Z","end":"2026-05-29T21:53:35.406328Z","steps":["trace[1543818321] 'process raft request' (duration: 120.493603ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.406446Z","caller":"traceutil/trace.go:171","msg":"trace[906682602] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"120.22714ms","start":"2026-05-29T21:53:35.286174Z","end":"2026-05-29T21:53:35.406401Z","steps":["trace[906682602] 'process raft request' (duration: 119.793886ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.406776Z","caller":"traceutil/trace.go:171","msg":"trace[88038727] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"112.630995ms","start":"2026-05-29T21:53:35.294131Z","end":"2026-05-29T21:53:35.406762Z","steps":["trace[88038727] 'process raft request' (duration: 111.951988ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.406789Z","caller":"traceutil/trace.go:171","msg":"trace[537607375] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"112.518164ms","start":"2026-05-29T21:53:35.29424Z","end":"2026-05-29T21:53:35.406758Z","steps":["trace[537607375] 'process raft request' (duration: 112.352862ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.406919Z","caller":"traceutil/trace.go:171","msg":"trace[1549321431] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"112.668166ms","start":"2026-05-29T21:53:35.294233Z","end":"2026-05-29T21:53:35.406901Z","steps":["trace[1549321431] 'process raft request' (duration: 112.028659ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.463631Z","caller":"traceutil/trace.go:171","msg":"trace[1824894680] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"162.799871ms","start":"2026-05-29T21:53:35.300805Z","end":"2026-05-29T21:53:35.463605Z","steps":["trace[1824894680] 'process raft request' (duration: 162.365626ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.463688Z","caller":"traceutil/trace.go:171","msg":"trace[854000489] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"131.952669ms","start":"2026-05-29T21:53:35.331706Z","end":"2026-05-29T21:53:35.463659Z","steps":["trace[854000489] 'process raft request' (duration: 131.886809ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.463905Z","caller":"traceutil/trace.go:171","msg":"trace[484845770] transaction","detail":"{read_only:false; number_of_response:1; response_revision:497; }","duration":"162.992413ms","start":"2026-05-29T21:53:35.300903Z","end":"2026-05-29T21:53:35.463895Z","steps":["trace[484845770] 'process raft request' (duration: 162.497938ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.464085Z","caller":"traceutil/trace.go:171","msg":"trace[267384790] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"160.868179ms","start":"2026-05-29T21:53:35.303173Z","end":"2026-05-29T21:53:35.464042Z","steps":["trace[267384790] 'process raft request' (duration: 160.278223ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.464416Z","caller":"traceutil/trace.go:171","msg":"trace[1111312401] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"163.516559ms","start":"2026-05-29T21:53:35.300853Z","end":"2026-05-29T21:53:35.46437Z","steps":["trace[1111312401] 'process raft request' (duration: 162.464418ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.59974Z","caller":"traceutil/trace.go:171","msg":"trace[1130440229] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"185.36352ms","start":"2026-05-29T21:53:35.414329Z","end":"2026-05-29T21:53:35.599692Z","steps":["trace[1130440229] 'process raft request' (duration: 135.926453ms)","trace[1130440229] 'compare' (duration: 49.180895ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:35.600152Z","caller":"traceutil/trace.go:171","msg":"trace[417029043] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"182.948774ms","start":"2026-05-29T21:53:35.417143Z","end":"2026-05-29T21:53:35.600092Z","steps":["trace[417029043] 'process raft request' (duration: 182.449378ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.600259Z","caller":"traceutil/trace.go:171","msg":"trace[1672075632] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"182.426777ms","start":"2026-05-29T21:53:35.417814Z","end":"2026-05-29T21:53:35.600241Z","steps":["trace[1672075632] 'process raft request' (duration: 181.893821ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.600287Z","caller":"traceutil/trace.go:171","msg":"trace[413261815] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"173.968945ms","start":"2026-05-29T21:53:35.426301Z","end":"2026-05-29T21:53:35.60027Z","steps":["trace[413261815] 'process raft request' (duration: 173.52416ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.600278Z","caller":"traceutil/trace.go:171","msg":"trace[2036320837] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"178.628686ms","start":"2026-05-29T21:53:35.421602Z","end":"2026-05-29T21:53:35.60023Z","steps":["trace[2036320837] 'process raft request' (duration: 178.174471ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.60048Z","caller":"traceutil/trace.go:171","msg":"trace[388120218] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"169.304623ms","start":"2026-05-29T21:53:35.431162Z","end":"2026-05-29T21:53:35.600467Z","steps":["trace[388120218] 'process raft request' (duration: 168.783457ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.600443Z","caller":"traceutil/trace.go:171","msg":"trace[27370467] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"173.010434ms","start":"2026-05-29T21:53:35.427374Z","end":"2026-05-29T21:53:35.600384Z","steps":["trace[27370467] 'process raft request' (duration: 172.499128ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.600484Z","caller":"traceutil/trace.go:171","msg":"trace[1872478306] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"182.744371ms","start":"2026-05-29T21:53:35.41741Z","end":"2026-05-29T21:53:35.600155Z","steps":["trace[1872478306] 'process raft request' (duration: 182.226316ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.608297Z","caller":"traceutil/trace.go:171","msg":"trace[2110397120] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"140.328902ms","start":"2026-05-29T21:53:35.461309Z","end":"2026-05-29T21:53:35.601638Z","steps":["trace[2110397120] 'process raft request' (duration: 140.200551ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.608522Z","caller":"traceutil/trace.go:171","msg":"trace[459913978] transaction","detail":"{read_only:false; number_of_response:1; response_revision:508; }","duration":"126.102195ms","start":"2026-05-29T21:53:35.475625Z","end":"2026-05-29T21:53:35.601728Z","steps":["trace[459913978] 'process raft request' (duration: 125.967634ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.610552Z","caller":"traceutil/trace.go:171","msg":"trace[1748382963] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"132.183452ms","start":"2026-05-29T21:53:35.478351Z","end":"2026-05-29T21:53:35.610534Z","steps":["trace[1748382963] 'process raft request' (duration: 125.028533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:35.770641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.160073ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-29T21:53:35.771092Z","caller":"traceutil/trace.go:171","msg":"trace[414481520] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"145.727682ms","start":"2026-05-29T21:53:35.625342Z","end":"2026-05-29T21:53:35.77107Z","steps":["trace[414481520] 'process raft request' (duration: 145.425108ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.083218Z","caller":"traceutil/trace.go:171","msg":"trace[1067928458] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"441.220929ms","start":"2026-05-29T21:53:35.641927Z","end":"2026-05-29T21:53:36.083148Z","steps":["trace[1067928458] 'process raft request' (duration: 378.488037ms)","trace[1067928458] 'compare' (duration: 62.382268ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:36.083482Z","caller":"traceutil/trace.go:171","msg":"trace[164526668] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"441.37506ms","start":"2026-05-29T21:53:35.642037Z","end":"2026-05-29T21:53:36.083412Z","steps":["trace[164526668] 'process raft request' (duration: 440.967625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.083593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.641904Z","time spent":"441.434541ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:36.083712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.679972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:53:36.08381Z","caller":"traceutil/trace.go:171","msg":"trace[326774921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"422.800134ms","start":"2026-05-29T21:53:35.660994Z","end":"2026-05-29T21:53:36.083794Z","steps":["trace[326774921] 'agreement among raft nodes before linearized reading' (duration: 422.610282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.083817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.642031Z","time spent":"441.608182ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:36.083853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.660951Z","time spent":"422.891276ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T21:53:36.083558Z","caller":"traceutil/trace.go:171","msg":"trace[677533223] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"405.066959ms","start":"2026-05-29T21:53:35.678441Z","end":"2026-05-29T21:53:36.083508Z","steps":["trace[677533223] 'process raft request' (duration: 404.736275ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.084115Z","caller":"traceutil/trace.go:171","msg":"trace[642301244] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"382.101046ms","start":"2026-05-29T21:53:35.70197Z","end":"2026-05-29T21:53:36.084071Z","steps":["trace[642301244] 'process raft request' (duration: 381.276696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.084243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.701931Z","time spent":"382.233337ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:53:36.083543Z","caller":"traceutil/trace.go:171","msg":"trace[1398048854] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:531; }","duration":"422.515391ms","start":"2026-05-29T21:53:35.660999Z","end":"2026-05-29T21:53:36.083514Z","steps":["trace[1398048854] 'read index received' (duration: 108.195456ms)","trace[1398048854] 'applied index is now lower than readState.Index' (duration: 314.318105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:36.084291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.678422Z","time spent":"405.685716ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:36.1123Z","caller":"traceutil/trace.go:171","msg":"trace[698131929] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"326.998895ms","start":"2026-05-29T21:53:35.78528Z","end":"2026-05-29T21:53:36.112279Z","steps":["trace[698131929] 'process raft request' (duration: 326.884653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.112443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.785271Z","time spent":"327.093025ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:53:36.112721Z","caller":"traceutil/trace.go:171","msg":"trace[1011974753] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"328.338379ms","start":"2026-05-29T21:53:35.784375Z","end":"2026-05-29T21:53:36.112714Z","steps":["trace[1011974753] 'process raft request' (duration: 327.662072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.112752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.784343Z","time spent":"328.391209ms","remote":"127.0.0.1:48286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:36.113193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.379237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:36.113263Z","caller":"traceutil/trace.go:171","msg":"trace[1247899523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"260.58112ms","start":"2026-05-29T21:53:35.852664Z","end":"2026-05-29T21:53:36.113245Z","steps":["trace[1247899523] 'agreement among raft nodes before linearized reading' (duration: 260.459649ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.305482Z","caller":"traceutil/trace.go:171","msg":"trace[1188235105] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"115.856619ms","start":"2026-05-29T21:53:36.189552Z","end":"2026-05-29T21:53:36.305408Z","steps":["trace[1188235105] 'process raft request' (duration: 79.323376ms)","trace[1188235105] 'compare' (duration: 36.326001ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:36.340128Z","caller":"traceutil/trace.go:171","msg":"trace[189973716] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"138.725651ms","start":"2026-05-29T21:53:36.201388Z","end":"2026-05-29T21:53:36.340114Z","steps":["trace[189973716] 'process raft request' (duration: 138.64904ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.340181Z","caller":"traceutil/trace.go:171","msg":"trace[659822566] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"150.585291ms","start":"2026-05-29T21:53:36.18958Z","end":"2026-05-29T21:53:36.340165Z","steps":["trace[659822566] 'process raft request' (duration: 150.298228ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.507015Z","caller":"traceutil/trace.go:171","msg":"trace[747390661] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"118.838761ms","start":"2026-05-29T21:53:36.388148Z","end":"2026-05-29T21:53:36.506986Z","steps":["trace[747390661] 'read index received' (duration: 118.556027ms)","trace[747390661] 'applied index is now lower than readState.Index' (duration: 281.494µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:36.507188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.695044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:36.507231Z","caller":"traceutil/trace.go:171","msg":"trace[1679505742] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:526; }","duration":"122.795824ms","start":"2026-05-29T21:53:36.384424Z","end":"2026-05-29T21:53:36.50722Z","steps":["trace[1679505742] 'agreement among raft nodes before linearized reading' (duration: 122.694373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.507262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.670563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:36.507345Z","caller":"traceutil/trace.go:171","msg":"trace[1214103779] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:526; }","duration":"122.903766ms","start":"2026-05-29T21:53:36.384424Z","end":"2026-05-29T21:53:36.507327Z","steps":["trace[1214103779] 'agreement among raft nodes before linearized reading' (duration: 122.597412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.507422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.784389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:36.507521Z","caller":"traceutil/trace.go:171","msg":"trace[2125831371] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:526; }","duration":"165.93554ms","start":"2026-05-29T21:53:36.341561Z","end":"2026-05-29T21:53:36.507497Z","steps":["trace[2125831371] 'agreement among raft nodes before linearized reading' (duration: 165.759459ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.5075Z","caller":"traceutil/trace.go:171","msg":"trace[1175562240] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"122.899256ms","start":"2026-05-29T21:53:36.384552Z","end":"2026-05-29T21:53:36.507451Z","steps":["trace[1175562240] 'process raft request' (duration: 122.145707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.507466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.559792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:36.50849Z","caller":"traceutil/trace.go:171","msg":"trace[232772043] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:526; }","duration":"122.574153ms","start":"2026-05-29T21:53:36.385887Z","end":"2026-05-29T21:53:36.508461Z","steps":["trace[232772043] 'agreement among raft nodes before linearized reading' (duration: 121.550422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.508356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.133834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:36.508735Z","caller":"traceutil/trace.go:171","msg":"trace[782596527] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:526; }","duration":"167.555368ms","start":"2026-05-29T21:53:36.341165Z","end":"2026-05-29T21:53:36.50872Z","steps":["trace[782596527] 'agreement among raft nodes before linearized reading' (duration: 166.444357ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.542856Z","caller":"traceutil/trace.go:171","msg":"trace[1162069315] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"139.403606ms","start":"2026-05-29T21:53:36.403406Z","end":"2026-05-29T21:53:36.542809Z","steps":["trace[1162069315] 'process raft request' (duration: 139.138344ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.54295Z","caller":"traceutil/trace.go:171","msg":"trace[1929566362] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"133.475451ms","start":"2026-05-29T21:53:36.409413Z","end":"2026-05-29T21:53:36.542888Z","steps":["trace[1929566362] 'process raft request' (duration: 133.3157ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:36.656535Z","caller":"traceutil/trace.go:171","msg":"trace[1335220565] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"130.726832ms","start":"2026-05-29T21:53:36.525767Z","end":"2026-05-29T21:53:36.656493Z","steps":["trace[1335220565] 'process raft request' (duration: 83.576382ms)","trace[1335220565] 'compare' (duration: 46.920817ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:36.657346Z","caller":"traceutil/trace.go:171","msg":"trace[1281752290] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"109.206174ms","start":"2026-05-29T21:53:36.548093Z","end":"2026-05-29T21:53:36.6573Z","steps":["trace[1281752290] 'process raft request' (duration: 108.906141ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:37.122402Z","caller":"traceutil/trace.go:171","msg":"trace[1157303232] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"208.724269ms","start":"2026-05-29T21:53:36.913655Z","end":"2026-05-29T21:53:37.122379Z","steps":["trace[1157303232] 'process raft request' (duration: 203.500901ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:37.162889Z","caller":"traceutil/trace.go:171","msg":"trace[26426397] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"167.996379ms","start":"2026-05-29T21:53:36.99487Z","end":"2026-05-29T21:53:37.162867Z","steps":["trace[26426397] 'process raft request' (duration: 167.651795ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:37.164277Z","caller":"traceutil/trace.go:171","msg":"trace[959034864] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:567; }","duration":"128.217491ms","start":"2026-05-29T21:53:37.036036Z","end":"2026-05-29T21:53:37.164253Z","steps":["trace[959034864] 'read index received' (duration: 81.189264ms)","trace[959034864] 'applied index is now lower than readState.Index' (duration: 47.027237ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:37.164444Z","caller":"traceutil/trace.go:171","msg":"trace[1929277144] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"117.22735ms","start":"2026-05-29T21:53:37.047205Z","end":"2026-05-29T21:53:37.164432Z","steps":["trace[1929277144] 'process raft request' (duration: 116.557893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:37.164758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.678676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:53:37.164869Z","caller":"traceutil/trace.go:171","msg":"trace[433839474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"128.841778ms","start":"2026-05-29T21:53:37.036003Z","end":"2026-05-29T21:53:37.164845Z","steps":["trace[433839474] 'agreement among raft nodes before linearized reading' (duration: 128.513394ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:40.059422Z","caller":"traceutil/trace.go:171","msg":"trace[1929331944] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"215.479347ms","start":"2026-05-29T21:53:39.843921Z","end":"2026-05-29T21:53:40.0594Z","steps":["trace[1929331944] 'process raft request' (duration: 214.502506ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:45.478152Z","caller":"traceutil/trace.go:171","msg":"trace[143696347] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"167.853916ms","start":"2026-05-29T21:53:45.310268Z","end":"2026-05-29T21:53:45.478122Z","steps":["trace[143696347] 'process raft request' (duration: 167.685101ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:46.121946Z","caller":"traceutil/trace.go:171","msg":"trace[575905251] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"129.496321ms","start":"2026-05-29T21:53:45.992415Z","end":"2026-05-29T21:53:46.121911Z","steps":["trace[575905251] 'process raft request' (duration: 129.160121ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:46.388713Z","caller":"traceutil/trace.go:171","msg":"trace[968215655] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"108.413005ms","start":"2026-05-29T21:53:46.280276Z","end":"2026-05-29T21:53:46.388689Z","steps":["trace[968215655] 'process raft request' (duration: 108.22788ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:46.766517Z","caller":"traceutil/trace.go:171","msg":"trace[489208107] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"483.918329ms","start":"2026-05-29T21:53:46.28245Z","end":"2026-05-29T21:53:46.766369Z","steps":["trace[489208107] 'process raft request' (duration: 469.281379ms)","trace[489208107] 'compare' (duration: 13.773204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:46.766758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:46.282357Z","time spent":"484.254459ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:47.726279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.600916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T21:53:47.726343Z","caller":"traceutil/trace.go:171","msg":"trace[2063535239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"243.728362ms","start":"2026-05-29T21:53:47.482599Z","end":"2026-05-29T21:53:47.726328Z","steps":["trace[2063535239] 'range keys from in-memory index tree' (duration: 243.452532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:47.726625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.2746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:47.726743Z","caller":"traceutil/trace.go:171","msg":"trace[1364640866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"633.476166ms","start":"2026-05-29T21:53:47.093237Z","end":"2026-05-29T21:53:47.726714Z","steps":["trace[1364640866] 'range keys from in-memory index tree' (duration: 633.072294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:47.726797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:47.093161Z","time spent":"633.617841ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:53:48.249272Z","caller":"traceutil/trace.go:171","msg":"trace[76374940] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"153.122974ms","start":"2026-05-29T21:53:48.096118Z","end":"2026-05-29T21:53:48.249241Z","steps":["trace[76374940] 'read index received' (duration: 152.892507ms)","trace[76374940] 'applied index is now lower than readState.Index' (duration: 229.327µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:48.249352Z","caller":"traceutil/trace.go:171","msg":"trace[1735004817] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"516.867911ms","start":"2026-05-29T21:53:47.732446Z","end":"2026-05-29T21:53:48.249314Z","steps":["trace[1735004817] 'process raft request' (duration: 516.661385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:48.249392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.263598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:48.249493Z","caller":"traceutil/trace.go:171","msg":"trace[1186215630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"153.408303ms","start":"2026-05-29T21:53:48.09606Z","end":"2026-05-29T21:53:48.249468Z","steps":["trace[1186215630] 'agreement among raft nodes before linearized reading' (duration: 153.28266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:48.249555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:47.732411Z","time spent":"517.050406ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:50.40503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.234858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T21:53:50.405113Z","caller":"traceutil/trace.go:171","msg":"trace[1646247462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"151.324711ms","start":"2026-05-29T21:53:50.253771Z","end":"2026-05-29T21:53:50.405096Z","steps":["trace[1646247462] 'range keys from in-memory index tree' (duration: 151.112695ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:50.420227Z","caller":"traceutil/trace.go:171","msg":"trace[842301808] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"164.043719ms","start":"2026-05-29T21:53:50.256162Z","end":"2026-05-29T21:53:50.420206Z","steps":["trace[842301808] 'process raft request' (duration: 163.885124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:53.412254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.714676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-29T21:53:53.412376Z","caller":"traceutil/trace.go:171","msg":"trace[1062503377] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:687; }","duration":"157.923292ms","start":"2026-05-29T21:53:53.254425Z","end":"2026-05-29T21:53:53.412348Z","steps":["trace[1062503377] 'range keys from in-memory index tree' (duration: 157.517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:05.412327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291823442816378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:54:05.773117Z","caller":"traceutil/trace.go:171","msg":"trace[1317622368] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"871.853969ms","start":"2026-05-29T21:54:04.901234Z","end":"2026-05-29T21:54:05.773088Z","steps":["trace[1317622368] 'process raft request' (duration: 871.684565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:05.773502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:04.901212Z","time spent":"871.995962ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:05.962676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051470373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:54:05.96273Z","caller":"traceutil/trace.go:171","msg":"trace[882357711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.051589586s","start":"2026-05-29T21:54:04.911129Z","end":"2026-05-29T21:54:05.962719Z","steps":["trace[882357711] 'agreement among raft nodes before linearized reading' (duration: 1.051414262s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:05.962767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:04.911109Z","time spent":"1.051648928s","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T21:54:05.962479Z","caller":"traceutil/trace.go:171","msg":"trace[334011145] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"1.051211117s","start":"2026-05-29T21:54:04.91119Z","end":"2026-05-29T21:54:05.962401Z","steps":["trace[334011145] 'read index received' (duration: 861.734479ms)","trace[334011145] 'applied index is now lower than readState.Index' (duration: 189.475028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:05.963418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.838021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:54:05.963518Z","caller":"traceutil/trace.go:171","msg":"trace[278832635] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:777; }","duration":"869.020495ms","start":"2026-05-29T21:54:05.09447Z","end":"2026-05-29T21:54:05.96349Z","steps":["trace[278832635] 'agreement among raft nodes before linearized reading' (duration: 868.781748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:05.963588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:05.094449Z","time spent":"869.125778ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:54:05.96368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.153378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:05.963723Z","caller":"traceutil/trace.go:171","msg":"trace[787607919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"867.258581ms","start":"2026-05-29T21:54:05.096454Z","end":"2026-05-29T21:54:05.963713Z","steps":["trace[787607919] 'agreement among raft nodes before linearized reading' (duration: 867.194459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:05.963758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:05.09641Z","time spent":"867.341723ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:54:05.964045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.324445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hv6sc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-29T21:54:05.964237Z","caller":"traceutil/trace.go:171","msg":"trace[1298074732] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hv6sc; range_end:; response_count:1; response_revision:777; }","duration":"862.579501ms","start":"2026-05-29T21:54:05.101615Z","end":"2026-05-29T21:54:05.964195Z","steps":["trace[1298074732] 'agreement among raft nodes before linearized reading' (duration: 862.099119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:05.964326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:05.101588Z","time spent":"862.721435ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hv6sc\" "} {"level":"info","ts":"2026-05-29T21:54:24.002947Z","caller":"traceutil/trace.go:171","msg":"trace[1798710995] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"103.535756ms","start":"2026-05-29T21:54:23.899371Z","end":"2026-05-29T21:54:24.002906Z","steps":["trace[1798710995] 'process raft request' (duration: 103.2893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:24.292543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.415181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:54:24.292674Z","caller":"traceutil/trace.go:171","msg":"trace[178241020] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"118.96341ms","start":"2026-05-29T21:54:24.173688Z","end":"2026-05-29T21:54:24.292651Z","steps":["trace[178241020] 'process raft request' (duration: 13.356685ms)","trace[178241020] 'compare' (duration: 105.262408ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:24.341336Z","caller":"traceutil/trace.go:171","msg":"trace[888249036] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"162.613787ms","start":"2026-05-29T21:54:24.178697Z","end":"2026-05-29T21:54:24.341311Z","steps":["trace[888249036] 'process raft request' (duration: 162.504494ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:24.341382Z","caller":"traceutil/trace.go:171","msg":"trace[427812981] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1116; }","duration":"153.752281ms","start":"2026-05-29T21:54:24.187611Z","end":"2026-05-29T21:54:24.341363Z","steps":["trace[427812981] 'read index received' (duration: 153.744991ms)","trace[427812981] 'applied index is now lower than readState.Index' (duration: 5.86µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:24.341656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.032727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:54:24.341694Z","caller":"traceutil/trace.go:171","msg":"trace[803710695] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1082; }","duration":"154.105938ms","start":"2026-05-29T21:54:24.187575Z","end":"2026-05-29T21:54:24.341681Z","steps":["trace[803710695] 'agreement among raft nodes before linearized reading' (duration: 153.849702ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:24.360329Z","caller":"traceutil/trace.go:171","msg":"trace[181861328] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"121.055248ms","start":"2026-05-29T21:54:24.239253Z","end":"2026-05-29T21:54:24.360308Z","steps":["trace[181861328] 'process raft request' (duration: 120.905145ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:24.519686Z","caller":"traceutil/trace.go:171","msg":"trace[2131612881] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"117.303814ms","start":"2026-05-29T21:54:24.402354Z","end":"2026-05-29T21:54:24.519658Z","steps":["trace[2131612881] 'process raft request' (duration: 117.15554ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:24.568398Z","caller":"traceutil/trace.go:171","msg":"trace[6507794] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"118.86285ms","start":"2026-05-29T21:54:24.449516Z","end":"2026-05-29T21:54:24.568379Z","steps":["trace[6507794] 'process raft request' (duration: 118.617844ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:24.736011Z","caller":"traceutil/trace.go:171","msg":"trace[222141614] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1121; }","duration":"113.782257ms","start":"2026-05-29T21:54:24.622202Z","end":"2026-05-29T21:54:24.735985Z","steps":["trace[222141614] 'read index received' (duration: 89.08126ms)","trace[222141614] 'applied index is now lower than readState.Index' (duration: 24.699727ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:24.736158Z","caller":"traceutil/trace.go:171","msg":"trace[1715795928] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"122.825267ms","start":"2026-05-29T21:54:24.613301Z","end":"2026-05-29T21:54:24.736126Z","steps":["trace[1715795928] 'process raft request' (duration: 122.615062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:24.73636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.964158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T21:54:24.736412Z","caller":"traceutil/trace.go:171","msg":"trace[471871870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"118.167293ms","start":"2026-05-29T21:54:24.618229Z","end":"2026-05-29T21:54:24.736396Z","steps":["trace[471871870] 'agreement among raft nodes before linearized reading' (duration: 117.897957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:24.736391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.390218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T21:54:24.736387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.141028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:54:24.73655Z","caller":"traceutil/trace.go:171","msg":"trace[969761823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"113.551622ms","start":"2026-05-29T21:54:24.622945Z","end":"2026-05-29T21:54:24.736497Z","steps":["trace[969761823] 'agreement among raft nodes before linearized reading' (duration: 113.270236ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:24.736572Z","caller":"traceutil/trace.go:171","msg":"trace[999165816] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1089; }","duration":"106.371242ms","start":"2026-05-29T21:54:24.630186Z","end":"2026-05-29T21:54:24.736557Z","steps":["trace[999165816] 'agreement among raft nodes before linearized reading' (duration: 106.075756ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:24.73696Z","caller":"traceutil/trace.go:171","msg":"trace[305242002] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"144.267321ms","start":"2026-05-29T21:54:24.591898Z","end":"2026-05-29T21:54:24.736165Z","steps":["trace[305242002] 'process raft request' (duration: 119.38955ms)","trace[305242002] 'compare' (duration: 24.484692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:25.08321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.694038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:54:25.083518Z","caller":"traceutil/trace.go:171","msg":"trace[1255574047] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"243.311879ms","start":"2026-05-29T21:54:24.84018Z","end":"2026-05-29T21:54:25.083492Z","steps":["trace[1255574047] 'process raft request' (duration: 243.235588ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:25.083866Z","caller":"traceutil/trace.go:171","msg":"trace[939121947] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"245.881146ms","start":"2026-05-29T21:54:24.837971Z","end":"2026-05-29T21:54:25.083852Z","steps":["trace[939121947] 'process raft request' (duration: 115.431983ms)","trace[939121947] 'compare' (duration: 129.528483ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:25.084105Z","caller":"traceutil/trace.go:171","msg":"trace[628859524] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"245.501038ms","start":"2026-05-29T21:54:24.838574Z","end":"2026-05-29T21:54:25.084075Z","steps":["trace[628859524] 'process raft request' (duration: 244.759192ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:25.277917Z","caller":"traceutil/trace.go:171","msg":"trace[91868999] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"134.814391ms","start":"2026-05-29T21:54:25.143068Z","end":"2026-05-29T21:54:25.277883Z","steps":["trace[91868999] 'process raft request' (duration: 70.618251ms)","trace[91868999] 'compare' (duration: 63.940264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:37.347969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291823442817182,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:54:37.70997Z","caller":"traceutil/trace.go:171","msg":"trace[766046840] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"862.877892ms","start":"2026-05-29T21:54:36.847059Z","end":"2026-05-29T21:54:37.709937Z","steps":["trace[766046840] 'read index received' (duration: 862.710459ms)","trace[766046840] 'applied index is now lower than readState.Index' (duration: 165.543µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:37.710371Z","caller":"traceutil/trace.go:171","msg":"trace[1298054360] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"872.731183ms","start":"2026-05-29T21:54:36.837624Z","end":"2026-05-29T21:54:37.710355Z","steps":["trace[1298054360] 'process raft request' (duration: 872.10704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:37.710583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:36.837584Z","time spent":"872.827585ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:37.711122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.538009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T21:54:37.711202Z","caller":"traceutil/trace.go:171","msg":"trace[1950696500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1180; }","duration":"830.683513ms","start":"2026-05-29T21:54:36.8805Z","end":"2026-05-29T21:54:37.711183Z","steps":["trace[1950696500] 'agreement among raft nodes before linearized reading' (duration: 830.474789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:37.711218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.166498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T21:54:37.711236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.550753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:37.71135Z","caller":"traceutil/trace.go:171","msg":"trace[1635926527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"611.722057ms","start":"2026-05-29T21:54:37.099611Z","end":"2026-05-29T21:54:37.711333Z","steps":["trace[1635926527] 'agreement among raft nodes before linearized reading' (duration: 611.576603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:37.71145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:37.099556Z","time spent":"611.88232ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:54:37.711256Z","caller":"traceutil/trace.go:171","msg":"trace[1757766356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"864.246919ms","start":"2026-05-29T21:54:36.846998Z","end":"2026-05-29T21:54:37.711245Z","steps":["trace[1757766356] 'agreement among raft nodes before linearized reading' (duration: 864.160988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:37.711592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:36.846969Z","time spent":"864.611967ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:54:37.711126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.349515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:54:37.711716Z","caller":"traceutil/trace.go:171","msg":"trace[838422888] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1180; }","duration":"511.016089ms","start":"2026-05-29T21:54:37.200689Z","end":"2026-05-29T21:54:37.711705Z","steps":["trace[838422888] 'agreement among raft nodes before linearized reading' (duration: 510.358315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:37.711751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:37.200661Z","time spent":"511.078501ms","remote":"127.0.0.1:48766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:54:37.711246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:36.880468Z","time spent":"830.767154ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:54:55.737004Z","caller":"traceutil/trace.go:171","msg":"trace[1691388489] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"341.345156ms","start":"2026-05-29T21:54:55.39564Z","end":"2026-05-29T21:54:55.736985Z","steps":["trace[1691388489] 'read index received' (duration: 341.220784ms)","trace[1691388489] 'applied index is now lower than readState.Index' (duration: 123.522µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:55.737107Z","caller":"traceutil/trace.go:171","msg":"trace[1295001635] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"385.654172ms","start":"2026-05-29T21:54:55.35141Z","end":"2026-05-29T21:54:55.737064Z","steps":["trace[1295001635] 'process raft request' (duration: 385.451508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:55.7373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.629542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T21:54:55.737365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:55.351393Z","time spent":"385.850315ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4335,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:55.737406Z","caller":"traceutil/trace.go:171","msg":"trace[738622997] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1325; }","duration":"341.817535ms","start":"2026-05-29T21:54:55.395565Z","end":"2026-05-29T21:54:55.737383Z","steps":["trace[738622997] 'agreement among raft nodes before linearized reading' (duration: 341.54078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:55.737712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:55.395542Z","time spent":"342.12683ms","remote":"127.0.0.1:48538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:55:01.670505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.311215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-2jr4m\" ","response":"range_response_count:1 size:4179"} {"level":"info","ts":"2026-05-29T21:55:01.670613Z","caller":"traceutil/trace.go:171","msg":"trace[343283367] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-2jr4m; range_end:; response_count:1; response_revision:1444; }","duration":"289.57033ms","start":"2026-05-29T21:55:01.381018Z","end":"2026-05-29T21:55:01.670588Z","steps":["trace[343283367] 'range keys from in-memory index tree' (duration: 288.976329ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:03.939691Z","caller":"traceutil/trace.go:171","msg":"trace[831302048] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"115.055434ms","start":"2026-05-29T21:55:03.824605Z","end":"2026-05-29T21:55:03.93966Z","steps":["trace[831302048] 'process raft request' (duration: 114.874151ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:03.94048Z","caller":"traceutil/trace.go:171","msg":"trace[874081040] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"114.547755ms","start":"2026-05-29T21:55:03.825899Z","end":"2026-05-29T21:55:03.940447Z","steps":["trace[874081040] 'process raft request' (duration: 114.344042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:07.944913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.617229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:55:07.94534Z","caller":"traceutil/trace.go:171","msg":"trace[835697864] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"290.622248ms","start":"2026-05-29T21:55:07.654698Z","end":"2026-05-29T21:55:07.94532Z","steps":["trace[835697864] 'process raft request' (duration: 290.330483ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:07.945352Z","caller":"traceutil/trace.go:171","msg":"trace[109079366] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"295.305658ms","start":"2026-05-29T21:55:07.650016Z","end":"2026-05-29T21:55:07.945322Z","steps":["trace[109079366] 'process raft request' (duration: 69.197881ms)","trace[109079366] 'compare' (duration: 225.491087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:07.945465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.346441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:07.94534Z","caller":"traceutil/trace.go:171","msg":"trace[253833069] linearizableReadLoop","detail":"{readStateIndex:1558; appliedIndex:1556; }","duration":"226.220569ms","start":"2026-05-29T21:55:07.719101Z","end":"2026-05-29T21:55:07.945321Z","steps":["trace[253833069] 'read index received' (duration: 123.982µs)","trace[253833069] 'applied index is now lower than readState.Index' (duration: 226.094827ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:07.945554Z","caller":"traceutil/trace.go:171","msg":"trace[1856489674] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:0; response_revision:1509; }","duration":"226.516944ms","start":"2026-05-29T21:55:07.719024Z","end":"2026-05-29T21:55:07.945541Z","steps":["trace[1856489674] 'agreement among raft nodes before linearized reading' (duration: 226.348471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:08.321315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.522302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:55:08.321975Z","caller":"traceutil/trace.go:171","msg":"trace[1434574525] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"166.984608ms","start":"2026-05-29T21:55:08.154976Z","end":"2026-05-29T21:55:08.32196Z","steps":["trace[1434574525] 'process raft request' (duration: 166.942217ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:08.322109Z","caller":"traceutil/trace.go:171","msg":"trace[679051808] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"173.112074ms","start":"2026-05-29T21:55:08.148887Z","end":"2026-05-29T21:55:08.321999Z","steps":["trace[679051808] 'process raft request' (duration: 69.828978ms)","trace[679051808] 'compare' (duration: 102.41005ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:08.506612Z","caller":"traceutil/trace.go:171","msg":"trace[1672741212] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"177.261175ms","start":"2026-05-29T21:55:08.329325Z","end":"2026-05-29T21:55:08.506586Z","steps":["trace[1672741212] 'process raft request' (duration: 142.321441ms)","trace[1672741212] 'compare' (duration: 34.569707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:08.506787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.767879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/superstreams.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:08.506906Z","caller":"traceutil/trace.go:171","msg":"trace[1350194339] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/superstreams.rabbitmq.com; range_end:; response_count:0; response_revision:1522; }","duration":"175.934563ms","start":"2026-05-29T21:55:08.330939Z","end":"2026-05-29T21:55:08.506873Z","steps":["trace[1350194339] 'agreement among raft nodes before linearized reading' (duration: 175.744029ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:08.506515Z","caller":"traceutil/trace.go:171","msg":"trace[223237595] linearizableReadLoop","detail":"{readStateIndex:1572; appliedIndex:1570; }","duration":"175.488424ms","start":"2026-05-29T21:55:08.330985Z","end":"2026-05-29T21:55:08.506474Z","steps":["trace[223237595] 'read index received' (duration: 73.46698ms)","trace[223237595] 'applied index is now lower than readState.Index' (duration: 102.019894ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:08.721969Z","caller":"traceutil/trace.go:171","msg":"trace[1790613077] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"112.20309ms","start":"2026-05-29T21:55:08.609661Z","end":"2026-05-29T21:55:08.721864Z","steps":["trace[1790613077] 'process raft request' (duration: 111.928286ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:09.53534Z","caller":"traceutil/trace.go:171","msg":"trace[2095925199] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"104.713974ms","start":"2026-05-29T21:55:09.430599Z","end":"2026-05-29T21:55:09.535313Z","steps":["trace[2095925199] 'process raft request' (duration: 104.536781ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:10.273505Z","caller":"traceutil/trace.go:171","msg":"trace[717602617] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"123.545258ms","start":"2026-05-29T21:55:10.149812Z","end":"2026-05-29T21:55:10.273357Z","steps":["trace[717602617] 'process raft request' (duration: 89.273041ms)","trace[717602617] 'compare' (duration: 34.037453ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:10.479418Z","caller":"traceutil/trace.go:171","msg":"trace[261554088] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"123.081589ms","start":"2026-05-29T21:55:10.356278Z","end":"2026-05-29T21:55:10.47936Z","steps":["trace[261554088] 'process raft request' (duration: 53.989465ms)","trace[261554088] 'compare' (duration: 64.584767ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:20.212812Z","caller":"traceutil/trace.go:171","msg":"trace[45041174] linearizableReadLoop","detail":"{readStateIndex:1777; appliedIndex:1776; }","duration":"192.975897ms","start":"2026-05-29T21:55:20.019808Z","end":"2026-05-29T21:55:20.212784Z","steps":["trace[45041174] 'read index received' (duration: 192.710432ms)","trace[45041174] 'applied index is now lower than readState.Index' (duration: 264.055µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:20.21291Z","caller":"traceutil/trace.go:171","msg":"trace[125975963] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"229.794294ms","start":"2026-05-29T21:55:19.98307Z","end":"2026-05-29T21:55:20.212864Z","steps":["trace[125975963] 'process raft request' (duration: 229.487429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:20.213166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.333802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T21:55:20.21322Z","caller":"traceutil/trace.go:171","msg":"trace[111079296] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1705; }","duration":"193.446204ms","start":"2026-05-29T21:55:20.019763Z","end":"2026-05-29T21:55:20.213209Z","steps":["trace[111079296] 'agreement among raft nodes before linearized reading' (duration: 193.227641ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.27782Z","caller":"traceutil/trace.go:171","msg":"trace[1977821184] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"176.098498ms","start":"2026-05-29T21:55:20.101696Z","end":"2026-05-29T21:55:20.277795Z","steps":["trace[1977821184] 'process raft request' (duration: 175.861504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:20.277886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.773625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:20.278007Z","caller":"traceutil/trace.go:171","msg":"trace[1260657041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1706; }","duration":"183.958379ms","start":"2026-05-29T21:55:20.094019Z","end":"2026-05-29T21:55:20.277977Z","steps":["trace[1260657041] 'agreement among raft nodes before linearized reading' (duration: 183.745574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:20.278173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.391681ms","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-05-29T21:55:20.278299Z","caller":"traceutil/trace.go:171","msg":"trace[749628174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1706; }","duration":"137.545954ms","start":"2026-05-29T21:55:20.140731Z","end":"2026-05-29T21:55:20.278277Z","steps":["trace[749628174] 'agreement among raft nodes before linearized reading' (duration: 137.092595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:20.2784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.928607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:55:20.278451Z","caller":"traceutil/trace.go:171","msg":"trace[818750582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1706; }","duration":"139.039719ms","start":"2026-05-29T21:55:20.139399Z","end":"2026-05-29T21:55:20.278439Z","steps":["trace[818750582] 'agreement among raft nodes before linearized reading' (duration: 138.892917ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.344307Z","caller":"traceutil/trace.go:171","msg":"trace[1107859086] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"122.18724ms","start":"2026-05-29T21:55:20.2221Z","end":"2026-05-29T21:55:20.344287Z","steps":["trace[1107859086] 'process raft request' (duration: 122.006417ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.344363Z","caller":"traceutil/trace.go:171","msg":"trace[1761117407] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"122.127849ms","start":"2026-05-29T21:55:20.222218Z","end":"2026-05-29T21:55:20.344346Z","steps":["trace[1761117407] 'process raft request' (duration: 122.061548ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.344641Z","caller":"traceutil/trace.go:171","msg":"trace[2034019649] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"122.463085ms","start":"2026-05-29T21:55:20.222166Z","end":"2026-05-29T21:55:20.344629Z","steps":["trace[2034019649] 'process raft request' (duration: 122.030948ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.344704Z","caller":"traceutil/trace.go:171","msg":"trace[1468829644] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"122.470495ms","start":"2026-05-29T21:55:20.22218Z","end":"2026-05-29T21:55:20.34465Z","steps":["trace[1468829644] 'process raft request' (duration: 122.060819ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.344971Z","caller":"traceutil/trace.go:171","msg":"trace[1492344589] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"122.278762ms","start":"2026-05-29T21:55:20.22266Z","end":"2026-05-29T21:55:20.344939Z","steps":["trace[1492344589] 'process raft request' (duration: 121.653622ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.34695Z","caller":"traceutil/trace.go:171","msg":"trace[101530751] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"123.914429ms","start":"2026-05-29T21:55:20.223016Z","end":"2026-05-29T21:55:20.34693Z","steps":["trace[101530751] 'process raft request' (duration: 121.786424ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:20.348054Z","caller":"traceutil/trace.go:171","msg":"trace[565211225] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"123.277979ms","start":"2026-05-29T21:55:20.224751Z","end":"2026-05-29T21:55:20.348029Z","steps":["trace[565211225] 'process raft request' (duration: 123.159156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.879279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291823442818579,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:55:32.933692Z","caller":"traceutil/trace.go:171","msg":"trace[943039269] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"554.624357ms","start":"2026-05-29T21:55:32.37904Z","end":"2026-05-29T21:55:32.933664Z","steps":["trace[943039269] 'read index received' (duration: 554.327353ms)","trace[943039269] 'applied index is now lower than readState.Index' (duration: 295.874µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:32.933785Z","caller":"traceutil/trace.go:171","msg":"trace[1126639874] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"603.219854ms","start":"2026-05-29T21:55:32.330538Z","end":"2026-05-29T21:55:32.933758Z","steps":["trace[1126639874] 'process raft request' (duration: 602.95812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.933887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.82562ms","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-05-29T21:55:32.933938Z","caller":"traceutil/trace.go:171","msg":"trace[1875359279] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1879; }","duration":"554.972422ms","start":"2026-05-29T21:55:32.378952Z","end":"2026-05-29T21:55:32.933925Z","steps":["trace[1875359279] 'agreement among raft nodes before linearized reading' (duration: 554.872931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.933944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.330507Z","time spent":"603.331225ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:55:32.933971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.378937Z","time spent":"555.024324ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:55:32.934261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.465046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-29T21:55:32.934302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.246796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-29T21:55:32.934365Z","caller":"traceutil/trace.go:171","msg":"trace[1246153483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1879; }","duration":"244.643208ms","start":"2026-05-29T21:55:32.689698Z","end":"2026-05-29T21:55:32.934341Z","steps":["trace[1246153483] 'agreement among raft nodes before linearized reading' (duration: 244.343353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.934417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.658604ms","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":"warn","ts":"2026-05-29T21:55:32.934441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.953816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:55:32.934456Z","caller":"traceutil/trace.go:171","msg":"trace[1232862973] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1879; }","duration":"160.720334ms","start":"2026-05-29T21:55:32.773724Z","end":"2026-05-29T21:55:32.934444Z","steps":["trace[1232862973] 'agreement among raft nodes before linearized reading' (duration: 160.582592ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:32.934484Z","caller":"traceutil/trace.go:171","msg":"trace[652986522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1879; }","duration":"197.060788ms","start":"2026-05-29T21:55:32.737407Z","end":"2026-05-29T21:55:32.934468Z","steps":["trace[652986522] 'agreement among raft nodes before linearized reading' (duration: 196.939906ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:32.93443Z","caller":"traceutil/trace.go:171","msg":"trace[952688802] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"548.438988ms","start":"2026-05-29T21:55:32.385954Z","end":"2026-05-29T21:55:32.934393Z","steps":["trace[952688802] 'agreement among raft nodes before linearized reading' (duration: 548.052873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.934553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.38591Z","time spent":"548.633613ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-29T21:55:32.934555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.480439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f90b3a4-8876-42a8-872e-9b0ae9fb212b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-29T21:55:32.934596Z","caller":"traceutil/trace.go:171","msg":"trace[1048884690] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f90b3a4-8876-42a8-872e-9b0ae9fb212b; range_end:; response_count:1; response_revision:1879; }","duration":"397.5842ms","start":"2026-05-29T21:55:32.536997Z","end":"2026-05-29T21:55:32.934581Z","steps":["trace[1048884690] 'agreement among raft nodes before linearized reading' (duration: 397.471508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.934561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.064876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:55:32.934603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.075135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T21:55:32.934614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.918095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T21:55:32.934649Z","caller":"traceutil/trace.go:171","msg":"trace[746989450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1879; }","duration":"496.144025ms","start":"2026-05-29T21:55:32.438491Z","end":"2026-05-29T21:55:32.934635Z","steps":["trace[746989450] 'agreement among raft nodes before linearized reading' (duration: 496.026503ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:32.934651Z","caller":"traceutil/trace.go:171","msg":"trace[1929276586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1879; }","duration":"518.999936ms","start":"2026-05-29T21:55:32.415641Z","end":"2026-05-29T21:55:32.934641Z","steps":["trace[1929276586] 'agreement among raft nodes before linearized reading' (duration: 518.910185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.934649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.536963Z","time spent":"397.674212ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f90b3a4-8876-42a8-872e-9b0ae9fb212b\" "} {"level":"info","ts":"2026-05-29T21:55:32.934658Z","caller":"traceutil/trace.go:171","msg":"trace[267615654] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1879; }","duration":"498.192757ms","start":"2026-05-29T21:55:32.436441Z","end":"2026-05-29T21:55:32.934634Z","steps":["trace[267615654] 'agreement among raft nodes before linearized reading' (duration: 498.056015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:32.934684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.438475Z","time spent":"496.200507ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:55:32.93469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.415615Z","time spent":"519.067057ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:55:32.934705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.436429Z","time spent":"498.265898ms","remote":"127.0.0.1:45046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:55:33.414706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.944574ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:33.414857Z","caller":"traceutil/trace.go:171","msg":"trace[686219324] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1879; }","duration":"333.105547ms","start":"2026-05-29T21:55:33.081737Z","end":"2026-05-29T21:55:33.414843Z","steps":["trace[686219324] 'range keys from in-memory index tree' (duration: 332.933894ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:33.415375Z","caller":"traceutil/trace.go:171","msg":"trace[1389299273] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"472.747875ms","start":"2026-05-29T21:55:32.942619Z","end":"2026-05-29T21:55:33.415367Z","steps":["trace[1389299273] 'process raft request' (duration: 465.351259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:33.415907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.942596Z","time spent":"472.830616ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:33.613053Z","caller":"traceutil/trace.go:171","msg":"trace[994842908] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1955; }","duration":"499.671186ms","start":"2026-05-29T21:55:33.113357Z","end":"2026-05-29T21:55:33.613028Z","steps":["trace[994842908] 'read index received' (duration: 294.632483ms)","trace[994842908] 'applied index is now lower than readState.Index' (duration: 205.037683ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:33.61321Z","caller":"traceutil/trace.go:171","msg":"trace[1449917043] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"668.181857ms","start":"2026-05-29T21:55:32.945016Z","end":"2026-05-29T21:55:33.613198Z","steps":["trace[1449917043] 'process raft request' (duration: 667.808632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:33.613314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.944991Z","time spent":"668.245468ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:33.613266Z","caller":"traceutil/trace.go:171","msg":"trace[1121111090] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"669.275143ms","start":"2026-05-29T21:55:32.943927Z","end":"2026-05-29T21:55:33.613203Z","steps":["trace[1121111090] 'process raft request' (duration: 668.820106ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:33.613363Z","caller":"traceutil/trace.go:171","msg":"trace[860410044] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"667.878903ms","start":"2026-05-29T21:55:32.945475Z","end":"2026-05-29T21:55:33.613354Z","steps":["trace[860410044] 'process raft request' (duration: 667.477907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:33.613486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.945451Z","time spent":"667.941663ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:55:33.61359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.943915Z","time spent":"669.477387ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:33.613659Z","caller":"traceutil/trace.go:171","msg":"trace[1767995107] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"670.536233ms","start":"2026-05-29T21:55:32.943114Z","end":"2026-05-29T21:55:33.613651Z","steps":["trace[1767995107] 'process raft request' (duration: 643.058601ms)","trace[1767995107] 'compare' (duration: 26.284724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:33.613708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.943083Z","time spent":"670.605264ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:55:33.613755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.408907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:33.613793Z","caller":"traceutil/trace.go:171","msg":"trace[156237324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1886; }","duration":"500.497628ms","start":"2026-05-29T21:55:33.113285Z","end":"2026-05-29T21:55:33.613782Z","steps":["trace[156237324] 'agreement among raft nodes before linearized reading' (duration: 500.435457ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:33.613826Z","caller":"traceutil/trace.go:171","msg":"trace[1788882081] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"670.475832ms","start":"2026-05-29T21:55:32.943344Z","end":"2026-05-29T21:55:33.613819Z","steps":["trace[1788882081] 'process raft request' (duration: 669.317164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:33.613822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.113254Z","time spent":"500.56207ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:55:33.613852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.943237Z","time spent":"670.600394ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:33.613266Z","caller":"traceutil/trace.go:171","msg":"trace[1464019113] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"667.978944ms","start":"2026-05-29T21:55:32.945274Z","end":"2026-05-29T21:55:33.613253Z","steps":["trace[1464019113] 'process raft request' (duration: 667.634339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:33.613927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:32.945266Z","time spent":"668.645744ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:55:34.256074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.714001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:55:34.256193Z","caller":"traceutil/trace.go:171","msg":"trace[2027031822] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"642.962964ms","start":"2026-05-29T21:55:33.61321Z","end":"2026-05-29T21:55:34.256173Z","steps":["trace[2027031822] 'read index received' (duration: 403.978599ms)","trace[2027031822] 'applied index is now lower than readState.Index' (duration: 238.982875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:34.256304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.36368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:55:34.256264Z","caller":"traceutil/trace.go:171","msg":"trace[1570841596] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"815.333805ms","start":"2026-05-29T21:55:33.440848Z","end":"2026-05-29T21:55:34.256182Z","steps":["trace[1570841596] 'process raft request' (duration: 576.414921ms)","trace[1570841596] 'compare' (duration: 238.093551ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:34.256346Z","caller":"traceutil/trace.go:171","msg":"trace[500407787] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1887; }","duration":"905.447881ms","start":"2026-05-29T21:55:33.350887Z","end":"2026-05-29T21:55:34.256335Z","steps":["trace[500407787] 'agreement among raft nodes before linearized reading' (duration: 905.3685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:34.256382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.350869Z","time spent":"905.503512ms","remote":"127.0.0.1:48358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:55:34.256425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030318293s","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":"warn","ts":"2026-05-29T21:55:34.25645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.440836Z","time spent":"815.525098ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:34.256508Z","caller":"traceutil/trace.go:171","msg":"trace[807783386] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1887; }","duration":"1.030433165s","start":"2026-05-29T21:55:33.226054Z","end":"2026-05-29T21:55:34.256487Z","steps":["trace[807783386] 'agreement among raft nodes before linearized reading' (duration: 1.030308013s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:34.256559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.22604Z","time spent":"1.030505057s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:55:34.257087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076969668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f90b3a4-8876-42a8-872e-9b0ae9fb212b\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-29T21:55:34.257142Z","caller":"traceutil/trace.go:171","msg":"trace[2030231563] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f90b3a4-8876-42a8-872e-9b0ae9fb212b; range_end:; response_count:1; response_revision:1887; }","duration":"1.077150661s","start":"2026-05-29T21:55:33.179978Z","end":"2026-05-29T21:55:34.257128Z","steps":["trace[2030231563] 'agreement among raft nodes before linearized reading' (duration: 1.076775695s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:34.257195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.179938Z","time spent":"1.077248413s","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f90b3a4-8876-42a8-872e-9b0ae9fb212b\" "} {"level":"warn","ts":"2026-05-29T21:55:34.260074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.16326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:34.260145Z","caller":"traceutil/trace.go:171","msg":"trace[619031043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"645.273211ms","start":"2026-05-29T21:55:33.614856Z","end":"2026-05-29T21:55:34.260129Z","steps":["trace[619031043] 'agreement among raft nodes before linearized reading' (duration: 645.14525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:34.260187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.61484Z","time spent":"645.337732ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:55:34.260504Z","caller":"traceutil/trace.go:171","msg":"trace[30892384] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"521.284392ms","start":"2026-05-29T21:55:33.739203Z","end":"2026-05-29T21:55:34.260487Z","steps":["trace[30892384] 'process raft request' (duration: 520.650112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:34.260609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.739184Z","time spent":"521.372073ms","remote":"127.0.0.1:48526","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-05-29T21:55:34.260804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.160863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-29T21:55:34.260859Z","caller":"traceutil/trace.go:171","msg":"trace[1446021126] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1888; }","duration":"317.254134ms","start":"2026-05-29T21:55:33.943591Z","end":"2026-05-29T21:55:34.260845Z","steps":["trace[1446021126] 'agreement among raft nodes before linearized reading' (duration: 317.119762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:34.2609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:33.943562Z","time spent":"317.328255ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-29T21:55:39.094696Z","caller":"traceutil/trace.go:171","msg":"trace[1465182903] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"138.989237ms","start":"2026-05-29T21:55:38.955681Z","end":"2026-05-29T21:55:39.09467Z","steps":["trace[1465182903] 'process raft request' (duration: 138.885134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.71056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.694393ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-29T21:55:45.710656Z","caller":"traceutil/trace.go:171","msg":"trace[285798462] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"526.715695ms","start":"2026-05-29T21:55:45.183923Z","end":"2026-05-29T21:55:45.710638Z","steps":["trace[285798462] 'read index received' (duration: 44.888221ms)","trace[285798462] 'applied index is now lower than readState.Index' (duration: 481.825744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:45.710898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.971359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:45.710942Z","caller":"traceutil/trace.go:171","msg":"trace[1315907126] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1981; }","duration":"527.06779ms","start":"2026-05-29T21:55:45.183863Z","end":"2026-05-29T21:55:45.710931Z","steps":["trace[1315907126] 'agreement among raft nodes before linearized reading' (duration: 526.983639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.710976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:45.183835Z","time spent":"527.127431ms","remote":"127.0.0.1:44952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-29T21:55:45.711201Z","caller":"traceutil/trace.go:171","msg":"trace[1084557566] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"296.89641ms","start":"2026-05-29T21:55:45.414295Z","end":"2026-05-29T21:55:45.711191Z","steps":["trace[1084557566] 'process raft request' (duration: 296.809709ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:45.868893Z","caller":"traceutil/trace.go:171","msg":"trace[1233250759] linearizableReadLoop","detail":"{readStateIndex:2062; appliedIndex:2060; }","duration":"158.184968ms","start":"2026-05-29T21:55:45.710678Z","end":"2026-05-29T21:55:45.868863Z","steps":["trace[1233250759] 'read index received' (duration: 435.016µs)","trace[1233250759] 'applied index is now lower than readState.Index' (duration: 157.748982ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:45.869079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.236206ms","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":"warn","ts":"2026-05-29T21:55:45.869096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.033919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-29T21:55:45.869129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.133886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-29T21:55:45.869157Z","caller":"traceutil/trace.go:171","msg":"trace[1730501611] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1983; }","duration":"169.378588ms","start":"2026-05-29T21:55:45.699757Z","end":"2026-05-29T21:55:45.869136Z","steps":["trace[1730501611] 'agreement among raft nodes before linearized reading' (duration: 169.151775ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:45.869168Z","caller":"traceutil/trace.go:171","msg":"trace[1850860151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1983; }","duration":"404.239599ms","start":"2026-05-29T21:55:45.464917Z","end":"2026-05-29T21:55:45.869156Z","steps":["trace[1850860151] 'agreement among raft nodes before linearized reading' (duration: 404.084716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.869165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.319349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11979"} {"level":"info","ts":"2026-05-29T21:55:45.869175Z","caller":"traceutil/trace.go:171","msg":"trace[462105598] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1983; }","duration":"397.14232ms","start":"2026-05-29T21:55:45.472009Z","end":"2026-05-29T21:55:45.869151Z","steps":["trace[462105598] 'agreement among raft nodes before linearized reading' (duration: 396.942167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.869187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.33323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-29T21:55:45.869209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:45.464892Z","time spent":"404.307219ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T21:55:45.869216Z","caller":"traceutil/trace.go:171","msg":"trace[1972706887] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1983; }","duration":"375.40688ms","start":"2026-05-29T21:55:45.493795Z","end":"2026-05-29T21:55:45.869201Z","steps":["trace[1972706887] 'agreement among raft nodes before linearized reading' (duration: 375.258668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.869197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.868896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T21:55:45.869248Z","caller":"traceutil/trace.go:171","msg":"trace[767390877] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1983; }","duration":"534.457783ms","start":"2026-05-29T21:55:45.334771Z","end":"2026-05-29T21:55:45.869229Z","steps":["trace[767390877] 'agreement among raft nodes before linearized reading' (duration: 534.26042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.869256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:45.493776Z","time spent":"375.471522ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12001,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-29T21:55:45.869246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:45.471997Z","time spent":"397.229942ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-29T21:55:45.869281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:45.334723Z","time spent":"534.550763ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-29T21:55:45.869282Z","caller":"traceutil/trace.go:171","msg":"trace[558333229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1983; }","duration":"169.999747ms","start":"2026-05-29T21:55:45.699261Z","end":"2026-05-29T21:55:45.869261Z","steps":["trace[558333229] 'agreement among raft nodes before linearized reading' (duration: 169.746753ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:45.869469Z","caller":"traceutil/trace.go:171","msg":"trace[2099598570] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"324.460428ms","start":"2026-05-29T21:55:45.544957Z","end":"2026-05-29T21:55:45.869417Z","steps":["trace[2099598570] 'process raft request' (duration: 271.019008ms)","trace[2099598570] 'compare' (duration: 52.652689ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:45.869482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.098612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-5bqlr\" ","response":"range_response_count:1 size:5815"} {"level":"info","ts":"2026-05-29T21:55:45.869533Z","caller":"traceutil/trace.go:171","msg":"trace[159575223] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-5bqlr; range_end:; response_count:1; response_revision:1983; }","duration":"475.161984ms","start":"2026-05-29T21:55:45.394352Z","end":"2026-05-29T21:55:45.869514Z","steps":["trace[159575223] 'agreement among raft nodes before linearized reading' (duration: 475.016662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.869565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:45.544933Z","time spent":"324.570401ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:55:45.869612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.944865ms","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":"warn","ts":"2026-05-29T21:55:45.869561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.53514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T21:55:45.869631Z","caller":"traceutil/trace.go:171","msg":"trace[900093726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1983; }","duration":"165.983465ms","start":"2026-05-29T21:55:45.703642Z","end":"2026-05-29T21:55:45.869625Z","steps":["trace[900093726] 'agreement among raft nodes before linearized reading' (duration: 165.931405ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:45.869646Z","caller":"traceutil/trace.go:171","msg":"trace[638334574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1983; }","duration":"169.613512ms","start":"2026-05-29T21:55:45.700021Z","end":"2026-05-29T21:55:45.869634Z","steps":["trace[638334574] 'agreement among raft nodes before linearized reading' (duration: 169.49225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:45.869601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:45.394341Z","time spent":"475.243175ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5837,"request content":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-7fcdcd478-5bqlr\" "} {"level":"warn","ts":"2026-05-29T21:55:45.869731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.718647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:55:45.869762Z","caller":"traceutil/trace.go:171","msg":"trace[1196290722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1983; }","duration":"168.770848ms","start":"2026-05-29T21:55:45.700982Z","end":"2026-05-29T21:55:45.869753Z","steps":["trace[1196290722] 'agreement among raft nodes before linearized reading' (duration: 168.678167ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.021469Z","caller":"traceutil/trace.go:171","msg":"trace[1296245594] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"146.54275ms","start":"2026-05-29T21:55:45.874883Z","end":"2026-05-29T21:55:46.021426Z","steps":["trace[1296245594] 'process raft request' (duration: 121.174187ms)","trace[1296245594] 'compare' (duration: 25.043288ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:46.021755Z","caller":"traceutil/trace.go:171","msg":"trace[1047357263] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"146.791784ms","start":"2026-05-29T21:55:45.874941Z","end":"2026-05-29T21:55:46.021732Z","steps":["trace[1047357263] 'process raft request' (duration: 146.441259ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.022237Z","caller":"traceutil/trace.go:171","msg":"trace[419702528] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"147.149819ms","start":"2026-05-29T21:55:45.875054Z","end":"2026-05-29T21:55:46.022204Z","steps":["trace[419702528] 'process raft request' (duration: 146.57377ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.023056Z","caller":"traceutil/trace.go:171","msg":"trace[115413410] linearizableReadLoop","detail":"{readStateIndex:2069; appliedIndex:2063; }","duration":"145.296991ms","start":"2026-05-29T21:55:45.877739Z","end":"2026-05-29T21:55:46.023036Z","steps":["trace[115413410] 'read index received' (duration: 9.335751ms)","trace[115413410] 'applied index is now lower than readState.Index' (duration: 135.95981ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:46.023183Z","caller":"traceutil/trace.go:171","msg":"trace[2069157296] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"146.49693ms","start":"2026-05-29T21:55:45.876669Z","end":"2026-05-29T21:55:46.023166Z","steps":["trace[2069157296] 'process raft request' (duration: 145.473004ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.023384Z","caller":"traceutil/trace.go:171","msg":"trace[146064246] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"143.318571ms","start":"2026-05-29T21:55:45.88003Z","end":"2026-05-29T21:55:46.023348Z","steps":["trace[146064246] 'process raft request' (duration: 142.934995ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.023534Z","caller":"traceutil/trace.go:171","msg":"trace[1175636621] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"146.758023ms","start":"2026-05-29T21:55:45.876741Z","end":"2026-05-29T21:55:46.023499Z","steps":["trace[1175636621] 'process raft request' (duration: 146.163304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:46.023604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.835569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:46.023802Z","caller":"traceutil/trace.go:171","msg":"trace[842395155] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1991; }","duration":"146.061862ms","start":"2026-05-29T21:55:45.877719Z","end":"2026-05-29T21:55:46.023781Z","steps":["trace[842395155] 'agreement among raft nodes before linearized reading' (duration: 145.655836ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.023785Z","caller":"traceutil/trace.go:171","msg":"trace[78543423] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"146.950236ms","start":"2026-05-29T21:55:45.876717Z","end":"2026-05-29T21:55:46.023667Z","steps":["trace[78543423] 'process raft request' (duration: 146.091774ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.322659Z","caller":"traceutil/trace.go:171","msg":"trace[311109741] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"281.029049ms","start":"2026-05-29T21:55:46.041606Z","end":"2026-05-29T21:55:46.322635Z","steps":["trace[311109741] 'process raft request' (duration: 245.140397ms)","trace[311109741] 'compare' (duration: 35.683719ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:46.362772Z","caller":"traceutil/trace.go:171","msg":"trace[215826507] linearizableReadLoop","detail":"{readStateIndex:2072; appliedIndex:2070; }","duration":"313.085663ms","start":"2026-05-29T21:55:46.049662Z","end":"2026-05-29T21:55:46.362748Z","steps":["trace[215826507] 'read index received' (duration: 237.024344ms)","trace[215826507] 'applied index is now lower than readState.Index' (duration: 76.060379ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:46.362857Z","caller":"traceutil/trace.go:171","msg":"trace[1594684684] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"316.967892ms","start":"2026-05-29T21:55:46.04586Z","end":"2026-05-29T21:55:46.362828Z","steps":["trace[1594684684] 'process raft request' (duration: 316.766949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:46.363014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:46.045832Z","time spent":"317.094233ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5885,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:55:46.363119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.405288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-29T21:55:46.363206Z","caller":"traceutil/trace.go:171","msg":"trace[524762949] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1993; }","duration":"313.522549ms","start":"2026-05-29T21:55:46.049635Z","end":"2026-05-29T21:55:46.363157Z","steps":["trace[524762949] 'agreement among raft nodes before linearized reading' (duration: 313.283176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:46.36325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:46.049625Z","time spent":"313.612571ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-29T21:55:46.404687Z","caller":"traceutil/trace.go:171","msg":"trace[1883767508] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"111.07613ms","start":"2026-05-29T21:55:46.29357Z","end":"2026-05-29T21:55:46.404646Z","steps":["trace[1883767508] 'process raft request' (duration: 110.869347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:46.405199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.496639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:46.405254Z","caller":"traceutil/trace.go:171","msg":"trace[17690842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1996; }","duration":"307.58139ms","start":"2026-05-29T21:55:46.097659Z","end":"2026-05-29T21:55:46.405241Z","steps":["trace[17690842] 'agreement among raft nodes before linearized reading' (duration: 307.487738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:46.405285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:46.097646Z","time spent":"307.633591ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:55:46.56604Z","caller":"traceutil/trace.go:171","msg":"trace[1868599686] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"197.135361ms","start":"2026-05-29T21:55:46.368884Z","end":"2026-05-29T21:55:46.566019Z","steps":["trace[1868599686] 'process raft request' (duration: 103.906141ms)","trace[1868599686] 'compare' (duration: 93.042196ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:46.56659Z","caller":"traceutil/trace.go:171","msg":"trace[332657317] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"196.762404ms","start":"2026-05-29T21:55:46.369798Z","end":"2026-05-29T21:55:46.566561Z","steps":["trace[332657317] 'process raft request' (duration: 196.167725ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.566694Z","caller":"traceutil/trace.go:171","msg":"trace[1795708871] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"191.013537ms","start":"2026-05-29T21:55:46.375666Z","end":"2026-05-29T21:55:46.566679Z","steps":["trace[1795708871] 'process raft request' (duration: 190.781524ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.56659Z","caller":"traceutil/trace.go:171","msg":"trace[1825030910] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2075; }","duration":"160.479177ms","start":"2026-05-29T21:55:46.406086Z","end":"2026-05-29T21:55:46.566565Z","steps":["trace[1825030910] 'read index received' (duration: 66.728589ms)","trace[1825030910] 'applied index is now lower than readState.Index' (duration: 93.749648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:55:46.567367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.258578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:46.56742Z","caller":"traceutil/trace.go:171","msg":"trace[146517087] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1999; }","duration":"161.40153ms","start":"2026-05-29T21:55:46.406006Z","end":"2026-05-29T21:55:46.567407Z","steps":["trace[146517087] 'agreement among raft nodes before linearized reading' (duration: 160.633779ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.701966Z","caller":"traceutil/trace.go:171","msg":"trace[1850849769] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"125.399846ms","start":"2026-05-29T21:55:46.576536Z","end":"2026-05-29T21:55:46.701936Z","steps":["trace[1850849769] 'process raft request' (duration: 116.125906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:51.410532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291823442818883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:55:51.523615Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107821557s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:55:51.523999Z","caller":"traceutil/trace.go:171","msg":"trace[1158511341] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"1.108322965s","start":"2026-05-29T21:55:50.415662Z","end":"2026-05-29T21:55:51.523985Z","steps":["trace[1158511341] 'process raft request' (duration: 1.108226904s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:51.52409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:50.415632Z","time spent":"1.108398016s","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:51.65112Z","caller":"traceutil/trace.go:171","msg":"trace[701049065] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2100; }","duration":"741.486025ms","start":"2026-05-29T21:55:50.909598Z","end":"2026-05-29T21:55:51.651084Z","steps":["trace[701049065] 'read index received' (duration: 614.560991ms)","trace[701049065] 'applied index is now lower than readState.Index' (duration: 126.923244ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:55:51.65132Z","caller":"traceutil/trace.go:171","msg":"trace[1740609199] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"1.234751232s","start":"2026-05-29T21:55:50.416549Z","end":"2026-05-29T21:55:51.6513Z","steps":["trace[1740609199] 'process raft request' (duration: 1.234233044s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:51.651486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:50.416519Z","time spent":"1.234847013s","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:51.651683Z","caller":"traceutil/trace.go:171","msg":"trace[1175113297] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"520.234141ms","start":"2026-05-29T21:55:51.131406Z","end":"2026-05-29T21:55:51.65164Z","steps":["trace[1175113297] 'process raft request' (duration: 519.571031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:51.651814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.165344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-29T21:55:51.651894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:51.131389Z","time spent":"520.392443ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:55:51.651954Z","caller":"traceutil/trace.go:171","msg":"trace[162627156] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2023; }","duration":"742.359547ms","start":"2026-05-29T21:55:50.909564Z","end":"2026-05-29T21:55:51.651923Z","steps":["trace[162627156] 'agreement among raft nodes before linearized reading' (duration: 741.971031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:51.652024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:50.909548Z","time spent":"742.463809ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-29T21:55:51.652082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.124619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"warn","ts":"2026-05-29T21:55:51.65186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.662616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:51.652133Z","caller":"traceutil/trace.go:171","msg":"trace[798979838] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2023; }","duration":"280.20025ms","start":"2026-05-29T21:55:51.37192Z","end":"2026-05-29T21:55:51.65212Z","steps":["trace[798979838] 'agreement among raft nodes before linearized reading' (duration: 280.023227ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:51.652316Z","caller":"traceutil/trace.go:171","msg":"trace[553287329] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"289.670501ms","start":"2026-05-29T21:55:51.362601Z","end":"2026-05-29T21:55:51.652271Z","steps":["trace[553287329] 'process raft request' (duration: 288.422333ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:51.652378Z","caller":"traceutil/trace.go:171","msg":"trace[803539330] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2023; }","duration":"677.176043ms","start":"2026-05-29T21:55:50.975155Z","end":"2026-05-29T21:55:51.652331Z","steps":["trace[803539330] 'agreement among raft nodes before linearized reading' (duration: 676.650445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:51.652527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:50.975138Z","time spent":"677.349245ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:55:51.652372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.543377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:51.661059Z","caller":"traceutil/trace.go:171","msg":"trace[1386801863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2023; }","duration":"568.270746ms","start":"2026-05-29T21:55:51.092755Z","end":"2026-05-29T21:55:51.661026Z","steps":["trace[1386801863] 'agreement among raft nodes before linearized reading' (duration: 559.490095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:51.661143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:51.092734Z","time spent":"568.380678ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:56:13.96674Z","caller":"traceutil/trace.go:171","msg":"trace[1415022562] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"349.438782ms","start":"2026-05-29T21:56:13.617261Z","end":"2026-05-29T21:56:13.966699Z","steps":["trace[1415022562] 'process raft request' (duration: 349.193778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:13.967221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:13.617221Z","time spent":"349.631395ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:56:14.351201Z","caller":"traceutil/trace.go:171","msg":"trace[80556765] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"618.682534ms","start":"2026-05-29T21:56:13.732488Z","end":"2026-05-29T21:56:14.35117Z","steps":["trace[80556765] 'process raft request' (duration: 528.551907ms)","trace[80556765] 'compare' (duration: 89.740972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:14.351357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:13.732472Z","time spent":"618.808497ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:56:14.351458Z","caller":"traceutil/trace.go:171","msg":"trace[265319383] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2228; }","duration":"544.642183ms","start":"2026-05-29T21:56:13.806773Z","end":"2026-05-29T21:56:14.351415Z","steps":["trace[265319383] 'read index received' (duration: 159.666817ms)","trace[265319383] 'applied index is now lower than readState.Index' (duration: 384.973596ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:56:14.351708Z","caller":"traceutil/trace.go:171","msg":"trace[472753963] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"615.622322ms","start":"2026-05-29T21:56:13.736066Z","end":"2026-05-29T21:56:14.351688Z","steps":["trace[472753963] 'process raft request' (duration: 615.052094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:14.351793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:13.736035Z","time spent":"615.718612ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:56:14.352026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.229821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-29T21:56:14.352117Z","caller":"traceutil/trace.go:171","msg":"trace[1738440009] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2146; }","duration":"545.358743ms","start":"2026-05-29T21:56:13.806735Z","end":"2026-05-29T21:56:14.352094Z","steps":["trace[1738440009] 'agreement among raft nodes before linearized reading' (duration: 545.031689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:14.352247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:13.806721Z","time spent":"545.482565ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-29T21:56:14.353042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.918597ms","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-05-29T21:56:14.353095Z","caller":"traceutil/trace.go:171","msg":"trace[1720803248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2146; }","duration":"335.031628ms","start":"2026-05-29T21:56:14.018051Z","end":"2026-05-29T21:56:14.353083Z","steps":["trace[1720803248] 'agreement among raft nodes before linearized reading' (duration: 334.886996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:14.353131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.837337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:56:14.353192Z","caller":"traceutil/trace.go:171","msg":"trace[1532791455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2146; }","duration":"145.949868ms","start":"2026-05-29T21:56:14.207227Z","end":"2026-05-29T21:56:14.353176Z","steps":["trace[1532791455] 'agreement among raft nodes before linearized reading' (duration: 145.776306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:14.353118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:14.018027Z","time spent":"335.085979ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:56:14.353477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.848967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-29T21:56:14.353465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.153768ms","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-05-29T21:56:14.353519Z","caller":"traceutil/trace.go:171","msg":"trace[898835911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2146; }","duration":"145.905108ms","start":"2026-05-29T21:56:14.207602Z","end":"2026-05-29T21:56:14.353507Z","steps":["trace[898835911] 'agreement among raft nodes before linearized reading' (duration: 145.785696ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:14.35354Z","caller":"traceutil/trace.go:171","msg":"trace[1033491358] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2146; }","duration":"150.313701ms","start":"2026-05-29T21:56:14.203205Z","end":"2026-05-29T21:56:14.353519Z","steps":["trace[1033491358] 'agreement among raft nodes before linearized reading' (duration: 150.070877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:14.35365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.093515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T21:56:14.353686Z","caller":"traceutil/trace.go:171","msg":"trace[1286979800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2146; }","duration":"159.171396ms","start":"2026-05-29T21:56:14.194505Z","end":"2026-05-29T21:56:14.353677Z","steps":["trace[1286979800] 'agreement among raft nodes before linearized reading' (duration: 159.089755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:14.35424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.234512ms","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-05-29T21:56:14.354522Z","caller":"traceutil/trace.go:171","msg":"trace[1956889835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2146; }","duration":"158.583406ms","start":"2026-05-29T21:56:14.195916Z","end":"2026-05-29T21:56:14.354499Z","steps":["trace[1956889835] 'agreement among raft nodes before linearized reading' (duration: 157.115546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:14.607522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.880461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:56:14.608106Z","caller":"traceutil/trace.go:171","msg":"trace[1429039639] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"246.658805ms","start":"2026-05-29T21:56:14.361389Z","end":"2026-05-29T21:56:14.608048Z","steps":["trace[1429039639] 'process raft request' (duration: 246.246489ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:14.608255Z","caller":"traceutil/trace.go:171","msg":"trace[1633716653] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"246.067577ms","start":"2026-05-29T21:56:14.362166Z","end":"2026-05-29T21:56:14.608234Z","steps":["trace[1633716653] 'process raft request' (duration: 245.51997ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:14.608302Z","caller":"traceutil/trace.go:171","msg":"trace[605576176] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"245.847984ms","start":"2026-05-29T21:56:14.362444Z","end":"2026-05-29T21:56:14.608292Z","steps":["trace[605576176] 'process raft request' (duration: 245.281697ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:14.608337Z","caller":"traceutil/trace.go:171","msg":"trace[1031406793] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"245.619701ms","start":"2026-05-29T21:56:14.362709Z","end":"2026-05-29T21:56:14.608329Z","steps":["trace[1031406793] 'process raft request' (duration: 245.057363ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:14.608425Z","caller":"traceutil/trace.go:171","msg":"trace[1937716432] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"247.048341ms","start":"2026-05-29T21:56:14.361365Z","end":"2026-05-29T21:56:14.608413Z","steps":["trace[1937716432] 'process raft request' (duration: 116.170646ms)","trace[1937716432] 'compare' (duration: 129.717668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:15.687292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.710077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:56:15.687393Z","caller":"traceutil/trace.go:171","msg":"trace[1155331635] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2153; }","duration":"612.879109ms","start":"2026-05-29T21:56:15.074489Z","end":"2026-05-29T21:56:15.687368Z","steps":["trace[1155331635] 'count revisions from in-memory index tree' (duration: 612.626216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:15.687446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:15.074468Z","time spent":"612.966061ms","remote":"127.0.0.1:45058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:56:15.68772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.186772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:56:15.687823Z","caller":"traceutil/trace.go:171","msg":"trace[1142963367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2153; }","duration":"591.348275ms","start":"2026-05-29T21:56:15.09645Z","end":"2026-05-29T21:56:15.687799Z","steps":["trace[1142963367] 'range keys from in-memory index tree' (duration: 591.03665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:15.687862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:15.09643Z","time spent":"591.424556ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:56:15.687859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.631166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:56:15.687934Z","caller":"traceutil/trace.go:171","msg":"trace[712132395] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2153; }","duration":"905.788448ms","start":"2026-05-29T21:56:14.782125Z","end":"2026-05-29T21:56:15.687913Z","steps":["trace[712132395] 'count revisions from in-memory index tree' (duration: 905.529775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:15.687986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:14.782102Z","time spent":"905.871729ms","remote":"127.0.0.1:48338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:56:16.102487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.731825ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-29T21:56:16.102719Z","caller":"traceutil/trace.go:171","msg":"trace[1457305491] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"766.500889ms","start":"2026-05-29T21:56:15.336198Z","end":"2026-05-29T21:56:16.102699Z","steps":["trace[1457305491] 'read index received' (duration: 8.47439ms)","trace[1457305491] 'applied index is now lower than readState.Index' (duration: 758.025389ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:16.103233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.017726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b42806521fe9b7\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-29T21:56:16.103334Z","caller":"traceutil/trace.go:171","msg":"trace[450557460] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b42806521fe9b7; range_end:; response_count:1; response_revision:2153; }","duration":"767.178338ms","start":"2026-05-29T21:56:15.336128Z","end":"2026-05-29T21:56:16.103306Z","steps":["trace[450557460] 'agreement among raft nodes before linearized reading' (duration: 766.910184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:16.103423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:15.3361Z","time spent":"767.28926ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b42806521fe9b7\" "} {"level":"info","ts":"2026-05-29T21:56:16.103561Z","caller":"traceutil/trace.go:171","msg":"trace[1784268185] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"190.546872ms","start":"2026-05-29T21:56:15.91299Z","end":"2026-05-29T21:56:16.103537Z","steps":["trace[1784268185] 'process raft request' (duration: 189.616619ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:16.181102Z","caller":"traceutil/trace.go:171","msg":"trace[1184152050] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"206.77037ms","start":"2026-05-29T21:56:15.974293Z","end":"2026-05-29T21:56:16.181063Z","steps":["trace[1184152050] 'process raft request' (duration: 186.366812ms)","trace[1184152050] 'compare' (duration: 19.951202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:16.181396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.895622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:56:16.181498Z","caller":"traceutil/trace.go:171","msg":"trace[363748695] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2156; }","duration":"183.035614ms","start":"2026-05-29T21:56:15.998446Z","end":"2026-05-29T21:56:16.181482Z","steps":["trace[363748695] 'agreement among raft nodes before linearized reading' (duration: 182.886582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:16.18161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.587621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:56:16.181657Z","caller":"traceutil/trace.go:171","msg":"trace[1364667322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2156; }","duration":"492.666493ms","start":"2026-05-29T21:56:15.688977Z","end":"2026-05-29T21:56:16.181643Z","steps":["trace[1364667322] 'agreement among raft nodes before linearized reading' (duration: 492.582422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:16.181694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:15.688953Z","time spent":"492.732133ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:56:16.181708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.287237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-29T21:56:16.181838Z","caller":"traceutil/trace.go:171","msg":"trace[1475996152] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2156; }","duration":"820.538541ms","start":"2026-05-29T21:56:15.361286Z","end":"2026-05-29T21:56:16.181824Z","steps":["trace[1475996152] 'agreement among raft nodes before linearized reading' (duration: 819.999803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:16.181969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:15.361209Z","time spent":"820.742094ms","remote":"127.0.0.1:44694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-29T21:56:16.181758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.761744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-29T21:56:16.182219Z","caller":"traceutil/trace.go:171","msg":"trace[434342813] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2156; }","duration":"143.234011ms","start":"2026-05-29T21:56:16.038958Z","end":"2026-05-29T21:56:16.182192Z","steps":["trace[434342813] 'agreement among raft nodes before linearized reading' (duration: 142.622122ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:16.653979Z","caller":"traceutil/trace.go:171","msg":"trace[1175200593] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"463.256709ms","start":"2026-05-29T21:56:16.190699Z","end":"2026-05-29T21:56:16.653956Z","steps":["trace[1175200593] 'read index received' (duration: 463.081877ms)","trace[1175200593] 'applied index is now lower than readState.Index' (duration: 173.812µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:16.65411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.389261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:56:16.654082Z","caller":"traceutil/trace.go:171","msg":"trace[1750123429] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"467.215416ms","start":"2026-05-29T21:56:16.186807Z","end":"2026-05-29T21:56:16.654023Z","steps":["trace[1750123429] 'process raft request' (duration: 466.895052ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:16.654151Z","caller":"traceutil/trace.go:171","msg":"trace[321718057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2157; }","duration":"463.459623ms","start":"2026-05-29T21:56:16.190681Z","end":"2026-05-29T21:56:16.654141Z","steps":["trace[321718057] 'agreement among raft nodes before linearized reading' (duration: 463.380952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:16.654188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:16.190673Z","time spent":"463.504063ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:56:16.654291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:16.186786Z","time spent":"467.381068ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:56:16.664138Z","caller":"traceutil/trace.go:171","msg":"trace[747467817] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"305.691906ms","start":"2026-05-29T21:56:16.358436Z","end":"2026-05-29T21:56:16.664128Z","steps":["trace[747467817] 'process raft request' (duration: 305.641985ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:16.664217Z","caller":"traceutil/trace.go:171","msg":"trace[653631842] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"305.835817ms","start":"2026-05-29T21:56:16.358355Z","end":"2026-05-29T21:56:16.664191Z","steps":["trace[653631842] 'process raft request' (duration: 305.665525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:16.664316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:16.358406Z","time spent":"305.826617ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:56:16.664337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:56:16.358325Z","time spent":"305.95615ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:56:16.664951Z","caller":"traceutil/trace.go:171","msg":"trace[1439674860] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"142.134707ms","start":"2026-05-29T21:56:16.522801Z","end":"2026-05-29T21:56:16.664936Z","steps":["trace[1439674860] 'process raft request' (duration: 141.299625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:16.665035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.618021ms","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-05-29T21:56:16.665166Z","caller":"traceutil/trace.go:171","msg":"trace[25513445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2160; }","duration":"229.806435ms","start":"2026-05-29T21:56:16.435332Z","end":"2026-05-29T21:56:16.665139Z","steps":["trace[25513445] 'agreement among raft nodes before linearized reading' (duration: 229.386229ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:20.767536Z","caller":"traceutil/trace.go:171","msg":"trace[614640452] linearizableReadLoop","detail":"{readStateIndex:2647; appliedIndex:2646; }","duration":"281.276866ms","start":"2026-05-29T21:57:20.486233Z","end":"2026-05-29T21:57:20.76751Z","steps":["trace[614640452] 'read index received' (duration: 281.022773ms)","trace[614640452] 'applied index is now lower than readState.Index' (duration: 252.923µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:57:20.767706Z","caller":"traceutil/trace.go:171","msg":"trace[851612664] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"297.305735ms","start":"2026-05-29T21:57:20.470347Z","end":"2026-05-29T21:57:20.767653Z","steps":["trace[851612664] 'process raft request' (duration: 297.016751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:20.767826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.539169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:57:20.767882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.343846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:57:20.767959Z","caller":"traceutil/trace.go:171","msg":"trace[1605891747] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2546; }","duration":"154.488798ms","start":"2026-05-29T21:57:20.613453Z","end":"2026-05-29T21:57:20.767942Z","steps":["trace[1605891747] 'agreement among raft nodes before linearized reading' (duration: 154.342806ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:20.768207Z","caller":"traceutil/trace.go:171","msg":"trace[100584168] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2546; }","duration":"281.719632ms","start":"2026-05-29T21:57:20.486161Z","end":"2026-05-29T21:57:20.767881Z","steps":["trace[100584168] 'agreement among raft nodes before linearized reading' (duration: 281.534829ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:57:29.924485Z","caller":"traceutil/trace.go:171","msg":"trace[1758460956] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"172.037625ms","start":"2026-05-29T21:57:29.752418Z","end":"2026-05-29T21:57:29.924456Z","steps":["trace[1758460956] 'process raft request' (duration: 171.852711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:30.582898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.587996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:57:30.582986Z","caller":"traceutil/trace.go:171","msg":"trace[1053055876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2653; }","duration":"486.689797ms","start":"2026-05-29T21:57:30.096273Z","end":"2026-05-29T21:57:30.582962Z","steps":["trace[1053055876] 'range keys from in-memory index tree' (duration: 486.430954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:30.583029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:30.096191Z","time spent":"486.829459ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:57:30.583388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.36193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T21:57:30.583459Z","caller":"traceutil/trace.go:171","msg":"trace[1603711390] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:2653; }","duration":"559.481422ms","start":"2026-05-29T21:57:30.023959Z","end":"2026-05-29T21:57:30.58344Z","steps":["trace[1603711390] 'range keys from in-memory index tree' (duration: 559.194098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:57:30.583498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:57:30.023938Z","time spent":"559.552053ms","remote":"127.0.0.1:48270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-05-29T21:58:08.950621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.287403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-29T21:58:08.95073Z","caller":"traceutil/trace.go:171","msg":"trace[39035707] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2864; }","duration":"615.471009ms","start":"2026-05-29T21:58:08.335236Z","end":"2026-05-29T21:58:08.950707Z","steps":["trace[39035707] 'range keys from in-memory index tree' (duration: 614.979384ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:08.950723Z","caller":"traceutil/trace.go:171","msg":"trace[1912460365] linearizableReadLoop","detail":"{readStateIndex:2975; appliedIndex:2974; }","duration":"204.269882ms","start":"2026-05-29T21:58:08.746417Z","end":"2026-05-29T21:58:08.950687Z","steps":["trace[1912460365] 'read index received' (duration: 122.204881ms)","trace[1912460365] 'applied index is now lower than readState.Index' (duration: 82.063051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:08.950778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:58:08.335208Z","time spent":"615.557411ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-29T21:58:08.950884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.433947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:58:08.95094Z","caller":"traceutil/trace.go:171","msg":"trace[1024357571] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2865; }","duration":"204.558371ms","start":"2026-05-29T21:58:08.746366Z","end":"2026-05-29T21:58:08.950925Z","steps":["trace[1024357571] 'agreement among raft nodes before linearized reading' (duration: 204.441578ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:08.951218Z","caller":"traceutil/trace.go:171","msg":"trace[456956170] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"217.890791ms","start":"2026-05-29T21:58:08.733308Z","end":"2026-05-29T21:58:08.951198Z","steps":["trace[456956170] 'process raft request' (duration: 135.346155ms)","trace[456956170] 'compare' (duration: 81.788183ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:09.19724Z","caller":"traceutil/trace.go:171","msg":"trace[225303396] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"210.972764ms","start":"2026-05-29T21:58:08.986227Z","end":"2026-05-29T21:58:09.1972Z","steps":["trace[225303396] 'process raft request' (duration: 194.864391ms)","trace[225303396] 'compare' (duration: 15.599788ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:58:09.197297Z","caller":"traceutil/trace.go:171","msg":"trace[1199145504] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"184.251734ms","start":"2026-05-29T21:58:09.013016Z","end":"2026-05-29T21:58:09.197267Z","steps":["trace[1199145504] 'process raft request' (duration: 183.850422ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.197127Z","caller":"traceutil/trace.go:171","msg":"trace[224913521] linearizableReadLoop","detail":"{readStateIndex:2977; appliedIndex:2975; }","duration":"179.710546ms","start":"2026-05-29T21:58:09.017383Z","end":"2026-05-29T21:58:09.197094Z","steps":["trace[224913521] 'read index received' (duration: 163.727637ms)","trace[224913521] 'applied index is now lower than readState.Index' (duration: 15.981359ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:58:09.197864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.418378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:58:09.197992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.557385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:58:09.198069Z","caller":"traceutil/trace.go:171","msg":"trace[121573139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2869; }","duration":"101.72606ms","start":"2026-05-29T21:58:09.096328Z","end":"2026-05-29T21:58:09.198054Z","steps":["trace[121573139] 'agreement among raft nodes before linearized reading' (duration: 101.575306ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:58:09.197996Z","caller":"traceutil/trace.go:171","msg":"trace[1610391674] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2869; }","duration":"180.653514ms","start":"2026-05-29T21:58:09.017313Z","end":"2026-05-29T21:58:09.197966Z","steps":["trace[1610391674] 'agreement among raft nodes before linearized reading' (duration: 180.375526ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:00:46.555717Z","caller":"traceutil/trace.go:171","msg":"trace[335234214] linearizableReadLoop","detail":"{readStateIndex:3929; appliedIndex:3928; }","duration":"166.573386ms","start":"2026-05-29T22:00:46.389123Z","end":"2026-05-29T22:00:46.555696Z","steps":["trace[335234214] 'read index received' (duration: 160.891986ms)","trace[335234214] 'applied index is now lower than readState.Index' (duration: 5.68076ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:00:46.555794Z","caller":"traceutil/trace.go:171","msg":"trace[1824384382] transaction","detail":"{read_only:false; response_revision:3782; number_of_response:1; }","duration":"220.431095ms","start":"2026-05-29T22:00:46.335319Z","end":"2026-05-29T22:00:46.55575Z","steps":["trace[1824384382] 'process raft request' (duration: 214.658243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:46.555953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.809952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-29T22:00:46.556064Z","caller":"traceutil/trace.go:171","msg":"trace[561550524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3782; }","duration":"162.902594ms","start":"2026-05-29T22:00:46.393117Z","end":"2026-05-29T22:00:46.55602Z","steps":["trace[561550524] 'agreement among raft nodes before linearized reading' (duration: 162.71467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:00:46.555913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.775609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T22:00:46.556188Z","caller":"traceutil/trace.go:171","msg":"trace[371262476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3782; }","duration":"167.087026ms","start":"2026-05-29T22:00:46.38909Z","end":"2026-05-29T22:00:46.556177Z","steps":["trace[371262476] 'agreement among raft nodes before linearized reading' (duration: 166.712259ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:15.024247Z","caller":"traceutil/trace.go:171","msg":"trace[1851217412] transaction","detail":"{read_only:false; response_revision:3997; number_of_response:1; }","duration":"167.124672ms","start":"2026-05-29T22:01:14.857082Z","end":"2026-05-29T22:01:15.024206Z","steps":["trace[1851217412] 'process raft request' (duration: 78.844229ms)","trace[1851217412] 'compare' (duration: 87.695682ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:01:15.024421Z","caller":"traceutil/trace.go:171","msg":"trace[510932491] transaction","detail":"{read_only:false; response_revision:3998; number_of_response:1; }","duration":"146.586924ms","start":"2026-05-29T22:01:14.877784Z","end":"2026-05-29T22:01:15.024371Z","steps":["trace[510932491] 'process raft request' (duration: 146.310619ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:01:55.674389Z","caller":"traceutil/trace.go:171","msg":"trace[836553100] transaction","detail":"{read_only:false; response_revision:4247; number_of_response:1; }","duration":"168.092658ms","start":"2026-05-29T22:01:55.506248Z","end":"2026-05-29T22:01:55.674341Z","steps":["trace[836553100] 'process raft request' (duration: 167.857264ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:04.897852Z","caller":"traceutil/trace.go:171","msg":"trace[1572281214] linearizableReadLoop","detail":"{readStateIndex:4857; appliedIndex:4856; }","duration":"249.432073ms","start":"2026-05-29T22:02:04.64838Z","end":"2026-05-29T22:02:04.897812Z","steps":["trace[1572281214] 'read index received' (duration: 249.175609ms)","trace[1572281214] 'applied index is now lower than readState.Index' (duration: 254.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:04.897922Z","caller":"traceutil/trace.go:171","msg":"trace[52258407] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"254.630413ms","start":"2026-05-29T22:02:04.643243Z","end":"2026-05-29T22:02:04.897874Z","steps":["trace[52258407] 'process raft request' (duration: 254.318098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:04.898263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.845061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-29T22:02:04.898402Z","caller":"traceutil/trace.go:171","msg":"trace[1710534770] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4674; }","duration":"250.029353ms","start":"2026-05-29T22:02:04.648342Z","end":"2026-05-29T22:02:04.898371Z","steps":["trace[1710534770] 'agreement among raft nodes before linearized reading' (duration: 249.611556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:04.904327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.687576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-29T22:02:04.904366Z","caller":"traceutil/trace.go:171","msg":"trace[1840543916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4677; }","duration":"126.794378ms","start":"2026-05-29T22:02:04.777561Z","end":"2026-05-29T22:02:04.904356Z","steps":["trace[1840543916] 'agreement among raft nodes before linearized reading' (duration: 126.640715ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:04.904525Z","caller":"traceutil/trace.go:171","msg":"trace[1495155536] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"240.815056ms","start":"2026-05-29T22:02:04.663699Z","end":"2026-05-29T22:02:04.904514Z","steps":["trace[1495155536] 'process raft request' (duration: 240.241025ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:04.904823Z","caller":"traceutil/trace.go:171","msg":"trace[218449123] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"256.295172ms","start":"2026-05-29T22:02:04.648518Z","end":"2026-05-29T22:02:04.904813Z","steps":["trace[218449123] 'process raft request' (duration: 255.141401ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:04.904922Z","caller":"traceutil/trace.go:171","msg":"trace[1311713237] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"249.572636ms","start":"2026-05-29T22:02:04.655295Z","end":"2026-05-29T22:02:04.904867Z","steps":["trace[1311713237] 'process raft request' (duration: 248.460646ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.096531Z","caller":"traceutil/trace.go:171","msg":"trace[517361153] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4989; }","duration":"222.154462ms","start":"2026-05-29T22:02:09.874349Z","end":"2026-05-29T22:02:10.096503Z","steps":["trace[517361153] 'read index received' (duration: 189.878742ms)","trace[517361153] 'applied index is now lower than readState.Index' (duration: 32.27448ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:10.096727Z","caller":"traceutil/trace.go:171","msg":"trace[243494907] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"223.146069ms","start":"2026-05-29T22:02:09.87356Z","end":"2026-05-29T22:02:10.096706Z","steps":["trace[243494907] 'process raft request' (duration: 190.741107ms)","trace[243494907] 'compare' (duration: 32.029166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:10.097042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.64377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-29T22:02:10.097082Z","caller":"traceutil/trace.go:171","msg":"trace[447671722] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4803; }","duration":"222.773001ms","start":"2026-05-29T22:02:09.874297Z","end":"2026-05-29T22:02:10.09707Z","steps":["trace[447671722] 'agreement among raft nodes before linearized reading' (duration: 222.579998ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.257506Z","caller":"traceutil/trace.go:171","msg":"trace[1727631113] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"145.791298ms","start":"2026-05-29T22:02:10.111674Z","end":"2026-05-29T22:02:10.257466Z","steps":["trace[1727631113] 'process raft request' (duration: 141.086587ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.257948Z","caller":"traceutil/trace.go:171","msg":"trace[1792312275] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"123.828293ms","start":"2026-05-29T22:02:10.134098Z","end":"2026-05-29T22:02:10.257927Z","steps":["trace[1792312275] 'process raft request' (duration: 123.225282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.258225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.457786ms","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-05-29T22:02:10.258273Z","caller":"traceutil/trace.go:171","msg":"trace[206554940] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4805; }","duration":"130.565297ms","start":"2026-05-29T22:02:10.127693Z","end":"2026-05-29T22:02:10.258258Z","steps":["trace[206554940] 'agreement among raft nodes before linearized reading' (duration: 130.354494ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.497773Z","caller":"traceutil/trace.go:171","msg":"trace[772579732] linearizableReadLoop","detail":"{readStateIndex:4994; appliedIndex:4993; }","duration":"240.250848ms","start":"2026-05-29T22:02:10.25748Z","end":"2026-05-29T22:02:10.497731Z","steps":["trace[772579732] 'read index received' (duration: 233.414321ms)","trace[772579732] 'applied index is now lower than readState.Index' (duration: 6.835037ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:10.497874Z","caller":"traceutil/trace.go:171","msg":"trace[1311523805] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"325.118916ms","start":"2026-05-29T22:02:10.172711Z","end":"2026-05-29T22:02:10.49783Z","steps":["trace[1311523805] 'process raft request' (duration: 318.100457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.498042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:10.172684Z","time spent":"325.271328ms","remote":"127.0.0.1:48270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:10.498123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.499307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-qbpgz\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-05-29T22:02:10.498178Z","caller":"traceutil/trace.go:171","msg":"trace[1652354270] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-qbpgz; range_end:; response_count:1; response_revision:4806; }","duration":"289.6015ms","start":"2026-05-29T22:02:10.208562Z","end":"2026-05-29T22:02:10.498163Z","steps":["trace[1652354270] 'agreement among raft nodes before linearized reading' (duration: 289.343065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.498457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.499042ms","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-05-29T22:02:10.498496Z","caller":"traceutil/trace.go:171","msg":"trace[1899488728] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4806; }","duration":"322.589703ms","start":"2026-05-29T22:02:10.175897Z","end":"2026-05-29T22:02:10.498486Z","steps":["trace[1899488728] 'agreement among raft nodes before linearized reading' (duration: 322.528062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.498531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:10.175859Z","time spent":"322.662215ms","remote":"127.0.0.1:48338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" "} {"level":"info","ts":"2026-05-29T22:02:10.499485Z","caller":"traceutil/trace.go:171","msg":"trace[948700153] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"234.932387ms","start":"2026-05-29T22:02:10.264528Z","end":"2026-05-29T22:02:10.499461Z","steps":["trace[948700153] 'process raft request' (duration: 234.726334ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.49967Z","caller":"traceutil/trace.go:171","msg":"trace[1707070024] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"234.028172ms","start":"2026-05-29T22:02:10.265615Z","end":"2026-05-29T22:02:10.499643Z","steps":["trace[1707070024] 'process raft request' (duration: 233.85026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.500047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.281569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-29T22:02:10.500134Z","caller":"traceutil/trace.go:171","msg":"trace[1258593523] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4817; }","duration":"216.418881ms","start":"2026-05-29T22:02:10.283701Z","end":"2026-05-29T22:02:10.500119Z","steps":["trace[1258593523] 'agreement among raft nodes before linearized reading' (duration: 215.995264ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500252Z","caller":"traceutil/trace.go:171","msg":"trace[1367107136] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"234.785995ms","start":"2026-05-29T22:02:10.265417Z","end":"2026-05-29T22:02:10.500203Z","steps":["trace[1367107136] 'process raft request' (duration: 233.989962ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500305Z","caller":"traceutil/trace.go:171","msg":"trace[2053386016] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"233.664895ms","start":"2026-05-29T22:02:10.266632Z","end":"2026-05-29T22:02:10.500297Z","steps":["trace[2053386016] 'process raft request' (duration: 232.859722ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500373Z","caller":"traceutil/trace.go:171","msg":"trace[749046819] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"233.628365ms","start":"2026-05-29T22:02:10.266739Z","end":"2026-05-29T22:02:10.500367Z","steps":["trace[749046819] 'process raft request' (duration: 232.77401ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500452Z","caller":"traceutil/trace.go:171","msg":"trace[792386409] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"235.811163ms","start":"2026-05-29T22:02:10.264629Z","end":"2026-05-29T22:02:10.50044Z","steps":["trace[792386409] 'process raft request' (duration: 234.737345ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500519Z","caller":"traceutil/trace.go:171","msg":"trace[1981302562] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"211.568848ms","start":"2026-05-29T22:02:10.288942Z","end":"2026-05-29T22:02:10.500511Z","steps":["trace[1981302562] 'process raft request' (duration: 210.599262ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500546Z","caller":"traceutil/trace.go:171","msg":"trace[919051183] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"149.370747ms","start":"2026-05-29T22:02:10.351164Z","end":"2026-05-29T22:02:10.500535Z","steps":["trace[919051183] 'process raft request' (duration: 148.500663ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500639Z","caller":"traceutil/trace.go:171","msg":"trace[841693550] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"235.09223ms","start":"2026-05-29T22:02:10.265534Z","end":"2026-05-29T22:02:10.500627Z","steps":["trace[841693550] 'process raft request' (duration: 233.91009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:10.500664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.833352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-29T22:02:10.500691Z","caller":"traceutil/trace.go:171","msg":"trace[1878957457] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4817; }","duration":"211.868503ms","start":"2026-05-29T22:02:10.288813Z","end":"2026-05-29T22:02:10.500682Z","steps":["trace[1878957457] 'agreement among raft nodes before linearized reading' (duration: 211.796922ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.500823Z","caller":"traceutil/trace.go:171","msg":"trace[1673862889] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"180.537359ms","start":"2026-05-29T22:02:10.320273Z","end":"2026-05-29T22:02:10.50081Z","steps":["trace[1673862889] 'process raft request' (duration: 179.315969ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:10.501028Z","caller":"traceutil/trace.go:171","msg":"trace[1453108838] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"155.213627ms","start":"2026-05-29T22:02:10.34579Z","end":"2026-05-29T22:02:10.501004Z","steps":["trace[1453108838] 'process raft request' (duration: 153.847704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.661133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.860512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:18.661279Z","caller":"traceutil/trace.go:171","msg":"trace[1170705724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4958; }","duration":"565.009405ms","start":"2026-05-29T22:02:18.096196Z","end":"2026-05-29T22:02:18.661206Z","steps":["trace[1170705724] 'range keys from in-memory index tree' (duration: 564.73956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:18.661319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.096128Z","time spent":"565.181508ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T22:02:18.661351Z","caller":"traceutil/trace.go:171","msg":"trace[1572737950] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"108.179869ms","start":"2026-05-29T22:02:18.553137Z","end":"2026-05-29T22:02:18.661317Z","steps":["trace[1572737950] 'process raft request' (duration: 98.945003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.117114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.71092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:19.117911Z","caller":"traceutil/trace.go:171","msg":"trace[1963289216] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"563.284573ms","start":"2026-05-29T22:02:18.554579Z","end":"2026-05-29T22:02:19.117863Z","steps":["trace[1963289216] 'process raft request' (duration: 263.651338ms)","trace[1963289216] 'compare' (duration: 298.344864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:19.118136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.554554Z","time spent":"563.465767ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T22:02:19.11857Z","caller":"traceutil/trace.go:171","msg":"trace[219683909] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"561.218039ms","start":"2026-05-29T22:02:18.557317Z","end":"2026-05-29T22:02:19.118535Z","steps":["trace[219683909] 'process raft request' (duration: 559.950147ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:19.118673Z","caller":"traceutil/trace.go:171","msg":"trace[1834202928] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5150; }","duration":"455.394619ms","start":"2026-05-29T22:02:18.663263Z","end":"2026-05-29T22:02:19.118658Z","steps":["trace[1834202928] 'read index received' (duration: 154.891919ms)","trace[1834202928] 'applied index is now lower than readState.Index' (duration: 300.50077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:19.118819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.549302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:19.11886Z","caller":"traceutil/trace.go:171","msg":"trace[1262434024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4961; }","duration":"455.637824ms","start":"2026-05-29T22:02:18.663213Z","end":"2026-05-29T22:02:19.11885Z","steps":["trace[1262434024] 'agreement among raft nodes before linearized reading' (duration: 455.548892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.118892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.663193Z","time spent":"455.690724ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T22:02:19.119394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.5243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-29T22:02:19.119452Z","caller":"traceutil/trace.go:171","msg":"trace[1076533884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4961; }","duration":"122.620971ms","start":"2026-05-29T22:02:18.996818Z","end":"2026-05-29T22:02:19.119439Z","steps":["trace[1076533884] 'agreement among raft nodes before linearized reading' (duration: 122.455799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.119404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.820812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"warn","ts":"2026-05-29T22:02:19.119574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.000962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-29T22:02:19.11964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.171866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-349fd5a1-d87e-4c60-a52e-34eb84142184\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-29T22:02:19.119651Z","caller":"traceutil/trace.go:171","msg":"trace[1500906088] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4961; }","duration":"277.125274ms","start":"2026-05-29T22:02:18.842508Z","end":"2026-05-29T22:02:19.119633Z","steps":["trace[1500906088] 'agreement among raft nodes before linearized reading' (duration: 276.916741ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:19.119676Z","caller":"traceutil/trace.go:171","msg":"trace[1581372243] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-349fd5a1-d87e-4c60-a52e-34eb84142184; range_end:; response_count:1; response_revision:4961; }","duration":"228.225997ms","start":"2026-05-29T22:02:18.891437Z","end":"2026-05-29T22:02:19.119663Z","steps":["trace[1581372243] 'agreement among raft nodes before linearized reading' (duration: 228.133245ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:19.119741Z","caller":"traceutil/trace.go:171","msg":"trace[1395409742] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4961; }","duration":"192.094117ms","start":"2026-05-29T22:02:18.927523Z","end":"2026-05-29T22:02:19.119617Z","steps":["trace[1395409742] 'agreement among raft nodes before linearized reading' (duration: 191.441836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.11982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.734206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-29T22:02:19.119861Z","caller":"traceutil/trace.go:171","msg":"trace[416285154] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4961; }","duration":"312.768767ms","start":"2026-05-29T22:02:18.807079Z","end":"2026-05-29T22:02:19.119847Z","steps":["trace[416285154] 'agreement among raft nodes before linearized reading' (duration: 312.679536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.119891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.807005Z","time spent":"312.87661ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-29T22:02:19.120236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.324937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16232"} {"level":"info","ts":"2026-05-29T22:02:19.120317Z","caller":"traceutil/trace.go:171","msg":"trace[540034169] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4961; }","duration":"400.413979ms","start":"2026-05-29T22:02:18.719873Z","end":"2026-05-29T22:02:19.120287Z","steps":["trace[540034169] 'agreement among raft nodes before linearized reading' (duration: 398.951085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:19.120418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.719859Z","time spent":"400.548941ms","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16254,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-29T22:02:19.120802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:18.557307Z","time spent":"563.419026ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:20.10892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.061289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:20.109722Z","caller":"traceutil/trace.go:171","msg":"trace[168726393] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"749.745053ms","start":"2026-05-29T22:02:19.359945Z","end":"2026-05-29T22:02:20.10969Z","steps":["trace[168726393] 'process raft request' (duration: 397.79863ms)","trace[168726393] 'compare' (duration: 350.775504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:20.10992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:19.359926Z","time spent":"749.886557ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:20.109965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.113285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-29T22:02:20.109403Z","caller":"traceutil/trace.go:171","msg":"trace[1951904991] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5166; }","duration":"287.530215ms","start":"2026-05-29T22:02:19.821841Z","end":"2026-05-29T22:02:20.109371Z","steps":["trace[1951904991] 'read index received' (duration: 59.191µs)","trace[1951904991] 'applied index is now lower than readState.Index' (duration: 287.468874ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:20.110039Z","caller":"traceutil/trace.go:171","msg":"trace[1994464503] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4976; }","duration":"288.229098ms","start":"2026-05-29T22:02:19.821785Z","end":"2026-05-29T22:02:20.110014Z","steps":["trace[1994464503] 'agreement among raft nodes before linearized reading' (duration: 288.025854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:20.383114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.333655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-64f4cd4cdd-wcghk.18b4285df33f319f\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-29T22:02:20.38322Z","caller":"traceutil/trace.go:171","msg":"trace[371120018] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-64f4cd4cdd-wcghk.18b4285df33f319f; range_end:; response_count:1; response_revision:4978; }","duration":"170.491687ms","start":"2026-05-29T22:02:20.212712Z","end":"2026-05-29T22:02:20.383204Z","steps":["trace[371120018] 'range keys from in-memory index tree' (duration: 170.187352ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:21.423382Z","caller":"traceutil/trace.go:171","msg":"trace[1174304230] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"116.786558ms","start":"2026-05-29T22:02:21.306552Z","end":"2026-05-29T22:02:21.423339Z","steps":["trace[1174304230] 'process raft request' (duration: 30.26144ms)","trace[1174304230] 'compare' (duration: 86.277134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:22.044479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.382943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-349fd5a1-d87e-4c60-a52e-34eb84142184\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-05-29T22:02:22.044572Z","caller":"traceutil/trace.go:171","msg":"trace[692589170] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-349fd5a1-d87e-4c60-a52e-34eb84142184; range_end:; response_count:1; response_revision:5002; }","duration":"211.515985ms","start":"2026-05-29T22:02:21.833034Z","end":"2026-05-29T22:02:22.04455Z","steps":["trace[692589170] 'range keys from in-memory index tree' (duration: 211.170499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:22.044483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.692544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:22.044812Z","caller":"traceutil/trace.go:171","msg":"trace[157256891] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5002; }","duration":"105.091341ms","start":"2026-05-29T22:02:21.939675Z","end":"2026-05-29T22:02:22.044766Z","steps":["trace[157256891] 'count revisions from in-memory index tree' (duration: 104.599533ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:23.263156Z","caller":"traceutil/trace.go:171","msg":"trace[391907554] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"126.037311ms","start":"2026-05-29T22:02:23.137079Z","end":"2026-05-29T22:02:23.263117Z","steps":["trace[391907554] 'process raft request' (duration: 125.819347ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:23.275377Z","caller":"traceutil/trace.go:171","msg":"trace[1395872823] linearizableReadLoop","detail":"{readStateIndex:5201; appliedIndex:5198; }","duration":"119.705085ms","start":"2026-05-29T22:02:23.155648Z","end":"2026-05-29T22:02:23.275353Z","steps":["trace[1395872823] 'read index received' (duration: 107.322965ms)","trace[1395872823] 'applied index is now lower than readState.Index' (duration: 12.380589ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:23.275492Z","caller":"traceutil/trace.go:171","msg":"trace[20895107] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"135.45177ms","start":"2026-05-29T22:02:23.139994Z","end":"2026-05-29T22:02:23.275446Z","steps":["trace[20895107] 'process raft request' (duration: 135.297427ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:23.275535Z","caller":"traceutil/trace.go:171","msg":"trace[1075063111] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"137.888381ms","start":"2026-05-29T22:02:23.1376Z","end":"2026-05-29T22:02:23.275489Z","steps":["trace[1075063111] 'process raft request' (duration: 137.557054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:23.275759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.186151ms","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-05-29T22:02:23.275796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.117941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-349fd5a1-d87e-4c60-a52e-34eb84142184\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-05-29T22:02:23.275842Z","caller":"traceutil/trace.go:171","msg":"trace[68329591] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-349fd5a1-d87e-4c60-a52e-34eb84142184; range_end:; response_count:1; response_revision:5009; }","duration":"120.220052ms","start":"2026-05-29T22:02:23.15561Z","end":"2026-05-29T22:02:23.27583Z","steps":["trace[68329591] 'agreement among raft nodes before linearized reading' (duration: 119.872357ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:23.275863Z","caller":"traceutil/trace.go:171","msg":"trace[175630242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5009; }","duration":"108.328543ms","start":"2026-05-29T22:02:23.167496Z","end":"2026-05-29T22:02:23.275825Z","steps":["trace[175630242] 'agreement among raft nodes before linearized reading' (duration: 108.053559ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:28.872888Z","caller":"traceutil/trace.go:171","msg":"trace[1611055016] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"103.776327ms","start":"2026-05-29T22:02:28.768998Z","end":"2026-05-29T22:02:28.872774Z","steps":["trace[1611055016] 'process raft request' (duration: 98.870004ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:31.169726Z","caller":"traceutil/trace.go:171","msg":"trace[777572788] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5315; }","duration":"283.340623ms","start":"2026-05-29T22:02:30.886344Z","end":"2026-05-29T22:02:31.169684Z","steps":["trace[777572788] 'read index received' (duration: 283.025866ms)","trace[777572788] 'applied index is now lower than readState.Index' (duration: 313.697µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:31.169919Z","caller":"traceutil/trace.go:171","msg":"trace[126014012] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"289.570406ms","start":"2026-05-29T22:02:30.88033Z","end":"2026-05-29T22:02:31.1699Z","steps":["trace[126014012] 'process raft request' (duration: 289.047137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:31.170311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.915912ms","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-05-29T22:02:31.170449Z","caller":"traceutil/trace.go:171","msg":"trace[1292896889] 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:5118; }","duration":"284.083445ms","start":"2026-05-29T22:02:30.886329Z","end":"2026-05-29T22:02:31.170412Z","steps":["trace[1292896889] 'agreement among raft nodes before linearized reading' (duration: 283.664148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:36.445933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291823442826615,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:36.947038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291823442826615,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T22:02:37.054848Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.140299816s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T22:02:37.055386Z","caller":"traceutil/trace.go:171","msg":"trace[2042237155] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5356; }","duration":"1.110294077s","start":"2026-05-29T22:02:35.945054Z","end":"2026-05-29T22:02:37.055349Z","steps":["trace[2042237155] 'read index received' (duration: 1.109975811s)","trace[2042237155] 'applied index is now lower than readState.Index' (duration: 316.856µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:37.055937Z","caller":"traceutil/trace.go:171","msg":"trace[1678100736] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"1.141493395s","start":"2026-05-29T22:02:35.914423Z","end":"2026-05-29T22:02:37.055916Z","steps":["trace[1678100736] 'process raft request' (duration: 1.140714951s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:37.056093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:35.914407Z","time spent":"1.141576857s","remote":"127.0.0.1:48456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T22:02:37.056419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111343103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-05-29T22:02:37.056516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.566336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:37.056714Z","caller":"traceutil/trace.go:171","msg":"trace[1095735323] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5158; }","duration":"119.857791ms","start":"2026-05-29T22:02:36.936791Z","end":"2026-05-29T22:02:37.056649Z","steps":["trace[1095735323] 'agreement among raft nodes before linearized reading' (duration: 119.468925ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:37.057156Z","caller":"traceutil/trace.go:171","msg":"trace[2001324625] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5158; }","duration":"1.111498726s","start":"2026-05-29T22:02:35.944975Z","end":"2026-05-29T22:02:37.056474Z","steps":["trace[2001324625] 'agreement among raft nodes before linearized reading' (duration: 1.11113589s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:37.057225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:35.944942Z","time spent":"1.112264819s","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-29T22:02:37.056651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.587712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-05-29T22:02:37.057429Z","caller":"traceutil/trace.go:171","msg":"trace[1054472429] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5158; }","duration":"564.375845ms","start":"2026-05-29T22:02:36.492981Z","end":"2026-05-29T22:02:37.057357Z","steps":["trace[1054472429] 'agreement among raft nodes before linearized reading' (duration: 563.227837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:37.057577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T22:02:36.492937Z","time spent":"564.570258ms","remote":"127.0.0.1:44502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3265,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-29T22:02:37.265393Z","caller":"traceutil/trace.go:171","msg":"trace[1804665041] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"196.200468ms","start":"2026-05-29T22:02:37.069152Z","end":"2026-05-29T22:02:37.265352Z","steps":["trace[1804665041] 'process raft request' (duration: 195.841531ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:37.265522Z","caller":"traceutil/trace.go:171","msg":"trace[1230443928] linearizableReadLoop","detail":"{readStateIndex:5362; appliedIndex:5357; }","duration":"168.433017ms","start":"2026-05-29T22:02:37.097048Z","end":"2026-05-29T22:02:37.265481Z","steps":["trace[1230443928] 'read index received' (duration: 7.8352ms)","trace[1230443928] 'applied index is now lower than readState.Index' (duration: 160.592057ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:37.265691Z","caller":"traceutil/trace.go:171","msg":"trace[138290863] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"195.928492ms","start":"2026-05-29T22:02:37.069747Z","end":"2026-05-29T22:02:37.265675Z","steps":["trace[138290863] 'process raft request' (duration: 195.570166ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:37.265893Z","caller":"traceutil/trace.go:171","msg":"trace[2121025453] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"196.599293ms","start":"2026-05-29T22:02:37.069282Z","end":"2026-05-29T22:02:37.265881Z","steps":["trace[2121025453] 'process raft request' (duration: 195.967822ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:37.265933Z","caller":"traceutil/trace.go:171","msg":"trace[1772758965] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"193.822828ms","start":"2026-05-29T22:02:37.072078Z","end":"2026-05-29T22:02:37.265901Z","steps":["trace[1772758965] 'process raft request' (duration: 193.306689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:37.266097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.028826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:02:37.266151Z","caller":"traceutil/trace.go:171","msg":"trace[383944154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5163; }","duration":"169.161408ms","start":"2026-05-29T22:02:37.096977Z","end":"2026-05-29T22:02:37.266138Z","steps":["trace[383944154] 'agreement among raft nodes before linearized reading' (duration: 169.047287ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:37.293606Z","caller":"traceutil/trace.go:171","msg":"trace[1745160175] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"103.179513ms","start":"2026-05-29T22:02:37.190402Z","end":"2026-05-29T22:02:37.293581Z","steps":["trace[1745160175] 'process raft request' (duration: 102.836737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:37.293645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.750022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T22:02:37.293759Z","caller":"traceutil/trace.go:171","msg":"trace[584410838] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5164; }","duration":"151.020337ms","start":"2026-05-29T22:02:37.142709Z","end":"2026-05-29T22:02:37.293729Z","steps":["trace[584410838] 'agreement among raft nodes before linearized reading' (duration: 150.797073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:37.70679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.610524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T22:02:37.706899Z","caller":"traceutil/trace.go:171","msg":"trace[469520512] linearizableReadLoop","detail":"{readStateIndex:5367; appliedIndex:5366; }","duration":"173.185485ms","start":"2026-05-29T22:02:37.533694Z","end":"2026-05-29T22:02:37.706879Z","steps":["trace[469520512] 'read index received' (duration: 38.308766ms)","trace[469520512] 'applied index is now lower than readState.Index' (duration: 134.875229ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:02:37.707403Z","caller":"traceutil/trace.go:171","msg":"trace[880183119] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"178.549583ms","start":"2026-05-29T22:02:37.528809Z","end":"2026-05-29T22:02:37.707359Z","steps":["trace[880183119] 'process raft request' (duration: 43.260188ms)","trace[880183119] 'compare' (duration: 134.470682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:02:37.707625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.175178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T22:02:37.707713Z","caller":"traceutil/trace.go:171","msg":"trace[791399620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5168; }","duration":"160.306851ms","start":"2026-05-29T22:02:37.547385Z","end":"2026-05-29T22:02:37.707692Z","steps":["trace[791399620] 'agreement among raft nodes before linearized reading' (duration: 160.098497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:02:37.707706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.017008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-29T22:02:37.707859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.312193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-29T22:02:37.707872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.380495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-29T22:02:37.707986Z","caller":"traceutil/trace.go:171","msg":"trace[2063226142] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5168; }","duration":"159.477737ms","start":"2026-05-29T22:02:37.548474Z","end":"2026-05-29T22:02:37.707952Z","steps":["trace[2063226142] 'agreement among raft nodes before linearized reading' (duration: 159.050339ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:37.707997Z","caller":"traceutil/trace.go:171","msg":"trace[83541670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5168; }","duration":"159.556948ms","start":"2026-05-29T22:02:37.548408Z","end":"2026-05-29T22:02:37.707965Z","steps":["trace[83541670] 'agreement among raft nodes before linearized reading' (duration: 159.192971ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:37.708394Z","caller":"traceutil/trace.go:171","msg":"trace[106462729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5168; }","duration":"174.292203ms","start":"2026-05-29T22:02:37.533623Z","end":"2026-05-29T22:02:37.707915Z","steps":["trace[106462729] 'agreement among raft nodes before linearized reading' (duration: 173.766894ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:02:56.394622Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2800} {"level":"info","ts":"2026-05-29T22:02:56.510413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2800,"took":"114.296846ms","hash":2155068872,"current-db-size-bytes":52002816,"current-db-size":"52 MB","current-db-size-in-use-bytes":27144192,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-29T22:02:56.510487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2155068872,"revision":2800,"compact-revision":-1} {"level":"info","ts":"2026-05-29T22:03:07.846357Z","caller":"traceutil/trace.go:171","msg":"trace[906778604] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"125.172897ms","start":"2026-05-29T22:03:07.721159Z","end":"2026-05-29T22:03:07.846331Z","steps":["trace[906778604] 'process raft request' (duration: 125.035764ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:03:13.435818Z","caller":"traceutil/trace.go:171","msg":"trace[1513861507] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5679; }","duration":"195.833017ms","start":"2026-05-29T22:03:13.239965Z","end":"2026-05-29T22:03:13.435798Z","steps":["trace[1513861507] 'read index received' (duration: 195.598293ms)","trace[1513861507] 'applied index is now lower than readState.Index' (duration: 233.464µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T22:03:13.435877Z","caller":"traceutil/trace.go:171","msg":"trace[2110487126] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"289.859422ms","start":"2026-05-29T22:03:13.145988Z","end":"2026-05-29T22:03:13.435847Z","steps":["trace[2110487126] 'process raft request' (duration: 289.667169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.435959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.972679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:13.435988Z","caller":"traceutil/trace.go:171","msg":"trace[623855255] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5471; }","duration":"196.0561ms","start":"2026-05-29T22:03:13.239921Z","end":"2026-05-29T22:03:13.435977Z","steps":["trace[623855255] 'agreement among raft nodes before linearized reading' (duration: 195.993599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.436172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.162219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-05-29T22:03:13.436219Z","caller":"traceutil/trace.go:171","msg":"trace[1947590898] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5471; }","duration":"189.27397ms","start":"2026-05-29T22:03:13.246933Z","end":"2026-05-29T22:03:13.436207Z","steps":["trace[1947590898] 'agreement among raft nodes before linearized reading' (duration: 189.111247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:13.436232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.279113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:03:13.436251Z","caller":"traceutil/trace.go:171","msg":"trace[231760913] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5471; }","duration":"196.343525ms","start":"2026-05-29T22:03:13.239901Z","end":"2026-05-29T22:03:13.436245Z","steps":["trace[231760913] 'agreement among raft nodes before linearized reading' (duration: 196.312064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T22:03:24.822064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.820645ms","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-05-29T22:03:24.822165Z","caller":"traceutil/trace.go:171","msg":"trace[944825366] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5538; }","duration":"242.988218ms","start":"2026-05-29T22:03:24.579149Z","end":"2026-05-29T22:03:24.822137Z","steps":["trace[944825366] 'range keys from in-memory index tree' (duration: 242.627732ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:06:24.79916Z","caller":"traceutil/trace.go:171","msg":"trace[1161730178] transaction","detail":"{read_only:false; response_revision:6789; number_of_response:1; }","duration":"144.089388ms","start":"2026-05-29T22:06:24.655039Z","end":"2026-05-29T22:06:24.799128Z","steps":["trace[1161730178] 'process raft request' (duration: 143.850505ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:06:39.212602Z","caller":"traceutil/trace.go:171","msg":"trace[1473720513] linearizableReadLoop","detail":"{readStateIndex:7174; appliedIndex:7173; }","duration":"118.090714ms","start":"2026-05-29T22:06:39.094482Z","end":"2026-05-29T22:06:39.212573Z","steps":["trace[1473720513] 'read index received' (duration: 117.878877ms)","trace[1473720513] 'applied index is now lower than readState.Index' (duration: 210.997µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T22:06:39.212811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.313342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T22:06:39.212844Z","caller":"traceutil/trace.go:171","msg":"trace[753979580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6914; }","duration":"118.399565ms","start":"2026-05-29T22:06:39.094437Z","end":"2026-05-29T22:06:39.212836Z","steps":["trace[753979580] 'agreement among raft nodes before linearized reading' (duration: 118.2527ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:06:39.213131Z","caller":"traceutil/trace.go:171","msg":"trace[271793998] transaction","detail":"{read_only:false; response_revision:6914; number_of_response:1; }","duration":"147.221638ms","start":"2026-05-29T22:06:39.065901Z","end":"2026-05-29T22:06:39.213123Z","steps":["trace[271793998] 'process raft request' (duration: 146.530177ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:06:44.747861Z","caller":"traceutil/trace.go:171","msg":"trace[1859520128] transaction","detail":"{read_only:false; response_revision:6942; number_of_response:1; }","duration":"128.513389ms","start":"2026-05-29T22:06:44.619326Z","end":"2026-05-29T22:06:44.747839Z","steps":["trace[1859520128] 'process raft request' (duration: 128.388055ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:06:44.779642Z","caller":"traceutil/trace.go:171","msg":"trace[68011843] transaction","detail":"{read_only:false; response_revision:6943; number_of_response:1; }","duration":"134.550682ms","start":"2026-05-29T22:06:44.645066Z","end":"2026-05-29T22:06:44.779616Z","steps":["trace[68011843] 'process raft request' (duration: 134.437569ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T22:07:56.401784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5292} {"level":"info","ts":"2026-05-29T22:07:56.49148Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5292,"took":"88.221841ms","hash":3145647728,"current-db-size-bytes":52002816,"current-db-size":"52 MB","current-db-size-in-use-bytes":18411520,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-29T22:07:56.49156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3145647728,"revision":5292,"compact-revision":2800}