{"level":"warn","ts":"2026-08-24T15:26:29.457105Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:26:29.457223Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.61:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.61:2380","--initial-cluster=instance=https://199.19.213.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.61:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T15:26:29.457305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:26:29.457322Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-08-24T15:26:29.457352Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:26:29.458414Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"]} {"level":"info","ts":"2026-08-24T15:26:29.458515Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.61:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T15:26:29.460913Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.085579ms"} {"level":"info","ts":"2026-08-24T15:26:29.471121Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1484a2210d3c2b8","cluster-id":"27fc2dec3fac595c"} {"level":"info","ts":"2026-08-24T15:26:29.471258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:26:29.471296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 0"} {"level":"info","ts":"2026-08-24T15:26:29.471311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1484a2210d3c2b8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:26:29.47133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 1"} {"level":"info","ts":"2026-08-24T15:26:29.47139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"warn","ts":"2026-08-24T15:26:29.476012Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:26:29.477455Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:26:29.478712Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T15:26:29.480973Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1484a2210d3c2b8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:26:29.482317Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1484a2210d3c2b8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:26:29.482693Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:26:29.482809Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:26:29.482833Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:26:29.4858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"info","ts":"2026-08-24T15:26:29.486231Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","added-peer-id":"d1484a2210d3c2b8","added-peer-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-08-24T15:26:29.48804Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:26:29.488184Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-08-24T15:26:29.488294Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-08-24T15:26:29.488439Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1484a2210d3c2b8","initial-advertise-peer-urls":["https://199.19.213.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:26:29.488506Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:26:29.672511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:26:29.672602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:26:29.672637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgPreVoteResp from d1484a2210d3c2b8 at term 1"} {"level":"info","ts":"2026-08-24T15:26:29.672659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:26:29.672667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgVoteResp from d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-08-24T15:26:29.67268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became leader at term 2"} {"level":"info","ts":"2026-08-24T15:26:29.672694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1484a2210d3c2b8 elected leader d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-08-24T15:26:29.673749Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:26:29.674587Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:26:29.674572Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1484a2210d3c2b8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.61:2379]}","request-path":"/0/members/d1484a2210d3c2b8/attributes","cluster-id":"27fc2dec3fac595c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:26:29.674655Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:26:29.674873Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:26:29.675003Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:26:29.675295Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:26:29.675502Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:26:29.675572Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:26:29.678048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:26:29.678048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.61:2379"} {"level":"warn","ts":"2026-08-24T15:26:53.811919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.12873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:26:53.812009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.793205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:26:53.81204Z","caller":"traceutil/trace.go:171","msg":"trace[1163177748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"169.364986ms","start":"2026-08-24T15:26:53.64265Z","end":"2026-08-24T15:26:53.812015Z","steps":["trace[1163177748] 'range keys from in-memory index tree' (duration: 168.988217ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:26:53.812098Z","caller":"traceutil/trace.go:171","msg":"trace[1459145786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"118.928839ms","start":"2026-08-24T15:26:53.693149Z","end":"2026-08-24T15:26:53.812078Z","steps":["trace[1459145786] 'range keys from in-memory index tree' (duration: 118.569971ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.605914Z","caller":"traceutil/trace.go:171","msg":"trace[1205108362] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"109.060831ms","start":"2026-08-24T15:27:07.49683Z","end":"2026-08-24T15:27:07.60589Z","steps":["trace[1205108362] 'process raft request' (duration: 108.907987ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.621713Z","caller":"traceutil/trace.go:171","msg":"trace[771252730] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"117.449623ms","start":"2026-08-24T15:27:07.504248Z","end":"2026-08-24T15:27:07.621698Z","steps":["trace[771252730] 'process raft request' (duration: 117.34554ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.621886Z","caller":"traceutil/trace.go:171","msg":"trace[2100984353] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"109.076911ms","start":"2026-08-24T15:27:07.512758Z","end":"2026-08-24T15:27:07.621835Z","steps":["trace[2100984353] 'process raft request' (duration: 108.869326ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.62215Z","caller":"traceutil/trace.go:171","msg":"trace[1390655626] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"107.236408ms","start":"2026-08-24T15:27:07.514885Z","end":"2026-08-24T15:27:07.622121Z","steps":["trace[1390655626] 'process raft request' (duration: 107.019992ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.622175Z","caller":"traceutil/trace.go:171","msg":"trace[2004713264] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"102.778749ms","start":"2026-08-24T15:27:07.519377Z","end":"2026-08-24T15:27:07.622156Z","steps":["trace[2004713264] 'process raft request' (duration: 102.620766ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.622205Z","caller":"traceutil/trace.go:171","msg":"trace[896130483] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"109.339358ms","start":"2026-08-24T15:27:07.512853Z","end":"2026-08-24T15:27:07.622192Z","steps":["trace[896130483] 'process raft request' (duration: 108.805455ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.622229Z","caller":"traceutil/trace.go:171","msg":"trace[167675306] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"107.402692ms","start":"2026-08-24T15:27:07.514818Z","end":"2026-08-24T15:27:07.622221Z","steps":["trace[167675306] 'process raft request' (duration: 107.034162ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.622263Z","caller":"traceutil/trace.go:171","msg":"trace[30504649] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"119.853111ms","start":"2026-08-24T15:27:07.502378Z","end":"2026-08-24T15:27:07.622231Z","steps":["trace[30504649] 'process raft request' (duration: 119.161224ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:07.622291Z","caller":"traceutil/trace.go:171","msg":"trace[318095656] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"106.158871ms","start":"2026-08-24T15:27:07.516125Z","end":"2026-08-24T15:27:07.622284Z","steps":["trace[318095656] 'process raft request' (duration: 105.832754ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:14.161016Z","caller":"traceutil/trace.go:171","msg":"trace[1161133455] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"254.51058ms","start":"2026-08-24T15:27:13.906479Z","end":"2026-08-24T15:27:14.16099Z","steps":["trace[1161133455] 'read index received' (duration: 254.380527ms)","trace[1161133455] 'applied index is now lower than readState.Index' (duration: 129.163µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:27:14.161116Z","caller":"traceutil/trace.go:171","msg":"trace[1237128918] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"332.315558ms","start":"2026-08-24T15:27:13.82874Z","end":"2026-08-24T15:27:14.161055Z","steps":["trace[1237128918] 'process raft request' (duration: 332.112063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:27:14.161248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.743925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:27:14.161288Z","caller":"traceutil/trace.go:171","msg":"trace[71322344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"254.838088ms","start":"2026-08-24T15:27:13.906438Z","end":"2026-08-24T15:27:14.161276Z","steps":["trace[71322344] 'agreement among raft nodes before linearized reading' (duration: 254.665394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:27:14.161964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:27:13.828711Z","time spent":"332.522432ms","remote":"127.0.0.1:58838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:27:14.314475Z","caller":"traceutil/trace.go:171","msg":"trace[98923079] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"144.014995ms","start":"2026-08-24T15:27:14.170417Z","end":"2026-08-24T15:27:14.314432Z","steps":["trace[98923079] 'read index received' (duration: 58.458411ms)","trace[98923079] 'applied index is now lower than readState.Index' (duration: 85.554904ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:27:14.314584Z","caller":"traceutil/trace.go:171","msg":"trace[1179310461] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"148.39154ms","start":"2026-08-24T15:27:14.166162Z","end":"2026-08-24T15:27:14.314554Z","steps":["trace[1179310461] 'process raft request' (duration: 62.683972ms)","trace[1179310461] 'compare' (duration: 85.377879ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:27:14.314672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.22817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:27:14.314728Z","caller":"traceutil/trace.go:171","msg":"trace[547774250] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:641; }","duration":"144.353423ms","start":"2026-08-24T15:27:14.170355Z","end":"2026-08-24T15:27:14.314708Z","steps":["trace[547774250] 'agreement among raft nodes before linearized reading' (duration: 144.23954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:27:14.833091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.474242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:27:14.833176Z","caller":"traceutil/trace.go:171","msg":"trace[1214995754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"187.645187ms","start":"2026-08-24T15:27:14.645513Z","end":"2026-08-24T15:27:14.83316Z","steps":["trace[1214995754] 'range keys from in-memory index tree' (duration: 187.244038ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:14.859368Z","caller":"traceutil/trace.go:171","msg":"trace[318017918] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"100.012293ms","start":"2026-08-24T15:27:14.759316Z","end":"2026-08-24T15:27:14.859328Z","steps":["trace[318017918] 'process raft request' (duration: 99.497081ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:27:30.666612Z","caller":"traceutil/trace.go:171","msg":"trace[683203248] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"247.177384ms","start":"2026-08-24T15:27:30.419407Z","end":"2026-08-24T15:27:30.666585Z","steps":["trace[683203248] 'process raft request' (duration: 246.990459ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:28:42.100467Z","caller":"traceutil/trace.go:171","msg":"trace[541257667] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"240.305998ms","start":"2026-08-24T15:28:41.860147Z","end":"2026-08-24T15:28:42.100453Z","steps":["trace[541257667] 'read index received' (duration: 240.206965ms)","trace[541257667] 'applied index is now lower than readState.Index' (duration: 98.353µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:28:42.100521Z","caller":"traceutil/trace.go:171","msg":"trace[1203878313] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"240.698347ms","start":"2026-08-24T15:28:41.859796Z","end":"2026-08-24T15:28:42.100495Z","steps":["trace[1203878313] 'process raft request' (duration: 240.541073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:28:42.100718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.552863ms","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-08-24T15:28:42.100789Z","caller":"traceutil/trace.go:171","msg":"trace[465122263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"240.680846ms","start":"2026-08-24T15:28:41.860094Z","end":"2026-08-24T15:28:42.100775Z","steps":["trace[465122263] 'agreement among raft nodes before linearized reading' (duration: 240.460531ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:28:42.186787Z","caller":"traceutil/trace.go:171","msg":"trace[1592551216] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"117.874834ms","start":"2026-08-24T15:28:42.06888Z","end":"2026-08-24T15:28:42.186754Z","steps":["trace[1592551216] 'process raft request' (duration: 89.09783ms)","trace[1592551216] 'compare' (duration: 28.60665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:28:42.186882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.020594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:28:42.186968Z","caller":"traceutil/trace.go:171","msg":"trace[1485226597] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1860; }","duration":"253.141176ms","start":"2026-08-24T15:28:41.933805Z","end":"2026-08-24T15:28:42.186946Z","steps":["trace[1485226597] 'agreement among raft nodes before linearized reading' (duration: 252.985113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:28:42.18698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.453827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-107a2047-b426-44a0-acdc-7a73b5f4a0fc\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-08-24T15:28:42.187046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.012626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:28:42.187126Z","caller":"traceutil/trace.go:171","msg":"trace[528025324] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-107a2047-b426-44a0-acdc-7a73b5f4a0fc; range_end:; response_count:1; response_revision:1860; }","duration":"176.615001ms","start":"2026-08-24T15:28:42.010477Z","end":"2026-08-24T15:28:42.187092Z","steps":["trace[528025324] 'agreement among raft nodes before linearized reading' (duration: 176.306474ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:28:42.187162Z","caller":"traceutil/trace.go:171","msg":"trace[1705952144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"113.155529ms","start":"2026-08-24T15:28:42.073992Z","end":"2026-08-24T15:28:42.187147Z","steps":["trace[1705952144] 'agreement among raft nodes before linearized reading' (duration: 112.816761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:28:42.484498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.505453ms","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-08-24T15:28:42.48457Z","caller":"traceutil/trace.go:171","msg":"trace[2021618922] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1863; }","duration":"182.623616ms","start":"2026-08-24T15:28:42.301933Z","end":"2026-08-24T15:28:42.484557Z","steps":["trace[2021618922] 'range keys from in-memory index tree' (duration: 182.368549ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:28:54.389527Z","caller":"traceutil/trace.go:171","msg":"trace[937388954] linearizableReadLoop","detail":"{readStateIndex:2035; appliedIndex:2034; }","duration":"139.791632ms","start":"2026-08-24T15:28:54.249669Z","end":"2026-08-24T15:28:54.389461Z","steps":["trace[937388954] 'read index received' (duration: 139.646068ms)","trace[937388954] 'applied index is now lower than readState.Index' (duration: 144.014µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:28:54.389569Z","caller":"traceutil/trace.go:171","msg":"trace[1015149019] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"160.087012ms","start":"2026-08-24T15:28:54.229448Z","end":"2026-08-24T15:28:54.389535Z","steps":["trace[1015149019] 'process raft request' (duration: 159.558779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:28:54.389924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.206973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:28:54.39006Z","caller":"traceutil/trace.go:171","msg":"trace[756467383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1961; }","duration":"140.395796ms","start":"2026-08-24T15:28:54.249638Z","end":"2026-08-24T15:28:54.390034Z","steps":["trace[756467383] 'agreement among raft nodes before linearized reading' (duration: 140.000737ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:28:54.391114Z","caller":"traceutil/trace.go:171","msg":"trace[301266851] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"128.463339ms","start":"2026-08-24T15:28:54.262609Z","end":"2026-08-24T15:28:54.391073Z","steps":["trace[301266851] 'process raft request' (duration: 128.244974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:28:54.391233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.930933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T15:28:54.391321Z","caller":"traceutil/trace.go:171","msg":"trace[427024372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1962; }","duration":"110.081527ms","start":"2026-08-24T15:28:54.281221Z","end":"2026-08-24T15:28:54.391303Z","steps":["trace[427024372] 'agreement among raft nodes before linearized reading' (duration: 109.80043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:28:54.391548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.855368ms","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-08-24T15:28:54.391613Z","caller":"traceutil/trace.go:171","msg":"trace[969740074] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1962; }","duration":"106.939329ms","start":"2026-08-24T15:28:54.284655Z","end":"2026-08-24T15:28:54.391594Z","steps":["trace[969740074] 'agreement among raft nodes before linearized reading' (duration: 106.823637ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:29:32.499848Z","caller":"traceutil/trace.go:171","msg":"trace[476056155] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"105.627649ms","start":"2026-08-24T15:29:32.394194Z","end":"2026-08-24T15:29:32.499821Z","steps":["trace[476056155] 'process raft request' (duration: 105.390613ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:29:32.672785Z","caller":"traceutil/trace.go:171","msg":"trace[2015156892] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"125.212731ms","start":"2026-08-24T15:29:32.547544Z","end":"2026-08-24T15:29:32.672757Z","steps":["trace[2015156892] 'process raft request' (duration: 90.274248ms)","trace[2015156892] 'compare' (duration: 34.767459ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:29:32.691577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.288098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T15:29:32.691706Z","caller":"traceutil/trace.go:171","msg":"trace[2104481454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2182; }","duration":"102.463782ms","start":"2026-08-24T15:29:32.589214Z","end":"2026-08-24T15:29:32.691678Z","steps":["trace[2104481454] 'agreement among raft nodes before linearized reading' (duration: 102.105623ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:29:32.859846Z","caller":"traceutil/trace.go:171","msg":"trace[1344034121] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2263; }","duration":"167.513241ms","start":"2026-08-24T15:29:32.692307Z","end":"2026-08-24T15:29:32.85982Z","steps":["trace[1344034121] 'read index received' (duration: 166.852654ms)","trace[1344034121] 'applied index is now lower than readState.Index' (duration: 658.997µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:29:32.859915Z","caller":"traceutil/trace.go:171","msg":"trace[123705018] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"182.073003ms","start":"2026-08-24T15:29:32.67781Z","end":"2026-08-24T15:29:32.859883Z","steps":["trace[123705018] 'process raft request' (duration: 181.315554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:29:32.860072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.724206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:29:32.860213Z","caller":"traceutil/trace.go:171","msg":"trace[4338001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2183; }","duration":"167.931331ms","start":"2026-08-24T15:29:32.692252Z","end":"2026-08-24T15:29:32.860184Z","steps":["trace[4338001] 'agreement among raft nodes before linearized reading' (duration: 167.678545ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:29:32.875013Z","caller":"traceutil/trace.go:171","msg":"trace[244361745] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"178.073976ms","start":"2026-08-24T15:29:32.696924Z","end":"2026-08-24T15:29:32.874998Z","steps":["trace[244361745] 'process raft request' (duration: 177.930742ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:14.869282Z","caller":"traceutil/trace.go:171","msg":"trace[88374036] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"122.399323ms","start":"2026-08-24T15:30:14.746852Z","end":"2026-08-24T15:30:14.869252Z","steps":["trace[88374036] 'process raft request' (duration: 94.805827ms)","trace[88374036] 'compare' (duration: 27.447692ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:14.872795Z","caller":"traceutil/trace.go:171","msg":"trace[567609211] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"120.933098ms","start":"2026-08-24T15:30:14.751839Z","end":"2026-08-24T15:30:14.872773Z","steps":["trace[567609211] 'process raft request' (duration: 120.810075ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:14.872872Z","caller":"traceutil/trace.go:171","msg":"trace[109586443] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"120.429876ms","start":"2026-08-24T15:30:14.752422Z","end":"2026-08-24T15:30:14.872852Z","steps":["trace[109586443] 'process raft request' (duration: 120.295132ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:25.617341Z","caller":"traceutil/trace.go:171","msg":"trace[489603327] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"120.955417ms","start":"2026-08-24T15:30:25.496363Z","end":"2026-08-24T15:30:25.617319Z","steps":["trace[489603327] 'process raft request' (duration: 120.759832ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:59.13733Z","caller":"traceutil/trace.go:171","msg":"trace[1358536148] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"111.737006ms","start":"2026-08-24T15:30:59.025557Z","end":"2026-08-24T15:30:59.137294Z","steps":["trace[1358536148] 'process raft request' (duration: 111.47751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:59.418763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.813978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:30:59.419061Z","caller":"traceutil/trace.go:171","msg":"trace[117010029] linearizableReadLoop","detail":"{readStateIndex:2952; appliedIndex:2950; }","duration":"193.356416ms","start":"2026-08-24T15:30:59.225685Z","end":"2026-08-24T15:30:59.419041Z","steps":["trace[117010029] 'read index received' (duration: 46.81273ms)","trace[117010029] 'applied index is now lower than readState.Index' (duration: 146.542696ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:59.419148Z","caller":"traceutil/trace.go:171","msg":"trace[957910239] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"268.340504ms","start":"2026-08-24T15:30:59.150784Z","end":"2026-08-24T15:30:59.419125Z","steps":["trace[957910239] 'process raft request' (duration: 121.693375ms)","trace[957910239] 'compare' (duration: 145.687955ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:30:59.419206Z","caller":"traceutil/trace.go:171","msg":"trace[69151732] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"259.738506ms","start":"2026-08-24T15:30:59.159415Z","end":"2026-08-24T15:30:59.419154Z","steps":["trace[69151732] 'process raft request' (duration: 259.549281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:59.419343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.654342ms","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-08-24T15:30:59.419426Z","caller":"traceutil/trace.go:171","msg":"trace[185377934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2846; }","duration":"193.792095ms","start":"2026-08-24T15:30:59.225616Z","end":"2026-08-24T15:30:59.419408Z","steps":["trace[185377934] 'agreement among raft nodes before linearized reading' (duration: 193.55247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:30:59.419524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.955887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-24T15:30:59.419623Z","caller":"traceutil/trace.go:171","msg":"trace[1520396669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2846; }","duration":"128.08516ms","start":"2026-08-24T15:30:59.291522Z","end":"2026-08-24T15:30:59.419608Z","steps":["trace[1520396669] 'agreement among raft nodes before linearized reading' (duration: 127.817654ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:59.585617Z","caller":"traceutil/trace.go:171","msg":"trace[1396199776] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"161.68165ms","start":"2026-08-24T15:30:59.423911Z","end":"2026-08-24T15:30:59.585592Z","steps":["trace[1396199776] 'process raft request' (duration: 161.633119ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:30:59.585688Z","caller":"traceutil/trace.go:171","msg":"trace[1636310019] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"162.785977ms","start":"2026-08-24T15:30:59.422871Z","end":"2026-08-24T15:30:59.585657Z","steps":["trace[1636310019] 'process raft request' (duration: 107.78445ms)","trace[1636310019] 'compare' (duration: 54.744411ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:33:30.320631Z","caller":"traceutil/trace.go:171","msg":"trace[494957682] transaction","detail":"{read_only:false; response_revision:3843; number_of_response:1; }","duration":"170.686567ms","start":"2026-08-24T15:33:30.149927Z","end":"2026-08-24T15:33:30.320614Z","steps":["trace[494957682] 'process raft request' (duration: 170.547694ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:34:54.962175Z","caller":"traceutil/trace.go:171","msg":"trace[870076939] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"111.985696ms","start":"2026-08-24T15:34:54.850165Z","end":"2026-08-24T15:34:54.96215Z","steps":["trace[870076939] 'process raft request' (duration: 58.280946ms)","trace[870076939] 'compare' (duration: 53.540906ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:35:08.687516Z","caller":"traceutil/trace.go:171","msg":"trace[296493155] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"115.115266ms","start":"2026-08-24T15:35:08.57237Z","end":"2026-08-24T15:35:08.687486Z","steps":["trace[296493155] 'process raft request' (duration: 114.845489ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:09.309938Z","caller":"traceutil/trace.go:171","msg":"trace[1394588554] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5478; }","duration":"195.926741ms","start":"2026-08-24T15:35:09.113974Z","end":"2026-08-24T15:35:09.309901Z","steps":["trace[1394588554] 'read index received' (duration: 195.733017ms)","trace[1394588554] 'applied index is now lower than readState.Index' (duration: 191.804µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:35:09.310374Z","caller":"traceutil/trace.go:171","msg":"trace[308642498] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"228.438245ms","start":"2026-08-24T15:35:09.081891Z","end":"2026-08-24T15:35:09.310329Z","steps":["trace[308642498] 'process raft request' (duration: 227.795448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:35:09.310856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.872457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-bdsmg\" ","response":"range_response_count:1 size:7665"} {"level":"info","ts":"2026-08-24T15:35:09.310899Z","caller":"traceutil/trace.go:171","msg":"trace[1581405222] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-bdsmg; range_end:; response_count:1; response_revision:5282; }","duration":"196.960019ms","start":"2026-08-24T15:35:09.113927Z","end":"2026-08-24T15:35:09.310887Z","steps":["trace[1581405222] 'agreement among raft nodes before linearized reading' (duration: 196.124417ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:16.926883Z","caller":"traceutil/trace.go:171","msg":"trace[1812185872] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"148.505191ms","start":"2026-08-24T15:35:16.778339Z","end":"2026-08-24T15:35:16.926844Z","steps":["trace[1812185872] 'process raft request' (duration: 129.539572ms)","trace[1812185872] 'compare' (duration: 18.828886ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:35:17.421513Z","caller":"traceutil/trace.go:171","msg":"trace[1250331161] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"148.412238ms","start":"2026-08-24T15:35:17.273049Z","end":"2026-08-24T15:35:17.421461Z","steps":["trace[1250331161] 'process raft request' (duration: 103.648525ms)","trace[1250331161] 'compare' (duration: 44.542467ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:35:17.42654Z","caller":"traceutil/trace.go:171","msg":"trace[1231424092] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"153.071826ms","start":"2026-08-24T15:35:17.273438Z","end":"2026-08-24T15:35:17.42651Z","steps":["trace[1231424092] 'process raft request' (duration: 152.81429ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:17.426611Z","caller":"traceutil/trace.go:171","msg":"trace[1804025158] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"152.82179ms","start":"2026-08-24T15:35:17.27376Z","end":"2026-08-24T15:35:17.426582Z","steps":["trace[1804025158] 'process raft request' (duration: 152.641625ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:17.426667Z","caller":"traceutil/trace.go:171","msg":"trace[1941997651] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"152.527133ms","start":"2026-08-24T15:35:17.274112Z","end":"2026-08-24T15:35:17.426639Z","steps":["trace[1941997651] 'process raft request' (duration: 152.373049ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:17.426857Z","caller":"traceutil/trace.go:171","msg":"trace[16377869] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"152.349198ms","start":"2026-08-24T15:35:17.27446Z","end":"2026-08-24T15:35:17.426809Z","steps":["trace[16377869] 'process raft request' (duration: 152.076942ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:17.426956Z","caller":"traceutil/trace.go:171","msg":"trace[1009074487] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"148.09702ms","start":"2026-08-24T15:35:17.278818Z","end":"2026-08-24T15:35:17.426915Z","steps":["trace[1009074487] 'process raft request' (duration: 147.888565ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:35:17.426997Z","caller":"traceutil/trace.go:171","msg":"trace[1970083701] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"151.955458ms","start":"2026-08-24T15:35:17.275022Z","end":"2026-08-24T15:35:17.426977Z","steps":["trace[1970083701] 'process raft request' (duration: 151.554408ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:36:29.800739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3015} {"level":"info","ts":"2026-08-24T15:36:29.881621Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3015,"took":"79.726598ms","hash":4130629207,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T15:36:29.881673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4130629207,"revision":3015,"compact-revision":-1} {"level":"warn","ts":"2026-08-24T15:41:28.578324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.05994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:41:28.578549Z","caller":"traceutil/trace.go:171","msg":"trace[1130673627] transaction","detail":"{read_only:false; response_revision:8301; number_of_response:1; }","duration":"378.303848ms","start":"2026-08-24T15:41:28.200219Z","end":"2026-08-24T15:41:28.578523Z","steps":["trace[1130673627] 'process raft request' (duration: 250.939421ms)","trace[1130673627] 'compare' (duration: 126.907986ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:41:28.57866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:41:28.200193Z","time spent":"378.40622ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:41:29.806577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6118} {"level":"info","ts":"2026-08-24T15:41:29.904838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6118,"took":"96.844783ms","hash":2420533566,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":23863296,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-24T15:41:29.904935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2420533566,"revision":6118,"compact-revision":3015} {"level":"info","ts":"2026-08-24T15:41:39.780386Z","caller":"traceutil/trace.go:171","msg":"trace[1825894593] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"133.016252ms","start":"2026-08-24T15:41:39.647333Z","end":"2026-08-24T15:41:39.78035Z","steps":["trace[1825894593] 'process raft request' (duration: 132.771965ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:39.852341Z","caller":"traceutil/trace.go:171","msg":"trace[1161644629] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"148.865662ms","start":"2026-08-24T15:41:39.703444Z","end":"2026-08-24T15:41:39.85231Z","steps":["trace[1161644629] 'process raft request' (duration: 130.021559ms)","trace[1161644629] 'compare' (duration: 18.600067ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:41:39.852331Z","caller":"traceutil/trace.go:171","msg":"trace[1055960366] linearizableReadLoop","detail":"{readStateIndex:8657; appliedIndex:8655; }","duration":"112.445608ms","start":"2026-08-24T15:41:39.739836Z","end":"2026-08-24T15:41:39.852282Z","steps":["trace[1055960366] 'read index received' (duration: 40.183124ms)","trace[1055960366] 'applied index is now lower than readState.Index' (duration: 72.261084ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:41:39.852613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.756485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pkwpx\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-08-24T15:41:39.852669Z","caller":"traceutil/trace.go:171","msg":"trace[296559178] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pkwpx; range_end:; response_count:1; response_revision:8363; }","duration":"112.895418ms","start":"2026-08-24T15:41:39.739762Z","end":"2026-08-24T15:41:39.852657Z","steps":["trace[296559178] 'agreement among raft nodes before linearized reading' (duration: 112.638512ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:56.412882Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002232.wal"} {"level":"info","ts":"2026-08-24T15:42:09.541153Z","caller":"traceutil/trace.go:171","msg":"trace[1337121315] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"107.5657ms","start":"2026-08-24T15:42:09.433552Z","end":"2026-08-24T15:42:09.541117Z","steps":["trace[1337121315] 'process raft request' (duration: 107.284754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:09.730351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.82414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:42:09.730448Z","caller":"traceutil/trace.go:171","msg":"trace[436366493] linearizableReadLoop","detail":"{readStateIndex:8878; appliedIndex:8877; }","duration":"185.805429ms","start":"2026-08-24T15:42:09.544627Z","end":"2026-08-24T15:42:09.730432Z","steps":["trace[436366493] 'read index received' (duration: 49.918828ms)","trace[436366493] 'applied index is now lower than readState.Index' (duration: 135.885041ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:09.730515Z","caller":"traceutil/trace.go:171","msg":"trace[1399527853] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"229.103768ms","start":"2026-08-24T15:42:09.501399Z","end":"2026-08-24T15:42:09.730503Z","steps":["trace[1399527853] 'process raft request' (duration: 93.018072ms)","trace[1399527853] 'compare' (duration: 135.375378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:42:09.730711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.079046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:42:09.73075Z","caller":"traceutil/trace.go:171","msg":"trace[12073125] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8576; }","duration":"186.204078ms","start":"2026-08-24T15:42:09.544534Z","end":"2026-08-24T15:42:09.730738Z","steps":["trace[12073125] 'agreement among raft nodes before linearized reading' (duration: 186.143907ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:09.744733Z","caller":"traceutil/trace.go:171","msg":"trace[1887511211] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"159.399555ms","start":"2026-08-24T15:42:09.585319Z","end":"2026-08-24T15:42:09.744718Z","steps":["trace[1887511211] 'process raft request' (duration: 159.118518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:09.744925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.333042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:42:09.744963Z","caller":"traceutil/trace.go:171","msg":"trace[991918687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8577; }","duration":"101.411223ms","start":"2026-08-24T15:42:09.643541Z","end":"2026-08-24T15:42:09.744952Z","steps":["trace[991918687] 'agreement among raft nodes before linearized reading' (duration: 101.350682ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:18.883654Z","caller":"traceutil/trace.go:171","msg":"trace[263748926] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"114.561729ms","start":"2026-08-24T15:42:18.769068Z","end":"2026-08-24T15:42:18.88363Z","steps":["trace[263748926] 'process raft request' (duration: 114.378084ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:19.743542Z","caller":"traceutil/trace.go:171","msg":"trace[496578119] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"125.42887ms","start":"2026-08-24T15:42:19.618081Z","end":"2026-08-24T15:42:19.74351Z","steps":["trace[496578119] 'process raft request' (duration: 125.205475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:44.0801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.477652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:43:44.080233Z","caller":"traceutil/trace.go:171","msg":"trace[1092597542] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:9460; }","duration":"141.631597ms","start":"2026-08-24T15:43:43.938546Z","end":"2026-08-24T15:43:44.080178Z","steps":["trace[1092597542] 'count revisions from in-memory index tree' (duration: 141.348139ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:44:12.389223Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1484a2210d3c2b8","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T15:44:12.394129Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-24T15:44:12.394218Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-24T15:46:29.81332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8310} {"level":"info","ts":"2026-08-24T15:46:29.911189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8310,"took":"94.338458ms","hash":2987100153,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":25149440,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-24T15:46:29.91127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2987100153,"revision":8310,"compact-revision":6118} {"level":"info","ts":"2026-08-24T15:49:31.64781Z","caller":"traceutil/trace.go:171","msg":"trace[1933847788] transaction","detail":"{read_only:false; response_revision:11938; number_of_response:1; }","duration":"107.194219ms","start":"2026-08-24T15:49:31.540586Z","end":"2026-08-24T15:49:31.647781Z","steps":["trace[1933847788] 'process raft request' (duration: 44.754194ms)","trace[1933847788] 'compare' (duration: 62.23328ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:49:31.839085Z","caller":"traceutil/trace.go:171","msg":"trace[809922768] transaction","detail":"{read_only:false; response_revision:11941; number_of_response:1; }","duration":"140.560648ms","start":"2026-08-24T15:49:31.698494Z","end":"2026-08-24T15:49:31.839054Z","steps":["trace[809922768] 'process raft request' (duration: 130.815462ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:31.844326Z","caller":"traceutil/trace.go:171","msg":"trace[680785679] transaction","detail":"{read_only:false; response_revision:11942; number_of_response:1; }","duration":"145.310483ms","start":"2026-08-24T15:49:31.699001Z","end":"2026-08-24T15:49:31.844312Z","steps":["trace[680785679] 'process raft request' (duration: 145.230792ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:51:29.819379Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10530} {"level":"info","ts":"2026-08-24T15:51:29.92138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10530,"took":"99.621178ms","hash":2904962452,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":26009600,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-24T15:51:29.921458Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2904962452,"revision":10530,"compact-revision":8310} {"level":"info","ts":"2026-08-24T15:56:01.452237Z","caller":"traceutil/trace.go:171","msg":"trace[1446784674] linearizableReadLoop","detail":"{readStateIndex:15057; appliedIndex:15056; }","duration":"324.827622ms","start":"2026-08-24T15:56:01.127383Z","end":"2026-08-24T15:56:01.452211Z","steps":["trace[1446784674] 'read index received' (duration: 324.692009ms)","trace[1446784674] 'applied index is now lower than readState.Index' (duration: 134.433µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:56:01.45237Z","caller":"traceutil/trace.go:171","msg":"trace[420137456] transaction","detail":"{read_only:false; response_revision:14557; number_of_response:1; }","duration":"380.301668ms","start":"2026-08-24T15:56:01.072023Z","end":"2026-08-24T15:56:01.452324Z","steps":["trace[420137456] 'process raft request' (duration: 380.024202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:56:01.452483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.099919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-24T15:56:01.452522Z","caller":"traceutil/trace.go:171","msg":"trace[57061306] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14557; }","duration":"325.214521ms","start":"2026-08-24T15:56:01.127298Z","end":"2026-08-24T15:56:01.452513Z","steps":["trace[57061306] 'agreement among raft nodes before linearized reading' (duration: 325.021956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:56:01.452587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:56:01.072002Z","time spent":"380.458002ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:56:01.45264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:56:01.127257Z","time spent":"325.373445ms","remote":"127.0.0.1:58502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-24T15:56:01.465004Z","caller":"traceutil/trace.go:171","msg":"trace[654313829] transaction","detail":"{read_only:false; response_revision:14558; number_of_response:1; }","duration":"306.004555ms","start":"2026-08-24T15:56:01.158962Z","end":"2026-08-24T15:56:01.464966Z","steps":["trace[654313829] 'process raft request' (duration: 305.759449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:56:01.465182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:56:01.158936Z","time spent":"306.147129ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:56:11.075298Z","caller":"traceutil/trace.go:171","msg":"trace[529958712] linearizableReadLoop","detail":"{readStateIndex:15232; appliedIndex:15231; }","duration":"185.586433ms","start":"2026-08-24T15:56:10.889686Z","end":"2026-08-24T15:56:11.075273Z","steps":["trace[529958712] 'read index received' (duration: 185.46672ms)","trace[529958712] 'applied index is now lower than readState.Index' (duration: 118.713µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:56:11.075406Z","caller":"traceutil/trace.go:171","msg":"trace[1431000990] transaction","detail":"{read_only:false; response_revision:14729; number_of_response:1; }","duration":"244.465481ms","start":"2026-08-24T15:56:10.830899Z","end":"2026-08-24T15:56:11.075365Z","steps":["trace[1431000990] 'process raft request' (duration: 244.225016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:56:11.075497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.804078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:56:11.075536Z","caller":"traceutil/trace.go:171","msg":"trace[1973095149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14729; }","duration":"185.89923ms","start":"2026-08-24T15:56:10.889625Z","end":"2026-08-24T15:56:11.075525Z","steps":["trace[1973095149] 'agreement among raft nodes before linearized reading' (duration: 185.758507ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:56:11.292395Z","caller":"traceutil/trace.go:171","msg":"trace[1972489751] transaction","detail":"{read_only:false; response_revision:14730; number_of_response:1; }","duration":"284.575796ms","start":"2026-08-24T15:56:11.007803Z","end":"2026-08-24T15:56:11.292379Z","steps":["trace[1972489751] 'process raft request' (duration: 284.374421ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:56:11.29271Z","caller":"traceutil/trace.go:171","msg":"trace[111202950] transaction","detail":"{read_only:false; response_revision:14731; number_of_response:1; }","duration":"283.861168ms","start":"2026-08-24T15:56:11.008815Z","end":"2026-08-24T15:56:11.292676Z","steps":["trace[111202950] 'process raft request' (duration: 283.52102ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:56:11.292757Z","caller":"traceutil/trace.go:171","msg":"trace[84137404] linearizableReadLoop","detail":"{readStateIndex:15235; appliedIndex:15232; }","duration":"202.094564ms","start":"2026-08-24T15:56:11.090627Z","end":"2026-08-24T15:56:11.292722Z","steps":["trace[84137404] 'read index received' (duration: 201.594442ms)","trace[84137404] 'applied index is now lower than readState.Index' (duration: 499.262µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:56:11.292897Z","caller":"traceutil/trace.go:171","msg":"trace[961816303] transaction","detail":"{read_only:false; response_revision:14732; number_of_response:1; }","duration":"239.264305ms","start":"2026-08-24T15:56:11.053599Z","end":"2026-08-24T15:56:11.292863Z","steps":["trace[961816303] 'process raft request' (duration: 239.010529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:56:11.292984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.34228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1f3bdbf3-66cd-45d6-abe7-b26040f739a5\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-08-24T15:56:11.293029Z","caller":"traceutil/trace.go:171","msg":"trace[1737100700] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1f3bdbf3-66cd-45d6-abe7-b26040f739a5; range_end:; response_count:1; response_revision:14732; }","duration":"202.441752ms","start":"2026-08-24T15:56:11.090574Z","end":"2026-08-24T15:56:11.293016Z","steps":["trace[1737100700] 'agreement among raft nodes before linearized reading' (duration: 202.282728ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:56:11.300311Z","caller":"traceutil/trace.go:171","msg":"trace[637071760] transaction","detail":"{read_only:false; response_revision:14733; number_of_response:1; }","duration":"208.963281ms","start":"2026-08-24T15:56:11.091337Z","end":"2026-08-24T15:56:11.300301Z","steps":["trace[637071760] 'process raft request' (duration: 208.717315ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:56:29.825256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12726} {"level":"info","ts":"2026-08-24T15:56:29.902759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12726,"took":"74.697983ms","hash":1202217541,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":29216768,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-24T15:56:29.902818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1202217541,"revision":12726,"compact-revision":10530} {"level":"info","ts":"2026-08-24T15:57:36.904728Z","caller":"traceutil/trace.go:171","msg":"trace[45192464] transaction","detail":"{read_only:false; response_revision:15742; number_of_response:1; }","duration":"411.501946ms","start":"2026-08-24T15:57:36.493203Z","end":"2026-08-24T15:57:36.904705Z","steps":["trace[45192464] 'process raft request' (duration: 411.360502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:36.905064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.493178Z","time spent":"411.621959ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:57:36.930097Z","caller":"traceutil/trace.go:171","msg":"trace[925870389] linearizableReadLoop","detail":"{readStateIndex:16279; appliedIndex:16277; }","duration":"315.688981ms","start":"2026-08-24T15:57:36.614384Z","end":"2026-08-24T15:57:36.930073Z","steps":["trace[925870389] 'read index received' (duration: 290.187973ms)","trace[925870389] 'applied index is now lower than readState.Index' (duration: 25.500018ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:57:36.930188Z","caller":"traceutil/trace.go:171","msg":"trace[424141552] transaction","detail":"{read_only:false; response_revision:15743; number_of_response:1; }","duration":"433.521751ms","start":"2026-08-24T15:57:36.496611Z","end":"2026-08-24T15:57:36.930133Z","steps":["trace[424141552] 'process raft request' (duration: 433.306245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:36.930362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.929448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:57:36.930454Z","caller":"traceutil/trace.go:171","msg":"trace[10682280] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15743; }","duration":"316.092131ms","start":"2026-08-24T15:57:36.614343Z","end":"2026-08-24T15:57:36.930435Z","steps":["trace[10682280] 'agreement among raft nodes before linearized reading' (duration: 315.880506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:36.930475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.888766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:57:36.930366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.496529Z","time spent":"433.739656ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:57:36.930579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.096493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:57:36.930504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.614327Z","time spent":"316.161833ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-08-24T15:57:36.930619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.237505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:57:36.930608Z","caller":"traceutil/trace.go:171","msg":"trace[949801232] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15743; }","duration":"314.138744ms","start":"2026-08-24T15:57:36.616461Z","end":"2026-08-24T15:57:36.930599Z","steps":["trace[949801232] 'agreement among raft nodes before linearized reading' (duration: 314.090893ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:57:36.930641Z","caller":"traceutil/trace.go:171","msg":"trace[829241021] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15743; }","duration":"316.290826ms","start":"2026-08-24T15:57:36.614343Z","end":"2026-08-24T15:57:36.930634Z","steps":["trace[829241021] 'agreement among raft nodes before linearized reading' (duration: 316.260425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:36.930658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.614323Z","time spent":"316.330947ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-24T15:57:36.930656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.616452Z","time spent":"314.197965ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-08-24T15:57:36.930586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.111717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-08-24T15:57:36.930675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.937634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:57:36.930575Z","caller":"traceutil/trace.go:171","msg":"trace[1864937975] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15743; }","duration":"316.02267ms","start":"2026-08-24T15:57:36.614536Z","end":"2026-08-24T15:57:36.930559Z","steps":["trace[1864937975] 'agreement among raft nodes before linearized reading' (duration: 315.877976ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:57:36.930746Z","caller":"traceutil/trace.go:171","msg":"trace[722233372] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15743; }","duration":"308.307662ms","start":"2026-08-24T15:57:36.622426Z","end":"2026-08-24T15:57:36.930734Z","steps":["trace[722233372] 'agreement among raft nodes before linearized reading' (duration: 308.072626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:36.930704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.050996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:57:36.930824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.622374Z","time spent":"308.435915ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5471,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-24T15:57:36.930851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.451508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-08-24T15:57:36.930799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.614507Z","time spent":"316.287996ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"info","ts":"2026-08-24T15:57:36.930868Z","caller":"traceutil/trace.go:171","msg":"trace[1119593018] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15743; }","duration":"313.476668ms","start":"2026-08-24T15:57:36.617385Z","end":"2026-08-24T15:57:36.930862Z","steps":["trace[1119593018] 'agreement among raft nodes before linearized reading' (duration: 313.426937ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:57:36.930798Z","caller":"traceutil/trace.go:171","msg":"trace[214393914] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15743; }","duration":"315.143429ms","start":"2026-08-24T15:57:36.615639Z","end":"2026-08-24T15:57:36.930782Z","steps":["trace[214393914] 'agreement among raft nodes before linearized reading' (duration: 314.935894ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:57:36.930847Z","caller":"traceutil/trace.go:171","msg":"trace[1037509651] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15743; }","duration":"315.252631ms","start":"2026-08-24T15:57:36.615573Z","end":"2026-08-24T15:57:36.930825Z","steps":["trace[1037509651] 'agreement among raft nodes before linearized reading' (duration: 315.041196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:36.931779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.750257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-t5qbx\" ","response":"range_response_count:1 size:9708"} {"level":"warn","ts":"2026-08-24T15:57:36.930883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.617381Z","time spent":"313.498748ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5471,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-08-24T15:57:36.931861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.064291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-24T15:57:36.932391Z","caller":"traceutil/trace.go:171","msg":"trace[1482971336] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15743; }","duration":"138.625014ms","start":"2026-08-24T15:57:36.793738Z","end":"2026-08-24T15:57:36.932363Z","steps":["trace[1482971336] 'agreement among raft nodes before linearized reading' (duration: 137.100327ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:57:36.932393Z","caller":"traceutil/trace.go:171","msg":"trace[1402192771] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-t5qbx; range_end:; response_count:1; response_revision:15743; }","duration":"214.374682ms","start":"2026-08-24T15:57:36.717949Z","end":"2026-08-24T15:57:36.932324Z","steps":["trace[1402192771] 'agreement among raft nodes before linearized reading' (duration: 212.721512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:36.932276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.615557Z","time spent":"316.707706ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-08-24T15:57:36.932615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:36.615608Z","time spent":"316.970573ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-08-24T15:57:36.930722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.589074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:57:36.93288Z","caller":"traceutil/trace.go:171","msg":"trace[302358554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15743; }","duration":"290.730846ms","start":"2026-08-24T15:57:36.642106Z","end":"2026-08-24T15:57:36.932837Z","steps":["trace[302358554] 'agreement among raft nodes before linearized reading' (duration: 288.596294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:37.923074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.722327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:57:37.923191Z","caller":"traceutil/trace.go:171","msg":"trace[2004912681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15746; }","duration":"232.914992ms","start":"2026-08-24T15:57:37.690248Z","end":"2026-08-24T15:57:37.923163Z","steps":["trace[2004912681] 'range keys from in-memory index tree' (duration: 232.40791ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:57:38.044936Z","caller":"traceutil/trace.go:171","msg":"trace[31993097] transaction","detail":"{read_only:false; response_revision:15747; number_of_response:1; }","duration":"201.350686ms","start":"2026-08-24T15:57:37.843554Z","end":"2026-08-24T15:57:38.044905Z","steps":["trace[31993097] 'process raft request' (duration: 201.175782ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:57:38.184777Z","caller":"traceutil/trace.go:171","msg":"trace[1373246931] linearizableReadLoop","detail":"{readStateIndex:16285; appliedIndex:16282; }","duration":"141.900813ms","start":"2026-08-24T15:57:38.042854Z","end":"2026-08-24T15:57:38.184755Z","steps":["trace[1373246931] 'read index received' (duration: 1.995288ms)","trace[1373246931] 'applied index is now lower than readState.Index' (duration: 139.904855ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:57:38.184801Z","caller":"traceutil/trace.go:171","msg":"trace[226038994] transaction","detail":"{read_only:false; response_revision:15748; number_of_response:1; }","duration":"324.000042ms","start":"2026-08-24T15:57:37.86077Z","end":"2026-08-24T15:57:38.18477Z","steps":["trace[226038994] 'process raft request' (duration: 301.64906ms)","trace[226038994] 'compare' (duration: 22.155747ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:57:38.184884Z","caller":"traceutil/trace.go:171","msg":"trace[331432901] transaction","detail":"{read_only:false; response_revision:15749; number_of_response:1; }","duration":"257.480808ms","start":"2026-08-24T15:57:37.927396Z","end":"2026-08-24T15:57:38.184877Z","steps":["trace[331432901] 'process raft request' (duration: 257.306134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:38.184948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:57:37.860752Z","time spent":"324.121545ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:57:38.187065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.201708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fdzhq\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-08-24T15:57:38.187117Z","caller":"traceutil/trace.go:171","msg":"trace[674606899] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fdzhq; range_end:; response_count:1; response_revision:15749; }","duration":"144.28622ms","start":"2026-08-24T15:57:38.042818Z","end":"2026-08-24T15:57:38.187104Z","steps":["trace[674606899] 'agreement among raft nodes before linearized reading' (duration: 142.229151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:57:38.187509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.938865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fdzhq\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-08-24T15:57:38.187669Z","caller":"traceutil/trace.go:171","msg":"trace[2111877518] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fdzhq; range_end:; response_count:1; response_revision:15749; }","duration":"128.17625ms","start":"2026-08-24T15:57:38.05947Z","end":"2026-08-24T15:57:38.187646Z","steps":["trace[2111877518] 'agreement among raft nodes before linearized reading' (duration: 127.885532ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:01:29.832456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15039} {"level":"info","ts":"2026-08-24T16:01:29.931308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15039,"took":"96.449126ms","hash":1480372453,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T16:01:29.931374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1480372453,"revision":15039,"compact-revision":12726} {"level":"info","ts":"2026-08-24T16:02:27.135391Z","caller":"traceutil/trace.go:171","msg":"trace[133024332] linearizableReadLoop","detail":"{readStateIndex:18528; appliedIndex:18527; }","duration":"141.578885ms","start":"2026-08-24T16:02:26.993794Z","end":"2026-08-24T16:02:27.135373Z","steps":["trace[133024332] 'read index received' (duration: 141.484883ms)","trace[133024332] 'applied index is now lower than readState.Index' (duration: 93.382µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:02:27.135483Z","caller":"traceutil/trace.go:171","msg":"trace[2032881128] transaction","detail":"{read_only:false; response_revision:17921; number_of_response:1; }","duration":"193.364867ms","start":"2026-08-24T16:02:26.94211Z","end":"2026-08-24T16:02:27.135475Z","steps":["trace[2032881128] 'process raft request' (duration: 193.15343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:27.135614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.790331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:02:27.135679Z","caller":"traceutil/trace.go:171","msg":"trace[1173622751] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:17921; }","duration":"141.910624ms","start":"2026-08-24T16:02:26.993754Z","end":"2026-08-24T16:02:27.135665Z","steps":["trace[1173622751] 'agreement among raft nodes before linearized reading' (duration: 141.7522ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:03:34.236638Z","caller":"traceutil/trace.go:171","msg":"trace[1163268882] linearizableReadLoop","detail":"{readStateIndex:19031; appliedIndex:19030; }","duration":"192.941325ms","start":"2026-08-24T16:03:34.043666Z","end":"2026-08-24T16:03:34.236608Z","steps":["trace[1163268882] 'read index received' (duration: 122.099481ms)","trace[1163268882] 'applied index is now lower than readState.Index' (duration: 70.838323ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:03:34.236982Z","caller":"traceutil/trace.go:171","msg":"trace[1543635696] transaction","detail":"{read_only:false; response_revision:18406; number_of_response:1; }","duration":"206.075896ms","start":"2026-08-24T16:03:34.03089Z","end":"2026-08-24T16:03:34.236966Z","steps":["trace[1543635696] 'process raft request' (duration: 134.960945ms)","trace[1543635696] 'compare' (duration: 70.29326ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:03:34.237265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.580031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T16:03:34.237305Z","caller":"traceutil/trace.go:171","msg":"trace[179374003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18406; }","duration":"193.653103ms","start":"2026-08-24T16:03:34.043641Z","end":"2026-08-24T16:03:34.237294Z","steps":["trace[179374003] 'agreement among raft nodes before linearized reading' (duration: 193.51233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:03:34.237534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.796697ms","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-08-24T16:03:34.237575Z","caller":"traceutil/trace.go:171","msg":"trace[81573126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18406; }","duration":"193.833757ms","start":"2026-08-24T16:03:34.043728Z","end":"2026-08-24T16:03:34.237562Z","steps":["trace[81573126] 'agreement among raft nodes before linearized reading' (duration: 193.744175ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:03:34.637398Z","caller":"traceutil/trace.go:171","msg":"trace[1937378990] transaction","detail":"{read_only:false; response_revision:18407; number_of_response:1; }","duration":"395.494275ms","start":"2026-08-24T16:03:34.241659Z","end":"2026-08-24T16:03:34.637153Z","steps":["trace[1937378990] 'process raft request' (duration: 394.83511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:03:34.637568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:03:34.241634Z","time spent":"395.844605ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:03:34.695557Z","caller":"traceutil/trace.go:171","msg":"trace[1424859551] transaction","detail":"{read_only:false; response_revision:18409; number_of_response:1; }","duration":"446.254391ms","start":"2026-08-24T16:03:34.249285Z","end":"2026-08-24T16:03:34.69554Z","steps":["trace[1424859551] 'process raft request' (duration: 446.031825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:03:34.695677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:03:34.24927Z","time spent":"446.330213ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:03:34.695683Z","caller":"traceutil/trace.go:171","msg":"trace[690907825] linearizableReadLoop","detail":"{readStateIndex:19035; appliedIndex:19031; }","duration":"280.961068ms","start":"2026-08-24T16:03:34.414703Z","end":"2026-08-24T16:03:34.695664Z","steps":["trace[690907825] 'read index received' (duration: 221.722056ms)","trace[690907825] 'applied index is now lower than readState.Index' (duration: 59.237812ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:03:34.695777Z","caller":"traceutil/trace.go:171","msg":"trace[1035836200] transaction","detail":"{read_only:false; response_revision:18410; number_of_response:1; }","duration":"421.409336ms","start":"2026-08-24T16:03:34.274362Z","end":"2026-08-24T16:03:34.695771Z","steps":["trace[1035836200] 'process raft request' (duration: 421.13506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:03:34.695823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:03:34.274328Z","time spent":"421.463098ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:03:34.695839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.121542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:03:34.695893Z","caller":"traceutil/trace.go:171","msg":"trace[1932541963] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:18410; }","duration":"281.219754ms","start":"2026-08-24T16:03:34.414658Z","end":"2026-08-24T16:03:34.695878Z","steps":["trace[1932541963] 'agreement among raft nodes before linearized reading' (duration: 281.125402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:03:34.695908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.816221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fdzhq\" ","response":"range_response_count:1 size:22011"} {"level":"info","ts":"2026-08-24T16:03:34.695924Z","caller":"traceutil/trace.go:171","msg":"trace[1515043417] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fdzhq; range_end:; response_count:1; response_revision:18410; }","duration":"143.887032ms","start":"2026-08-24T16:03:34.552032Z","end":"2026-08-24T16:03:34.695919Z","steps":["trace[1515043417] 'agreement among raft nodes before linearized reading' (duration: 143.837281ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:03:34.695419Z","caller":"traceutil/trace.go:171","msg":"trace[1412177888] transaction","detail":"{read_only:false; response_revision:18408; number_of_response:1; }","duration":"449.528411ms","start":"2026-08-24T16:03:34.245846Z","end":"2026-08-24T16:03:34.695374Z","steps":["trace[1412177888] 'process raft request' (duration: 449.339126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:03:34.696436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:03:34.245812Z","time spent":"450.508345ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:03:34.942327Z","caller":"traceutil/trace.go:171","msg":"trace[862473456] linearizableReadLoop","detail":"{readStateIndex:19036; appliedIndex:19035; }","duration":"236.915546ms","start":"2026-08-24T16:03:34.70539Z","end":"2026-08-24T16:03:34.942305Z","steps":["trace[862473456] 'read index received' (duration: 198.274656ms)","trace[862473456] 'applied index is now lower than readState.Index' (duration: 38.6396ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:03:34.94256Z","caller":"traceutil/trace.go:171","msg":"trace[1288206966] transaction","detail":"{read_only:false; response_revision:18411; number_of_response:1; }","duration":"240.529184ms","start":"2026-08-24T16:03:34.701899Z","end":"2026-08-24T16:03:34.942428Z","steps":["trace[1288206966] 'process raft request' (duration: 201.74822ms)","trace[1288206966] 'compare' (duration: 38.517807ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:03:34.943617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.192327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:950625"} {"level":"info","ts":"2026-08-24T16:03:34.943731Z","caller":"traceutil/trace.go:171","msg":"trace[667302354] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:18411; }","duration":"238.31645ms","start":"2026-08-24T16:03:34.705351Z","end":"2026-08-24T16:03:34.943667Z","steps":["trace[667302354] 'agreement among raft nodes before linearized reading' (duration: 237.071149ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:03:34.970415Z","caller":"traceutil/trace.go:171","msg":"trace[1252531310] transaction","detail":"{read_only:false; response_revision:18412; number_of_response:1; }","duration":"195.270553ms","start":"2026-08-24T16:03:34.775095Z","end":"2026-08-24T16:03:34.970365Z","steps":["trace[1252531310] 'process raft request' (duration: 195.074358ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:03:35.103213Z","caller":"traceutil/trace.go:171","msg":"trace[906143777] linearizableReadLoop","detail":"{readStateIndex:19038; appliedIndex:19036; }","duration":"160.801454ms","start":"2026-08-24T16:03:34.94239Z","end":"2026-08-24T16:03:35.103191Z","steps":["trace[906143777] 'read index received' (duration: 27.784756ms)","trace[906143777] 'applied index is now lower than readState.Index' (duration: 133.015608ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:03:35.103318Z","caller":"traceutil/trace.go:171","msg":"trace[681395749] transaction","detail":"{read_only:false; response_revision:18413; number_of_response:1; }","duration":"166.193136ms","start":"2026-08-24T16:03:34.937115Z","end":"2026-08-24T16:03:35.103308Z","steps":["trace[681395749] 'process raft request' (duration: 155.030194ms)","trace[681395749] 'compare' (duration: 10.607818ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:03:35.103458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.259789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:03:35.103487Z","caller":"traceutil/trace.go:171","msg":"trace[1115381991] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:18413; }","duration":"275.30852ms","start":"2026-08-24T16:03:34.828171Z","end":"2026-08-24T16:03:35.10348Z","steps":["trace[1115381991] 'agreement among raft nodes before linearized reading' (duration: 275.238128ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:05:01.124253Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1484a2210d3c2b8","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T16:05:01.131367Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-24T16:05:01.131528Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-24T16:06:29.847088Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17525} {"level":"info","ts":"2026-08-24T16:06:29.941986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17525,"took":"91.23705ms","hash":1774027180,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":35426304,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-24T16:06:29.942044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1774027180,"revision":17525,"compact-revision":15039} {"level":"info","ts":"2026-08-24T16:09:23.24369Z","caller":"traceutil/trace.go:171","msg":"trace[1835255905] linearizableReadLoop","detail":"{readStateIndex:21902; appliedIndex:21901; }","duration":"108.8627ms","start":"2026-08-24T16:09:23.134804Z","end":"2026-08-24T16:09:23.243666Z","steps":["trace[1835255905] 'read index received' (duration: 108.746606ms)","trace[1835255905] 'applied index is now lower than readState.Index' (duration: 114.724µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:09:23.243902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.072485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-24T16:09:23.243955Z","caller":"traceutil/trace.go:171","msg":"trace[891840453] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:21188; }","duration":"109.174437ms","start":"2026-08-24T16:09:23.134766Z","end":"2026-08-24T16:09:23.24394Z","steps":["trace[891840453] 'agreement among raft nodes before linearized reading' (duration: 108.999243ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:29.968445Z","caller":"traceutil/trace.go:171","msg":"trace[1697332962] transaction","detail":"{read_only:false; response_revision:21222; number_of_response:1; }","duration":"183.707272ms","start":"2026-08-24T16:09:29.784712Z","end":"2026-08-24T16:09:29.968419Z","steps":["trace[1697332962] 'process raft request' (duration: 183.545168ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:38.838065Z","caller":"traceutil/trace.go:171","msg":"trace[649384923] transaction","detail":"{read_only:false; response_revision:21270; number_of_response:1; }","duration":"129.620414ms","start":"2026-08-24T16:09:38.708408Z","end":"2026-08-24T16:09:38.838028Z","steps":["trace[649384923] 'process raft request' (duration: 124.225723ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:38.859162Z","caller":"traceutil/trace.go:171","msg":"trace[2051350815] transaction","detail":"{read_only:false; response_revision:21271; number_of_response:1; }","duration":"100.574908ms","start":"2026-08-24T16:09:38.758567Z","end":"2026-08-24T16:09:38.859142Z","steps":["trace[2051350815] 'process raft request' (duration: 100.443375ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:44.572628Z","caller":"traceutil/trace.go:171","msg":"trace[444345313] linearizableReadLoop","detail":"{readStateIndex:22016; appliedIndex:22015; }","duration":"103.905399ms","start":"2026-08-24T16:09:44.468697Z","end":"2026-08-24T16:09:44.572602Z","steps":["trace[444345313] 'read index received' (duration: 103.703094ms)","trace[444345313] 'applied index is now lower than readState.Index' (duration: 200.545µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:09:44.572854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.141804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-24T16:09:44.572889Z","caller":"traceutil/trace.go:171","msg":"trace[274690467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21297; }","duration":"104.221917ms","start":"2026-08-24T16:09:44.468656Z","end":"2026-08-24T16:09:44.572878Z","steps":["trace[274690467] 'agreement among raft nodes before linearized reading' (duration: 104.050923ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:44.572835Z","caller":"traceutil/trace.go:171","msg":"trace[2141879417] transaction","detail":"{read_only:false; response_revision:21297; number_of_response:1; }","duration":"123.472724ms","start":"2026-08-24T16:09:44.449313Z","end":"2026-08-24T16:09:44.572785Z","steps":["trace[2141879417] 'process raft request' (duration: 123.109516ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:44.753666Z","caller":"traceutil/trace.go:171","msg":"trace[2116709850] linearizableReadLoop","detail":"{readStateIndex:22017; appliedIndex:22016; }","duration":"108.484031ms","start":"2026-08-24T16:09:44.645154Z","end":"2026-08-24T16:09:44.753638Z","steps":["trace[2116709850] 'read index received' (duration: 81.989255ms)","trace[2116709850] 'applied index is now lower than readState.Index' (duration: 26.493506ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:09:44.753824Z","caller":"traceutil/trace.go:171","msg":"trace[905457339] transaction","detail":"{read_only:false; response_revision:21298; number_of_response:1; }","duration":"176.710551ms","start":"2026-08-24T16:09:44.5771Z","end":"2026-08-24T16:09:44.753811Z","steps":["trace[905457339] 'process raft request' (duration: 150.148504ms)","trace[905457339] 'compare' (duration: 26.243219ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:09:44.753976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.811489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:09:44.754646Z","caller":"traceutil/trace.go:171","msg":"trace[796992836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21298; }","duration":"109.552716ms","start":"2026-08-24T16:09:44.645072Z","end":"2026-08-24T16:09:44.754625Z","steps":["trace[796992836] 'agreement among raft nodes before linearized reading' (duration: 108.816759ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:45.022694Z","caller":"traceutil/trace.go:171","msg":"trace[1469506814] transaction","detail":"{read_only:false; response_revision:21299; number_of_response:1; }","duration":"264.642641ms","start":"2026-08-24T16:09:44.758018Z","end":"2026-08-24T16:09:45.022661Z","steps":["trace[1469506814] 'process raft request' (duration: 264.488367ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:45.069507Z","caller":"traceutil/trace.go:171","msg":"trace[1565555845] transaction","detail":"{read_only:false; response_revision:21301; number_of_response:1; }","duration":"214.300116ms","start":"2026-08-24T16:09:44.855173Z","end":"2026-08-24T16:09:45.069474Z","steps":["trace[1565555845] 'process raft request' (duration: 214.218104ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:45.069544Z","caller":"traceutil/trace.go:171","msg":"trace[2064701009] transaction","detail":"{read_only:false; response_revision:21300; number_of_response:1; }","duration":"311.247325ms","start":"2026-08-24T16:09:44.758242Z","end":"2026-08-24T16:09:45.06949Z","steps":["trace[2064701009] 'process raft request' (duration: 310.996829ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:09:45.069704Z","caller":"traceutil/trace.go:171","msg":"trace[1226966815] transaction","detail":"{read_only:false; response_revision:21302; number_of_response:1; }","duration":"189.884292ms","start":"2026-08-24T16:09:44.87975Z","end":"2026-08-24T16:09:45.069634Z","steps":["trace[1226966815] 'process raft request' (duration: 189.682557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:09:45.069901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:09:44.75819Z","time spent":"311.503662ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:11:00.447654Z","caller":"traceutil/trace.go:171","msg":"trace[1486325569] transaction","detail":"{read_only:false; response_revision:22023; number_of_response:1; }","duration":"129.532863ms","start":"2026-08-24T16:11:00.318089Z","end":"2026-08-24T16:11:00.447621Z","steps":["trace[1486325569] 'process raft request' (duration: 129.386039ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:11:29.853631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20014} {"level":"info","ts":"2026-08-24T16:11:29.96408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20014,"took":"107.901517ms","hash":596639200,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T16:11:29.964136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":596639200,"revision":20014,"compact-revision":17525} {"level":"info","ts":"2026-08-24T16:16:17.265117Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000665c.wal"} {"level":"info","ts":"2026-08-24T16:16:29.88052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22227} {"level":"info","ts":"2026-08-24T16:16:29.989672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22227,"took":"103.976141ms","hash":1828074677,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":45539328,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-08-24T16:16:29.989726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1828074677,"revision":22227,"compact-revision":20014} {"level":"info","ts":"2026-08-24T16:17:29.195967Z","caller":"traceutil/trace.go:171","msg":"trace[1746611832] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"269.379089ms","start":"2026-08-24T16:17:28.926562Z","end":"2026-08-24T16:17:29.195941Z","steps":["trace[1746611832] 'process raft request' (duration: 269.189654ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:29.239882Z","caller":"traceutil/trace.go:171","msg":"trace[1352697329] transaction","detail":"{read_only:false; response_revision:26070; number_of_response:1; }","duration":"303.83633ms","start":"2026-08-24T16:17:28.936026Z","end":"2026-08-24T16:17:29.239862Z","steps":["trace[1352697329] 'process raft request' (duration: 303.712967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:29.240198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:28.936009Z","time spent":"304.125867ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:29.377937Z","caller":"traceutil/trace.go:171","msg":"trace[1156687283] linearizableReadLoop","detail":"{readStateIndex:26922; appliedIndex:26921; }","duration":"163.990393ms","start":"2026-08-24T16:17:29.213908Z","end":"2026-08-24T16:17:29.377898Z","steps":["trace[1156687283] 'read index received' (duration: 26.337129ms)","trace[1156687283] 'applied index is now lower than readState.Index' (duration: 137.652024ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:29.378075Z","caller":"traceutil/trace.go:171","msg":"trace[2140175055] transaction","detail":"{read_only:false; response_revision:26071; number_of_response:1; }","duration":"176.805634ms","start":"2026-08-24T16:17:29.20122Z","end":"2026-08-24T16:17:29.378026Z","steps":["trace[2140175055] 'process raft request' (duration: 106.30358ms)","trace[2140175055] 'compare' (duration: 70.210626ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:29.378244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.308571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T16:17:29.37833Z","caller":"traceutil/trace.go:171","msg":"trace[189523516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26071; }","duration":"164.424454ms","start":"2026-08-24T16:17:29.213896Z","end":"2026-08-24T16:17:29.37832Z","steps":["trace[189523516] 'agreement among raft nodes before linearized reading' (duration: 164.193578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:29.378418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.569522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-24T16:17:29.37851Z","caller":"traceutil/trace.go:171","msg":"trace[1447284638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26071; }","duration":"157.711856ms","start":"2026-08-24T16:17:29.220777Z","end":"2026-08-24T16:17:29.378489Z","steps":["trace[1447284638] 'agreement among raft nodes before linearized reading' (duration: 157.455329ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:29.537073Z","caller":"traceutil/trace.go:171","msg":"trace[893216167] linearizableReadLoop","detail":"{readStateIndex:26924; appliedIndex:26923; }","duration":"120.760961ms","start":"2026-08-24T16:17:29.416292Z","end":"2026-08-24T16:17:29.537053Z","steps":["trace[893216167] 'read index received' (duration: 112.72827ms)","trace[893216167] 'applied index is now lower than readState.Index' (duration: 8.032051ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:29.537155Z","caller":"traceutil/trace.go:171","msg":"trace[360604180] transaction","detail":"{read_only:false; response_revision:26073; number_of_response:1; }","duration":"154.75076ms","start":"2026-08-24T16:17:29.382377Z","end":"2026-08-24T16:17:29.537127Z","steps":["trace[360604180] 'process raft request' (duration: 146.626127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:29.537278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.975206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-24T16:17:29.537306Z","caller":"traceutil/trace.go:171","msg":"trace[866593490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26073; }","duration":"121.051808ms","start":"2026-08-24T16:17:29.416245Z","end":"2026-08-24T16:17:29.537297Z","steps":["trace[866593490] 'agreement among raft nodes before linearized reading' (duration: 120.889984ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:29.89532Z","caller":"traceutil/trace.go:171","msg":"trace[1588116825] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"476.831058ms","start":"2026-08-24T16:17:29.418463Z","end":"2026-08-24T16:17:29.895294Z","steps":["trace[1588116825] 'process raft request' (duration: 430.155741ms)","trace[1588116825] 'compare' (duration: 46.498523ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:29.895466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:29.418439Z","time spent":"476.9527ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:29.908313Z","caller":"traceutil/trace.go:171","msg":"trace[2123402621] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"364.407016ms","start":"2026-08-24T16:17:29.543885Z","end":"2026-08-24T16:17:29.908292Z","steps":["trace[2123402621] 'process raft request' (duration: 363.970335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:29.908453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:29.543859Z","time spent":"364.519648ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:29.908573Z","caller":"traceutil/trace.go:171","msg":"trace[1375951370] transaction","detail":"{read_only:false; response_revision:26076; number_of_response:1; }","duration":"338.99039ms","start":"2026-08-24T16:17:29.569569Z","end":"2026-08-24T16:17:29.908559Z","steps":["trace[1375951370] 'process raft request' (duration: 338.416136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:29.90863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:29.569542Z","time spent":"339.056581ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:17:30.175218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.779267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:30.175602Z","caller":"traceutil/trace.go:171","msg":"trace[345352551] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"324.040456ms","start":"2026-08-24T16:17:29.851296Z","end":"2026-08-24T16:17:30.175337Z","steps":["trace[345352551] 'process raft request' (duration: 189.508691ms)","trace[345352551] 'compare' (duration: 133.536511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:30.175664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:29.85128Z","time spent":"324.354204ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:35.233761Z","caller":"traceutil/trace.go:171","msg":"trace[1663273469] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"317.78503ms","start":"2026-08-24T16:17:34.915919Z","end":"2026-08-24T16:17:35.233704Z","steps":["trace[1663273469] 'process raft request' (duration: 317.642446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:35.23393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:34.915898Z","time spent":"317.955405ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:35.737466Z","caller":"traceutil/trace.go:171","msg":"trace[1865958086] linearizableReadLoop","detail":"{readStateIndex:26969; appliedIndex:26968; }","duration":"176.626039ms","start":"2026-08-24T16:17:35.560818Z","end":"2026-08-24T16:17:35.737444Z","steps":["trace[1865958086] 'read index received' (duration: 176.474825ms)","trace[1865958086] 'applied index is now lower than readState.Index' (duration: 150.214µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:35.737604Z","caller":"traceutil/trace.go:171","msg":"trace[1156819817] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"294.61359ms","start":"2026-08-24T16:17:35.442963Z","end":"2026-08-24T16:17:35.737576Z","steps":["trace[1156819817] 'process raft request' (duration: 294.346913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:35.737703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.860034ms","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:8"} {"level":"info","ts":"2026-08-24T16:17:35.737735Z","caller":"traceutil/trace.go:171","msg":"trace[983832759] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26117; }","duration":"176.951066ms","start":"2026-08-24T16:17:35.560775Z","end":"2026-08-24T16:17:35.737726Z","steps":["trace[983832759] 'agreement among raft nodes before linearized reading' (duration: 176.800953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:35.738104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.163352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-24T16:17:35.738142Z","caller":"traceutil/trace.go:171","msg":"trace[1186710306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26117; }","duration":"175.226643ms","start":"2026-08-24T16:17:35.562904Z","end":"2026-08-24T16:17:35.73813Z","steps":["trace[1186710306] 'agreement among raft nodes before linearized reading' (duration: 175.08629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:35.922796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.384947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:35.923177Z","caller":"traceutil/trace.go:171","msg":"trace[716542409] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"180.355972ms","start":"2026-08-24T16:17:35.742801Z","end":"2026-08-24T16:17:35.923157Z","steps":["trace[716542409] 'process raft request' (duration: 46.536954ms)","trace[716542409] 'compare' (duration: 133.290595ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:36.141669Z","caller":"traceutil/trace.go:171","msg":"trace[322002380] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"143.93761ms","start":"2026-08-24T16:17:35.997701Z","end":"2026-08-24T16:17:36.141638Z","steps":["trace[322002380] 'process raft request' (duration: 139.331355ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:36.449148Z","caller":"traceutil/trace.go:171","msg":"trace[1723070917] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"248.21536ms","start":"2026-08-24T16:17:36.200912Z","end":"2026-08-24T16:17:36.449127Z","steps":["trace[1723070917] 'process raft request' (duration: 248.087206ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:36.47389Z","caller":"traceutil/trace.go:171","msg":"trace[935576341] linearizableReadLoop","detail":"{readStateIndex:26975; appliedIndex:26974; }","duration":"265.134033ms","start":"2026-08-24T16:17:36.208735Z","end":"2026-08-24T16:17:36.473869Z","steps":["trace[935576341] 'read index received' (duration: 240.363533ms)","trace[935576341] 'applied index is now lower than readState.Index' (duration: 24.76969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:36.474083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.328447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-24T16:17:36.474152Z","caller":"traceutil/trace.go:171","msg":"trace[1855384084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26123; }","duration":"265.411629ms","start":"2026-08-24T16:17:36.20873Z","end":"2026-08-24T16:17:36.474142Z","steps":["trace[1855384084] 'agreement among raft nodes before linearized reading' (duration: 265.217345ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:36.474434Z","caller":"traceutil/trace.go:171","msg":"trace[1069266333] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"273.326287ms","start":"2026-08-24T16:17:36.201099Z","end":"2026-08-24T16:17:36.474425Z","steps":["trace[1069266333] 'process raft request' (duration: 272.66104ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:36.705283Z","caller":"traceutil/trace.go:171","msg":"trace[269045662] linearizableReadLoop","detail":"{readStateIndex:26976; appliedIndex:26975; }","duration":"192.185057ms","start":"2026-08-24T16:17:36.513076Z","end":"2026-08-24T16:17:36.705262Z","steps":["trace[269045662] 'read index received' (duration: 124.724169ms)","trace[269045662] 'applied index is now lower than readState.Index' (duration: 67.459958ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:36.705488Z","caller":"traceutil/trace.go:171","msg":"trace[888590717] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"216.218699ms","start":"2026-08-24T16:17:36.489257Z","end":"2026-08-24T16:17:36.705476Z","steps":["trace[888590717] 'process raft request' (duration: 148.577927ms)","trace[888590717] 'compare' (duration: 67.329234ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:36.705748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.655449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T16:17:36.705938Z","caller":"traceutil/trace.go:171","msg":"trace[1715195532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26124; }","duration":"192.911366ms","start":"2026-08-24T16:17:36.512999Z","end":"2026-08-24T16:17:36.705911Z","steps":["trace[1715195532] 'agreement among raft nodes before linearized reading' (duration: 192.557187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:36.978828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.586198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:36.979032Z","caller":"traceutil/trace.go:171","msg":"trace[1246358393] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"184.013973ms","start":"2026-08-24T16:17:36.795004Z","end":"2026-08-24T16:17:36.979018Z","steps":["trace[1246358393] 'process raft request' (duration: 183.973042ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:36.979112Z","caller":"traceutil/trace.go:171","msg":"trace[25253348] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"388.590211ms","start":"2026-08-24T16:17:36.590446Z","end":"2026-08-24T16:17:36.979036Z","steps":["trace[25253348] 'process raft request' (duration: 215.718127ms)","trace[25253348] 'compare' (duration: 172.036054ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:36.979233Z","caller":"traceutil/trace.go:171","msg":"trace[426230074] linearizableReadLoop","detail":"{readStateIndex:26977; appliedIndex:26976; }","duration":"273.862601ms","start":"2026-08-24T16:17:36.705339Z","end":"2026-08-24T16:17:36.979201Z","steps":["trace[426230074] 'read index received' (duration: 100.844933ms)","trace[426230074] 'applied index is now lower than readState.Index' (duration: 173.014508ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:36.979246Z","caller":"traceutil/trace.go:171","msg":"trace[2077551706] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"269.218025ms","start":"2026-08-24T16:17:36.709986Z","end":"2026-08-24T16:17:36.979204Z","steps":["trace[2077551706] 'process raft request' (duration: 268.941248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:36.979268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:36.590422Z","time spent":"388.759665ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:17:36.979339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.523908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T16:17:36.979329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.643211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:17:36.979371Z","caller":"traceutil/trace.go:171","msg":"trace[1501014780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26127; }","duration":"334.599769ms","start":"2026-08-24T16:17:36.644761Z","end":"2026-08-24T16:17:36.979361Z","steps":["trace[1501014780] 'agreement among raft nodes before linearized reading' (duration: 334.543568ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:36.979412Z","caller":"traceutil/trace.go:171","msg":"trace[1245040757] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:26127; }","duration":"324.744213ms","start":"2026-08-24T16:17:36.654647Z","end":"2026-08-24T16:17:36.979392Z","steps":["trace[1245040757] 'agreement among raft nodes before linearized reading' (duration: 324.557679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:36.979404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:36.644744Z","time spent":"334.654221ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T16:17:36.979439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.989305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-08-24T16:17:36.979458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:36.654611Z","time spent":"324.836956ms","remote":"127.0.0.1:58614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":31,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-24T16:17:36.979496Z","caller":"traceutil/trace.go:171","msg":"trace[1639817080] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26127; }","duration":"350.056066ms","start":"2026-08-24T16:17:36.62942Z","end":"2026-08-24T16:17:36.979476Z","steps":["trace[1639817080] 'agreement among raft nodes before linearized reading' (duration: 349.868062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:36.979546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:36.629372Z","time spent":"350.15441ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-08-24T16:17:37.28691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.504815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:37.28699Z","caller":"traceutil/trace.go:171","msg":"trace[1051358879] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26127; }","duration":"118.632769ms","start":"2026-08-24T16:17:37.168336Z","end":"2026-08-24T16:17:37.286968Z","steps":["trace[1051358879] 'count revisions from in-memory index tree' (duration: 118.421223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:37.287018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.434498ms","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:6"} {"level":"info","ts":"2026-08-24T16:17:37.287128Z","caller":"traceutil/trace.go:171","msg":"trace[1873377010] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:26127; }","duration":"117.55058ms","start":"2026-08-24T16:17:37.169533Z","end":"2026-08-24T16:17:37.287084Z","steps":["trace[1873377010] 'count revisions from in-memory index tree' (duration: 117.367526ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:37.548757Z","caller":"traceutil/trace.go:171","msg":"trace[1775696458] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"253.750937ms","start":"2026-08-24T16:17:37.294976Z","end":"2026-08-24T16:17:37.548727Z","steps":["trace[1775696458] 'process raft request' (duration: 253.581353ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:37.619707Z","caller":"traceutil/trace.go:171","msg":"trace[19790079] linearizableReadLoop","detail":"{readStateIndex:26981; appliedIndex:26980; }","duration":"251.313987ms","start":"2026-08-24T16:17:37.368369Z","end":"2026-08-24T16:17:37.619683Z","steps":["trace[19790079] 'read index received' (duration: 180.475474ms)","trace[19790079] 'applied index is now lower than readState.Index' (duration: 70.837213ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:37.619711Z","caller":"traceutil/trace.go:171","msg":"trace[1349427403] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"319.329138ms","start":"2026-08-24T16:17:37.300355Z","end":"2026-08-24T16:17:37.619684Z","steps":["trace[1349427403] 'process raft request' (duration: 310.181058ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:37.619772Z","caller":"traceutil/trace.go:171","msg":"trace[1552452080] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"181.391388ms","start":"2026-08-24T16:17:37.438341Z","end":"2026-08-24T16:17:37.619733Z","steps":["trace[1552452080] 'process raft request' (duration: 181.256135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:37.619884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:37.300336Z","time spent":"319.453581ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:17:37.620007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.623534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:37.620049Z","caller":"traceutil/trace.go:171","msg":"trace[1647035617] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26131; }","duration":"251.710457ms","start":"2026-08-24T16:17:37.36833Z","end":"2026-08-24T16:17:37.62004Z","steps":["trace[1647035617] 'agreement among raft nodes before linearized reading' (duration: 251.43192ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:37.931085Z","caller":"traceutil/trace.go:171","msg":"trace[321089791] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"136.999317ms","start":"2026-08-24T16:17:37.794049Z","end":"2026-08-24T16:17:37.931048Z","steps":["trace[321089791] 'process raft request' (duration: 136.694509ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:38.159826Z","caller":"traceutil/trace.go:171","msg":"trace[59395070] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"228.28933ms","start":"2026-08-24T16:17:37.931504Z","end":"2026-08-24T16:17:38.159794Z","steps":["trace[59395070] 'process raft request' (duration: 205.211483ms)","trace[59395070] 'compare' (duration: 22.695428ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:38.160106Z","caller":"traceutil/trace.go:171","msg":"trace[1228160208] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"225.371508ms","start":"2026-08-24T16:17:37.934711Z","end":"2026-08-24T16:17:38.160082Z","steps":["trace[1228160208] 'process raft request' (duration: 225.201863ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:38.16023Z","caller":"traceutil/trace.go:171","msg":"trace[1391801452] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"160.062275ms","start":"2026-08-24T16:17:38.000159Z","end":"2026-08-24T16:17:38.160221Z","steps":["trace[1391801452] 'process raft request' (duration: 159.851929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:38.431452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.020092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:38.431898Z","caller":"traceutil/trace.go:171","msg":"trace[2094526020] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"265.588693ms","start":"2026-08-24T16:17:38.166268Z","end":"2026-08-24T16:17:38.431857Z","steps":["trace[2094526020] 'process raft request' (duration: 140.487734ms)","trace[2094526020] 'compare' (duration: 123.713035ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:38.432227Z","caller":"traceutil/trace.go:171","msg":"trace[862642168] linearizableReadLoop","detail":"{readStateIndex:26988; appliedIndex:26987; }","duration":"265.208725ms","start":"2026-08-24T16:17:38.166996Z","end":"2026-08-24T16:17:38.432205Z","steps":["trace[862642168] 'read index received' (duration: 139.774067ms)","trace[862642168] 'applied index is now lower than readState.Index' (duration: 125.432848ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:38.444373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.359678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18556"} {"level":"info","ts":"2026-08-24T16:17:38.444417Z","caller":"traceutil/trace.go:171","msg":"trace[1710147564] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26136; }","duration":"277.43465ms","start":"2026-08-24T16:17:38.166973Z","end":"2026-08-24T16:17:38.444407Z","steps":["trace[1710147564] 'agreement among raft nodes before linearized reading' (duration: 265.305636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:38.82221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.1013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:38.822292Z","caller":"traceutil/trace.go:171","msg":"trace[1710846241] linearizableReadLoop","detail":"{readStateIndex:26991; appliedIndex:26990; }","duration":"326.1939ms","start":"2026-08-24T16:17:38.496085Z","end":"2026-08-24T16:17:38.822279Z","steps":["trace[1710846241] 'read index received' (duration: 14.911373ms)","trace[1710846241] 'applied index is now lower than readState.Index' (duration: 311.281457ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:38.822293Z","caller":"traceutil/trace.go:171","msg":"trace[796080670] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"343.357129ms","start":"2026-08-24T16:17:38.478924Z","end":"2026-08-24T16:17:38.822281Z","steps":["trace[796080670] 'process raft request' (duration: 174.103855ms)","trace[796080670] 'compare' (duration: 168.907016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:38.822347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:38.478886Z","time spent":"343.438341ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:17:38.822381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.299933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:38.822399Z","caller":"traceutil/trace.go:171","msg":"trace[1190634708] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26138; }","duration":"326.346664ms","start":"2026-08-24T16:17:38.496047Z","end":"2026-08-24T16:17:38.822393Z","steps":["trace[1190634708] 'agreement among raft nodes before linearized reading' (duration: 326.306383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:38.822425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:38.49603Z","time spent":"326.390285ms","remote":"127.0.0.1:58786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-24T16:17:38.822545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.464229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:38.82256Z","caller":"traceutil/trace.go:171","msg":"trace[1791630068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26138; }","duration":"179.50848ms","start":"2026-08-24T16:17:38.643047Z","end":"2026-08-24T16:17:38.822556Z","steps":["trace[1791630068] 'agreement among raft nodes before linearized reading' (duration: 179.482759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:38.822681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.109569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-24T16:17:38.822701Z","caller":"traceutil/trace.go:171","msg":"trace[1609039870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26138; }","duration":"113.13026ms","start":"2026-08-24T16:17:38.709565Z","end":"2026-08-24T16:17:38.822695Z","steps":["trace[1609039870] 'agreement among raft nodes before linearized reading' (duration: 113.044617ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:39.111548Z","caller":"traceutil/trace.go:171","msg":"trace[1922305765] linearizableReadLoop","detail":"{readStateIndex:26992; appliedIndex:26991; }","duration":"284.173828ms","start":"2026-08-24T16:17:38.827333Z","end":"2026-08-24T16:17:39.111507Z","steps":["trace[1922305765] 'read index received' (duration: 284.024405ms)","trace[1922305765] 'applied index is now lower than readState.Index' (duration: 148.433µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:39.111672Z","caller":"traceutil/trace.go:171","msg":"trace[1974398043] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"285.868342ms","start":"2026-08-24T16:17:38.825766Z","end":"2026-08-24T16:17:39.111634Z","steps":["trace[1974398043] 'process raft request' (duration: 285.559954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:39.111783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.422934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-24T16:17:39.111985Z","caller":"traceutil/trace.go:171","msg":"trace[997887886] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26139; }","duration":"284.64276ms","start":"2026-08-24T16:17:38.827318Z","end":"2026-08-24T16:17:39.111961Z","steps":["trace[997887886] 'agreement among raft nodes before linearized reading' (duration: 284.326472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:39.132788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.834141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:39.132878Z","caller":"traceutil/trace.go:171","msg":"trace[149015071] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26140; }","duration":"297.974505ms","start":"2026-08-24T16:17:38.834884Z","end":"2026-08-24T16:17:39.132859Z","steps":["trace[149015071] 'agreement among raft nodes before linearized reading' (duration: 297.79592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:39.13297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.26821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T16:17:39.13305Z","caller":"traceutil/trace.go:171","msg":"trace[1256203747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26140; }","duration":"150.409413ms","start":"2026-08-24T16:17:38.982624Z","end":"2026-08-24T16:17:39.133034Z","steps":["trace[1256203747] 'agreement among raft nodes before linearized reading' (duration: 150.105456ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:39.133221Z","caller":"traceutil/trace.go:171","msg":"trace[1764405363] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"149.564392ms","start":"2026-08-24T16:17:38.983156Z","end":"2026-08-24T16:17:39.132721Z","steps":["trace[1764405363] 'process raft request' (duration: 149.288026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:39.462418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.605753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:39.462507Z","caller":"traceutil/trace.go:171","msg":"trace[1696065186] linearizableReadLoop","detail":"{readStateIndex:26994; appliedIndex:26993; }","duration":"315.199934ms","start":"2026-08-24T16:17:39.147294Z","end":"2026-08-24T16:17:39.462494Z","steps":["trace[1696065186] 'read index received' (duration: 35.369854ms)","trace[1696065186] 'applied index is now lower than readState.Index' (duration: 279.82906ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:39.462685Z","caller":"traceutil/trace.go:171","msg":"trace[1063769194] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"324.968909ms","start":"2026-08-24T16:17:39.13759Z","end":"2026-08-24T16:17:39.462559Z","steps":["trace[1063769194] 'process raft request' (duration: 45.15163ms)","trace[1063769194] 'compare' (duration: 278.578288ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:39.462788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.491211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-24T16:17:39.462818Z","caller":"traceutil/trace.go:171","msg":"trace[1269118069] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26141; }","duration":"315.550573ms","start":"2026-08-24T16:17:39.147258Z","end":"2026-08-24T16:17:39.462808Z","steps":["trace[1269118069] 'agreement among raft nodes before linearized reading' (duration: 315.4252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:39.462853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:39.147241Z","time spent":"315.603254ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5675,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-24T16:17:39.462977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.442909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:39.463129Z","caller":"traceutil/trace.go:171","msg":"trace[1872485642] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26141; }","duration":"177.649064ms","start":"2026-08-24T16:17:39.285457Z","end":"2026-08-24T16:17:39.463106Z","steps":["trace[1872485642] 'agreement among raft nodes before linearized reading' (duration: 177.296754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:39.463332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:39.13757Z","time spent":"325.242766ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:17:39.887036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.702921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:39.887204Z","caller":"traceutil/trace.go:171","msg":"trace[439542041] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"260.797794ms","start":"2026-08-24T16:17:39.626364Z","end":"2026-08-24T16:17:39.887161Z","steps":["trace[439542041] 'process raft request' (duration: 113.806746ms)","trace[439542041] 'compare' (duration: 146.578038ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:39.887335Z","caller":"traceutil/trace.go:171","msg":"trace[661837630] linearizableReadLoop","detail":"{readStateIndex:26997; appliedIndex:26996; }","duration":"240.383824ms","start":"2026-08-24T16:17:39.646856Z","end":"2026-08-24T16:17:39.88724Z","steps":["trace[661837630] 'read index received' (duration: 93.322844ms)","trace[661837630] 'applied index is now lower than readState.Index' (duration: 147.05943ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:39.887476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.590659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:39.887559Z","caller":"traceutil/trace.go:171","msg":"trace[80286131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26145; }","duration":"240.723162ms","start":"2026-08-24T16:17:39.646815Z","end":"2026-08-24T16:17:39.887538Z","steps":["trace[80286131] 'agreement among raft nodes before linearized reading' (duration: 240.546538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:40.18488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.613157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:40.185011Z","caller":"traceutil/trace.go:171","msg":"trace[741104427] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"244.120117ms","start":"2026-08-24T16:17:39.940878Z","end":"2026-08-24T16:17:40.184998Z","steps":["trace[741104427] 'process raft request' (duration: 89.328365ms)","trace[741104427] 'compare' (duration: 154.414433ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:40.48362Z","caller":"traceutil/trace.go:171","msg":"trace[1999479876] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"244.227559ms","start":"2026-08-24T16:17:40.239362Z","end":"2026-08-24T16:17:40.483589Z","steps":["trace[1999479876] 'process raft request' (duration: 243.688356ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:40.673978Z","caller":"traceutil/trace.go:171","msg":"trace[1471794922] linearizableReadLoop","detail":"{readStateIndex:27005; appliedIndex:27004; }","duration":"130.753361ms","start":"2026-08-24T16:17:40.543203Z","end":"2026-08-24T16:17:40.673956Z","steps":["trace[1471794922] 'read index received' (duration: 108.090524ms)","trace[1471794922] 'applied index is now lower than readState.Index' (duration: 22.662097ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:40.674076Z","caller":"traceutil/trace.go:171","msg":"trace[437293563] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"159.574752ms","start":"2026-08-24T16:17:40.51449Z","end":"2026-08-24T16:17:40.674065Z","steps":["trace[437293563] 'process raft request' (duration: 136.845454ms)","trace[437293563] 'compare' (duration: 22.529853ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:40.674361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.148571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:17:40.674393Z","caller":"traceutil/trace.go:171","msg":"trace[2108010853] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26152; }","duration":"131.227183ms","start":"2026-08-24T16:17:40.543155Z","end":"2026-08-24T16:17:40.674382Z","steps":["trace[2108010853] 'agreement among raft nodes before linearized reading' (duration: 131.152661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:47.360657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.89336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-24T16:17:47.360737Z","caller":"traceutil/trace.go:171","msg":"trace[528416530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26250; }","duration":"208.042254ms","start":"2026-08-24T16:17:47.15268Z","end":"2026-08-24T16:17:47.360722Z","steps":["trace[528416530] 'range keys from in-memory index tree' (duration: 207.687886ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:47.361654Z","caller":"traceutil/trace.go:171","msg":"trace[762624645] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"204.911406ms","start":"2026-08-24T16:17:47.156685Z","end":"2026-08-24T16:17:47.361597Z","steps":["trace[762624645] 'process raft request' (duration: 191.608202ms)","trace[762624645] 'compare' (duration: 12.644917ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:47.361732Z","caller":"traceutil/trace.go:171","msg":"trace[453624136] linearizableReadLoop","detail":"{readStateIndex:27105; appliedIndex:27104; }","duration":"152.945926ms","start":"2026-08-24T16:17:47.208749Z","end":"2026-08-24T16:17:47.361695Z","steps":["trace[453624136] 'read index received' (duration: 139.467318ms)","trace[453624136] 'applied index is now lower than readState.Index' (duration: 13.474498ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:47.36187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.09737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7eae130-397c-4ee3-913d-58ca59b9689c\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-24T16:17:47.361903Z","caller":"traceutil/trace.go:171","msg":"trace[1934525191] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7eae130-397c-4ee3-913d-58ca59b9689c; range_end:; response_count:1; response_revision:26251; }","duration":"153.177111ms","start":"2026-08-24T16:17:47.208714Z","end":"2026-08-24T16:17:47.361892Z","steps":["trace[1934525191] 'agreement among raft nodes before linearized reading' (duration: 153.067918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:47.601873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.950875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:17:47.602077Z","caller":"traceutil/trace.go:171","msg":"trace[1470556452] linearizableReadLoop","detail":"{readStateIndex:27109; appliedIndex:27107; }","duration":"107.622781ms","start":"2026-08-24T16:17:47.494414Z","end":"2026-08-24T16:17:47.602036Z","steps":["trace[1470556452] 'read index received' (duration: 46.461µs)","trace[1470556452] 'applied index is now lower than readState.Index' (duration: 107.57531ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:47.602109Z","caller":"traceutil/trace.go:171","msg":"trace[419043635] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"223.817419ms","start":"2026-08-24T16:17:47.378245Z","end":"2026-08-24T16:17:47.602062Z","steps":["trace[419043635] 'process raft request' (duration: 114.601187ms)","trace[419043635] 'compare' (duration: 108.837463ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:47.602231Z","caller":"traceutil/trace.go:171","msg":"trace[391776505] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"209.363977ms","start":"2026-08-24T16:17:47.392838Z","end":"2026-08-24T16:17:47.602202Z","steps":["trace[391776505] 'process raft request' (duration: 209.128452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:47.602253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.838138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T16:17:47.602294Z","caller":"traceutil/trace.go:171","msg":"trace[609662752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26255; }","duration":"107.920241ms","start":"2026-08-24T16:17:47.494364Z","end":"2026-08-24T16:17:47.602284Z","steps":["trace[609662752] 'agreement among raft nodes before linearized reading' (duration: 107.744216ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:48.019262Z","caller":"traceutil/trace.go:171","msg":"trace[516766392] linearizableReadLoop","detail":"{readStateIndex:27113; appliedIndex:27112; }","duration":"101.351985ms","start":"2026-08-24T16:17:47.917885Z","end":"2026-08-24T16:17:48.019237Z","steps":["trace[516766392] 'read index received' (duration: 101.121929ms)","trace[516766392] 'applied index is now lower than readState.Index' (duration: 228.796µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:48.019373Z","caller":"traceutil/trace.go:171","msg":"trace[12692420] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"104.266488ms","start":"2026-08-24T16:17:47.915086Z","end":"2026-08-24T16:17:48.019353Z","steps":["trace[12692420] 'process raft request' (duration: 103.888519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:48.019487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.586921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7eae130-397c-4ee3-913d-58ca59b9689c\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-24T16:17:48.019539Z","caller":"traceutil/trace.go:171","msg":"trace[1360466090] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7eae130-397c-4ee3-913d-58ca59b9689c; range_end:; response_count:1; response_revision:26259; }","duration":"101.679063ms","start":"2026-08-24T16:17:47.917843Z","end":"2026-08-24T16:17:48.019522Z","steps":["trace[1360466090] 'agreement among raft nodes before linearized reading' (duration: 101.491778ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:48.279883Z","caller":"traceutil/trace.go:171","msg":"trace[982586967] linearizableReadLoop","detail":"{readStateIndex:27114; appliedIndex:27113; }","duration":"162.999637ms","start":"2026-08-24T16:17:48.116867Z","end":"2026-08-24T16:17:48.279866Z","steps":["trace[982586967] 'read index received' (duration: 162.952536ms)","trace[982586967] 'applied index is now lower than readState.Index' (duration: 46.491µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:48.280036Z","caller":"traceutil/trace.go:171","msg":"trace[384357528] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"251.769778ms","start":"2026-08-24T16:17:48.028229Z","end":"2026-08-24T16:17:48.279998Z","steps":["trace[384357528] 'process raft request' (duration: 251.499522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:48.280254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.382767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-24T16:17:48.280282Z","caller":"traceutil/trace.go:171","msg":"trace[14843476] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26260; }","duration":"163.427208ms","start":"2026-08-24T16:17:48.116847Z","end":"2026-08-24T16:17:48.280275Z","steps":["trace[14843476] 'agreement among raft nodes before linearized reading' (duration: 163.238453ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:48.600623Z","caller":"traceutil/trace.go:171","msg":"trace[1887021364] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"386.15262ms","start":"2026-08-24T16:17:48.21443Z","end":"2026-08-24T16:17:48.600582Z","steps":["trace[1887021364] 'process raft request' (duration: 335.267526ms)","trace[1887021364] 'compare' (duration: 50.546495ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:48.600803Z","caller":"traceutil/trace.go:171","msg":"trace[455629328] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"385.36995ms","start":"2026-08-24T16:17:48.215405Z","end":"2026-08-24T16:17:48.600774Z","steps":["trace[455629328] 'process raft request' (duration: 385.122874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:48.600936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:48.215396Z","time spent":"385.472283ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:17:48.601107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:48.214408Z","time spent":"386.413715ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:17:48.835215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.557787ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-24T16:17:48.835437Z","caller":"traceutil/trace.go:171","msg":"trace[1939606980] linearizableReadLoop","detail":"{readStateIndex:27117; appliedIndex:27114; }","duration":"555.480475ms","start":"2026-08-24T16:17:48.279936Z","end":"2026-08-24T16:17:48.835417Z","steps":["trace[1939606980] 'read index received' (duration: 269.710996ms)","trace[1939606980] 'applied index is now lower than readState.Index' (duration: 285.768399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:48.835635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.116987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-24T16:17:48.835674Z","caller":"traceutil/trace.go:171","msg":"trace[1737424401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26262; }","duration":"587.192928ms","start":"2026-08-24T16:17:48.248471Z","end":"2026-08-24T16:17:48.835664Z","steps":["trace[1737424401] 'agreement among raft nodes before linearized reading' (duration: 587.059075ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:48.835694Z","caller":"traceutil/trace.go:171","msg":"trace[1789912972] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"135.968321ms","start":"2026-08-24T16:17:48.699701Z","end":"2026-08-24T16:17:48.83567Z","steps":["trace[1789912972] 'process raft request' (duration: 135.622283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:48.835712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:48.248454Z","time spent":"587.24934ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T16:17:48.836936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.77273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-24T16:17:48.836972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.819726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-24T16:17:48.836995Z","caller":"traceutil/trace.go:171","msg":"trace[519664936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26263; }","duration":"303.841292ms","start":"2026-08-24T16:17:48.533139Z","end":"2026-08-24T16:17:48.83698Z","steps":["trace[519664936] 'agreement among raft nodes before linearized reading' (duration: 302.59961ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:48.837018Z","caller":"traceutil/trace.go:171","msg":"trace[234806130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26263; }","duration":"619.891308ms","start":"2026-08-24T16:17:48.217115Z","end":"2026-08-24T16:17:48.837006Z","steps":["trace[234806130] 'agreement among raft nodes before linearized reading' (duration: 618.466882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:48.837027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:48.533077Z","time spent":"303.943134ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T16:17:48.837054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:48.217107Z","time spent":"619.938879ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T16:17:48.837078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.121979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-24T16:17:48.83718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.240643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7eae130-397c-4ee3-913d-58ca59b9689c\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-24T16:17:48.837193Z","caller":"traceutil/trace.go:171","msg":"trace[1101139642] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26263; }","duration":"103.345945ms","start":"2026-08-24T16:17:48.733816Z","end":"2026-08-24T16:17:48.837162Z","steps":["trace[1101139642] 'agreement among raft nodes before linearized reading' (duration: 101.894878ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:48.83728Z","caller":"traceutil/trace.go:171","msg":"trace[330552480] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7eae130-397c-4ee3-913d-58ca59b9689c; range_end:; response_count:1; response_revision:26263; }","duration":"470.378417ms","start":"2026-08-24T16:17:48.366874Z","end":"2026-08-24T16:17:48.837253Z","steps":["trace[330552480] 'agreement among raft nodes before linearized reading' (duration: 468.777807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:48.837458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:48.366856Z","time spent":"470.536671ms","remote":"127.0.0.1:58502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7eae130-397c-4ee3-913d-58ca59b9689c\" "} {"level":"info","ts":"2026-08-24T16:17:49.093068Z","caller":"traceutil/trace.go:171","msg":"trace[1373870021] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"211.661675ms","start":"2026-08-24T16:17:48.88137Z","end":"2026-08-24T16:17:49.093032Z","steps":["trace[1373870021] 'process raft request' (duration: 144.73989ms)","trace[1373870021] 'compare' (duration: 66.662938ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:51.211394Z","caller":"traceutil/trace.go:171","msg":"trace[1784961494] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"112.254498ms","start":"2026-08-24T16:17:51.099101Z","end":"2026-08-24T16:17:51.211355Z","steps":["trace[1784961494] 'process raft request' (duration: 111.830598ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.439064Z","caller":"traceutil/trace.go:171","msg":"trace[362668118] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"344.204199ms","start":"2026-08-24T16:17:55.094829Z","end":"2026-08-24T16:17:55.439033Z","steps":["trace[362668118] 'process raft request' (duration: 344.009195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.439266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:55.09477Z","time spent":"344.398866ms","remote":"127.0.0.1:58502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10630,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:55.528918Z","caller":"traceutil/trace.go:171","msg":"trace[866680155] linearizableReadLoop","detail":"{readStateIndex:27186; appliedIndex:27184; }","duration":"406.879059ms","start":"2026-08-24T16:17:55.122019Z","end":"2026-08-24T16:17:55.528898Z","steps":["trace[866680155] 'read index received' (duration: 316.816046ms)","trace[866680155] 'applied index is now lower than readState.Index' (duration: 90.062003ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:55.529004Z","caller":"traceutil/trace.go:171","msg":"trace[464649751] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"108.475474ms","start":"2026-08-24T16:17:55.42052Z","end":"2026-08-24T16:17:55.528996Z","steps":["trace[464649751] 'process raft request' (duration: 108.345721ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.529084Z","caller":"traceutil/trace.go:171","msg":"trace[183876109] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"307.609886ms","start":"2026-08-24T16:17:55.221341Z","end":"2026-08-24T16:17:55.528951Z","steps":["trace[183876109] 'process raft request' (duration: 307.496863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.529206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.174677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-24T16:17:55.529235Z","caller":"traceutil/trace.go:171","msg":"trace[1551498804] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26332; }","duration":"407.244909ms","start":"2026-08-24T16:17:55.121981Z","end":"2026-08-24T16:17:55.529226Z","steps":["trace[1551498804] 'agreement among raft nodes before linearized reading' (duration: 407.124446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.529254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:55.121964Z","time spent":"407.283839ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-08-24T16:17:55.529446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:55.221301Z","time spent":"307.901232ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:55.529589Z","caller":"traceutil/trace.go:171","msg":"trace[666219985] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"421.776492ms","start":"2026-08-24T16:17:55.107791Z","end":"2026-08-24T16:17:55.529567Z","steps":["trace[666219985] 'process raft request' (duration: 420.92227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.529685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:55.107766Z","time spent":"421.869474ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:17:55.662228Z","caller":"traceutil/trace.go:171","msg":"trace[1083296606] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"215.805449ms","start":"2026-08-24T16:17:55.446404Z","end":"2026-08-24T16:17:55.662209Z","steps":["trace[1083296606] 'process raft request' (duration: 215.666785ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.662242Z","caller":"traceutil/trace.go:171","msg":"trace[1632762587] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"216.057534ms","start":"2026-08-24T16:17:55.44614Z","end":"2026-08-24T16:17:55.662197Z","steps":["trace[1632762587] 'process raft request' (duration: 215.788618ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.662447Z","caller":"traceutil/trace.go:171","msg":"trace[826330799] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"215.771127ms","start":"2026-08-24T16:17:55.446663Z","end":"2026-08-24T16:17:55.662434Z","steps":["trace[826330799] 'process raft request' (duration: 215.507911ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.662562Z","caller":"traceutil/trace.go:171","msg":"trace[495872779] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"213.349337ms","start":"2026-08-24T16:17:55.449194Z","end":"2026-08-24T16:17:55.662543Z","steps":["trace[495872779] 'process raft request' (duration: 213.179423ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.66288Z","caller":"traceutil/trace.go:171","msg":"trace[657558954] linearizableReadLoop","detail":"{readStateIndex:27193; appliedIndex:27188; }","duration":"133.872418ms","start":"2026-08-24T16:17:55.528972Z","end":"2026-08-24T16:17:55.662844Z","steps":["trace[657558954] 'read index received' (duration: 132.948975ms)","trace[657558954] 'applied index is now lower than readState.Index' (duration: 921.933µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:17:55.662965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.055313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T16:17:55.662981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.328786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-24T16:17:55.663011Z","caller":"traceutil/trace.go:171","msg":"trace[1223489593] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26337; }","duration":"425.148755ms","start":"2026-08-24T16:17:55.237848Z","end":"2026-08-24T16:17:55.662997Z","steps":["trace[1223489593] 'agreement among raft nodes before linearized reading' (duration: 425.026622ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.663024Z","caller":"traceutil/trace.go:171","msg":"trace[2015950745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26337; }","duration":"217.410007ms","start":"2026-08-24T16:17:55.445604Z","end":"2026-08-24T16:17:55.663014Z","steps":["trace[2015950745] 'agreement among raft nodes before linearized reading' (duration: 217.246663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.663066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:55.237833Z","time spent":"425.198926ms","remote":"127.0.0.1:57254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"info","ts":"2026-08-24T16:17:55.662892Z","caller":"traceutil/trace.go:171","msg":"trace[2049114458] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"161.169851ms","start":"2026-08-24T16:17:55.501689Z","end":"2026-08-24T16:17:55.662859Z","steps":["trace[2049114458] 'process raft request' (duration: 160.811752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.663115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.816738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:17:55.663152Z","caller":"traceutil/trace.go:171","msg":"trace[1687444483] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:26337; }","duration":"470.91608ms","start":"2026-08-24T16:17:55.192225Z","end":"2026-08-24T16:17:55.663141Z","steps":["trace[1687444483] 'agreement among raft nodes before linearized reading' (duration: 470.802547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.663182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.516368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a7eae130-397c-4ee3-913d-58ca59b9689c\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-24T16:17:55.66324Z","caller":"traceutil/trace.go:171","msg":"trace[229312035] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a7eae130-397c-4ee3-913d-58ca59b9689c; range_end:; response_count:1; response_revision:26337; }","duration":"131.612481ms","start":"2026-08-24T16:17:55.531619Z","end":"2026-08-24T16:17:55.663231Z","steps":["trace[229312035] 'agreement among raft nodes before linearized reading' (duration: 131.495428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:17:55.663192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:17:55.192208Z","time spent":"470.971352ms","remote":"127.0.0.1:53788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-08-24T16:17:55.800417Z","caller":"traceutil/trace.go:171","msg":"trace[1430841564] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"132.699839ms","start":"2026-08-24T16:17:55.667688Z","end":"2026-08-24T16:17:55.800388Z","steps":["trace[1430841564] 'process raft request' (duration: 78.159035ms)","trace[1430841564] 'compare' (duration: 54.424461ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:17:55.802128Z","caller":"traceutil/trace.go:171","msg":"trace[28912527] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"134.419562ms","start":"2026-08-24T16:17:55.667688Z","end":"2026-08-24T16:17:55.802108Z","steps":["trace[28912527] 'process raft request' (duration: 134.269379ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.802377Z","caller":"traceutil/trace.go:171","msg":"trace[553918586] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"115.622702ms","start":"2026-08-24T16:17:55.686721Z","end":"2026-08-24T16:17:55.802343Z","steps":["trace[553918586] 'process raft request' (duration: 115.350075ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:17:55.802635Z","caller":"traceutil/trace.go:171","msg":"trace[1864454948] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"115.827137ms","start":"2026-08-24T16:17:55.686797Z","end":"2026-08-24T16:17:55.802624Z","steps":["trace[1864454948] 'process raft request' (duration: 115.492239ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:04.8546Z","caller":"traceutil/trace.go:171","msg":"trace[1180430261] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"196.954988ms","start":"2026-08-24T16:18:04.657621Z","end":"2026-08-24T16:18:04.854576Z","steps":["trace[1180430261] 'process raft request' (duration: 196.822824ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:05.055731Z","caller":"traceutil/trace.go:171","msg":"trace[40091590] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"164.481374ms","start":"2026-08-24T16:18:04.891226Z","end":"2026-08-24T16:18:05.055708Z","steps":["trace[40091590] 'process raft request' (duration: 164.416452ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:05.05589Z","caller":"traceutil/trace.go:171","msg":"trace[846428908] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"397.692259ms","start":"2026-08-24T16:18:04.658171Z","end":"2026-08-24T16:18:05.055863Z","steps":["trace[846428908] 'process raft request' (duration: 306.917678ms)","trace[846428908] 'compare' (duration: 90.190816ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:05.055984Z","caller":"traceutil/trace.go:171","msg":"trace[194966852] linearizableReadLoop","detail":"{readStateIndex:27268; appliedIndex:27266; }","duration":"397.668468ms","start":"2026-08-24T16:18:04.6583Z","end":"2026-08-24T16:18:05.055968Z","steps":["trace[194966852] 'read index received' (duration: 196.121376ms)","trace[194966852] 'applied index is now lower than readState.Index' (duration: 201.545952ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:18:05.056031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:04.658162Z","time spent":"397.784561ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:18:05.056113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.809741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:05.056151Z","caller":"traceutil/trace.go:171","msg":"trace[899446154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26411; }","duration":"397.864402ms","start":"2026-08-24T16:18:04.658278Z","end":"2026-08-24T16:18:05.056142Z","steps":["trace[899446154] 'agreement among raft nodes before linearized reading' (duration: 397.77703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.056181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:04.658273Z","time spent":"397.898864ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T16:18:05.056386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.205979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-24T16:18:05.056446Z","caller":"traceutil/trace.go:171","msg":"trace[531771412] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26411; }","duration":"186.307801ms","start":"2026-08-24T16:18:04.870125Z","end":"2026-08-24T16:18:05.056433Z","steps":["trace[531771412] 'agreement among raft nodes before linearized reading' (duration: 186.114755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.056524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.765767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-24T16:18:05.056582Z","caller":"traceutil/trace.go:171","msg":"trace[711755618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26411; }","duration":"123.855219ms","start":"2026-08-24T16:18:04.932717Z","end":"2026-08-24T16:18:05.056572Z","steps":["trace[711755618] 'agreement among raft nodes before linearized reading' (duration: 123.715216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.056586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.007933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-24T16:18:05.056635Z","caller":"traceutil/trace.go:171","msg":"trace[1608144750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26411; }","duration":"122.089234ms","start":"2026-08-24T16:18:04.934532Z","end":"2026-08-24T16:18:05.056621Z","steps":["trace[1608144750] 'agreement among raft nodes before linearized reading' (duration: 121.89799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.05685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.276595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-24T16:18:05.056892Z","caller":"traceutil/trace.go:171","msg":"trace[785349832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26411; }","duration":"121.328796ms","start":"2026-08-24T16:18:04.935551Z","end":"2026-08-24T16:18:05.05688Z","steps":["trace[785349832] 'agreement among raft nodes before linearized reading' (duration: 121.190762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.447214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.324182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:18:05.447389Z","caller":"traceutil/trace.go:171","msg":"trace[611758068] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"383.691478ms","start":"2026-08-24T16:18:05.063672Z","end":"2026-08-24T16:18:05.447364Z","steps":["trace[611758068] 'process raft request' (duration: 151.141501ms)","trace[611758068] 'compare' (duration: 232.200919ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:05.447401Z","caller":"traceutil/trace.go:171","msg":"trace[1844413822] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"383.441702ms","start":"2026-08-24T16:18:05.063943Z","end":"2026-08-24T16:18:05.447385Z","steps":["trace[1844413822] 'process raft request' (duration: 383.35639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.447507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:05.063654Z","time spent":"383.78978ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:18:05.447507Z","caller":"traceutil/trace.go:171","msg":"trace[611975117] linearizableReadLoop","detail":"{readStateIndex:27271; appliedIndex:27269; }","duration":"383.432672ms","start":"2026-08-24T16:18:05.06404Z","end":"2026-08-24T16:18:05.447472Z","steps":["trace[611975117] 'read index received' (duration: 150.70677ms)","trace[611975117] 'applied index is now lower than readState.Index' (duration: 232.724232ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:05.44754Z","caller":"traceutil/trace.go:171","msg":"trace[1971121925] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"383.36559ms","start":"2026-08-24T16:18:05.064138Z","end":"2026-08-24T16:18:05.447504Z","steps":["trace[1971121925] 'process raft request' (duration: 383.214056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.447573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:05.063925Z","time spent":"383.611617ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:18:05.447675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.629977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-24T16:18:05.447697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:05.064122Z","time spent":"383.498984ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:18:05.447714Z","caller":"traceutil/trace.go:171","msg":"trace[1625845692] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26414; }","duration":"383.690318ms","start":"2026-08-24T16:18:05.064014Z","end":"2026-08-24T16:18:05.447704Z","steps":["trace[1625845692] 'agreement among raft nodes before linearized reading' (duration: 383.571025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.44775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:05.064005Z","time spent":"383.72657ms","remote":"127.0.0.1:58502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-24T16:18:05.651975Z","caller":"traceutil/trace.go:171","msg":"trace[726483476] transaction","detail":"{read_only:false; response_revision:26415; number_of_response:1; }","duration":"205.639094ms","start":"2026-08-24T16:18:05.446308Z","end":"2026-08-24T16:18:05.651947Z","steps":["trace[726483476] 'process raft request' (duration: 149.444859ms)","trace[726483476] 'compare' (duration: 56.022971ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:05.652086Z","caller":"traceutil/trace.go:171","msg":"trace[1519483607] linearizableReadLoop","detail":"{readStateIndex:27273; appliedIndex:27272; }","duration":"204.459985ms","start":"2026-08-24T16:18:05.447599Z","end":"2026-08-24T16:18:05.652059Z","steps":["trace[1519483607] 'read index received' (duration: 148.178797ms)","trace[1519483607] 'applied index is now lower than readState.Index' (duration: 56.278628ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:18:05.652288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.256178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"warn","ts":"2026-08-24T16:18:05.652319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.521341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18556"} {"level":"info","ts":"2026-08-24T16:18:05.652336Z","caller":"traceutil/trace.go:171","msg":"trace[711433756] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26417; }","duration":"463.340411ms","start":"2026-08-24T16:18:05.188984Z","end":"2026-08-24T16:18:05.652325Z","steps":["trace[711433756] 'agreement among raft nodes before linearized reading' (duration: 463.179057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.65237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:05.188969Z","time spent":"463.390782ms","remote":"127.0.0.1:52054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7937,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-08-24T16:18:05.65237Z","caller":"traceutil/trace.go:171","msg":"trace[1207519341] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26417; }","duration":"203.627503ms","start":"2026-08-24T16:18:05.448725Z","end":"2026-08-24T16:18:05.652352Z","steps":["trace[1207519341] 'agreement among raft nodes before linearized reading' (duration: 203.471179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:05.652372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.097312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:18:05.652483Z","caller":"traceutil/trace.go:171","msg":"trace[2123798741] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26417; }","duration":"283.275116ms","start":"2026-08-24T16:18:05.369186Z","end":"2026-08-24T16:18:05.652461Z","steps":["trace[2123798741] 'agreement among raft nodes before linearized reading' (duration: 282.908697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:07.709679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.202677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:18:07.709866Z","caller":"traceutil/trace.go:171","msg":"trace[1725724347] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"131.012397ms","start":"2026-08-24T16:18:07.578839Z","end":"2026-08-24T16:18:07.709852Z","steps":["trace[1725724347] 'process raft request' (duration: 130.978586ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:07.709869Z","caller":"traceutil/trace.go:171","msg":"trace[1824640429] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"254.957318ms","start":"2026-08-24T16:18:07.454871Z","end":"2026-08-24T16:18:07.709829Z","steps":["trace[1824640429] 'process raft request' (duration: 152.202737ms)","trace[1824640429] 'compare' (duration: 102.079433ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:07.710051Z","caller":"traceutil/trace.go:171","msg":"trace[2047181488] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"254.354233ms","start":"2026-08-24T16:18:07.455675Z","end":"2026-08-24T16:18:07.710029Z","steps":["trace[2047181488] 'process raft request' (duration: 254.091286ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:07.829538Z","caller":"traceutil/trace.go:171","msg":"trace[222772418] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"195.133971ms","start":"2026-08-24T16:18:07.634374Z","end":"2026-08-24T16:18:07.829508Z","steps":["trace[222772418] 'process raft request' (duration: 189.787318ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:07.829553Z","caller":"traceutil/trace.go:171","msg":"trace[482664642] linearizableReadLoop","detail":"{readStateIndex:27290; appliedIndex:27289; }","duration":"184.492035ms","start":"2026-08-24T16:18:07.645036Z","end":"2026-08-24T16:18:07.829529Z","steps":["trace[482664642] 'read index received' (duration: 179.459359ms)","trace[482664642] 'applied index is now lower than readState.Index' (duration: 5.020755ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:07.829806Z","caller":"traceutil/trace.go:171","msg":"trace[1339304943] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"167.658704ms","start":"2026-08-24T16:18:07.662124Z","end":"2026-08-24T16:18:07.829783Z","steps":["trace[1339304943] 'process raft request' (duration: 167.545281ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:07.829832Z","caller":"traceutil/trace.go:171","msg":"trace[2126750026] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"173.46342ms","start":"2026-08-24T16:18:07.656324Z","end":"2026-08-24T16:18:07.829787Z","steps":["trace[2126750026] 'process raft request' (duration: 173.128801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:07.829823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.759332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:07.829951Z","caller":"traceutil/trace.go:171","msg":"trace[1886673868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26433; }","duration":"184.955587ms","start":"2026-08-24T16:18:07.644984Z","end":"2026-08-24T16:18:07.829939Z","steps":["trace[1886673868] 'agreement among raft nodes before linearized reading' (duration: 184.623277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:09.925017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.811676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:18:09.925198Z","caller":"traceutil/trace.go:171","msg":"trace[75776449] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"206.984718ms","start":"2026-08-24T16:18:09.718173Z","end":"2026-08-24T16:18:09.925157Z","steps":["trace[75776449] 'process raft request' (duration: 100.933135ms)","trace[75776449] 'compare' (duration: 105.657822ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:09.957562Z","caller":"traceutil/trace.go:171","msg":"trace[955126634] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"122.988507ms","start":"2026-08-24T16:18:09.834541Z","end":"2026-08-24T16:18:09.95753Z","steps":["trace[955126634] 'process raft request' (duration: 122.831663ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:10.065657Z","caller":"traceutil/trace.go:171","msg":"trace[2115659336] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"230.528738ms","start":"2026-08-24T16:18:09.835106Z","end":"2026-08-24T16:18:10.065635Z","steps":["trace[2115659336] 'process raft request' (duration: 229.45525ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:10.065913Z","caller":"traceutil/trace.go:171","msg":"trace[2114269201] linearizableReadLoop","detail":"{readStateIndex:27307; appliedIndex:27304; }","duration":"214.160368ms","start":"2026-08-24T16:18:09.851718Z","end":"2026-08-24T16:18:10.065878Z","steps":["trace[2114269201] 'read index received' (duration: 105.51255ms)","trace[2114269201] 'applied index is now lower than readState.Index' (duration: 108.646738ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:10.066Z","caller":"traceutil/trace.go:171","msg":"trace[631251570] transaction","detail":"{read_only:false; response_revision:26449; number_of_response:1; }","duration":"206.030834ms","start":"2026-08-24T16:18:09.859948Z","end":"2026-08-24T16:18:10.065979Z","steps":["trace[631251570] 'process raft request' (duration: 205.83934ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:10.066012Z","caller":"traceutil/trace.go:171","msg":"trace[1521467412] transaction","detail":"{read_only:false; response_revision:26448; number_of_response:1; }","duration":"221.911921ms","start":"2026-08-24T16:18:09.844046Z","end":"2026-08-24T16:18:10.065957Z","steps":["trace[1521467412] 'process raft request' (duration: 221.517941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:10.066198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.447915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-24T16:18:10.066248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.744311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T16:18:10.066304Z","caller":"traceutil/trace.go:171","msg":"trace[1536886663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26449; }","duration":"210.843024ms","start":"2026-08-24T16:18:09.855447Z","end":"2026-08-24T16:18:10.06629Z","steps":["trace[1536886663] 'agreement among raft nodes before linearized reading' (duration: 210.653289ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:10.066313Z","caller":"traceutil/trace.go:171","msg":"trace[2136404398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26449; }","duration":"214.574588ms","start":"2026-08-24T16:18:09.85171Z","end":"2026-08-24T16:18:10.066285Z","steps":["trace[2136404398] 'agreement among raft nodes before linearized reading' (duration: 214.273601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:18.519773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.782182ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-24T16:18:22.353052Z","caller":"traceutil/trace.go:171","msg":"trace[616677692] transaction","detail":"{read_only:false; response_revision:26548; number_of_response:1; }","duration":"182.714061ms","start":"2026-08-24T16:18:22.170308Z","end":"2026-08-24T16:18:22.353022Z","steps":["trace[616677692] 'process raft request' (duration: 182.557017ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:22.523317Z","caller":"traceutil/trace.go:171","msg":"trace[2128004614] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"337.844972ms","start":"2026-08-24T16:18:22.185448Z","end":"2026-08-24T16:18:22.523293Z","steps":["trace[2128004614] 'process raft request' (duration: 337.671558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:22.523432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:22.185415Z","time spent":"337.956714ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:18:22.52358Z","caller":"traceutil/trace.go:171","msg":"trace[1566118574] linearizableReadLoop","detail":"{readStateIndex:27413; appliedIndex:27409; }","duration":"321.165005ms","start":"2026-08-24T16:18:22.202392Z","end":"2026-08-24T16:18:22.523557Z","steps":["trace[1566118574] 'read index received' (duration: 150.513176ms)","trace[1566118574] 'applied index is now lower than readState.Index' (duration: 170.650809ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:22.523661Z","caller":"traceutil/trace.go:171","msg":"trace[511371555] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"329.807321ms","start":"2026-08-24T16:18:22.193831Z","end":"2026-08-24T16:18:22.523638Z","steps":["trace[511371555] 'process raft request' (duration: 329.407271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:22.523804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.40249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-24T16:18:22.523803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:22.193773Z","time spent":"329.945744ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:18:22.523846Z","caller":"traceutil/trace.go:171","msg":"trace[1562239905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26551; }","duration":"321.480262ms","start":"2026-08-24T16:18:22.202354Z","end":"2026-08-24T16:18:22.523835Z","steps":["trace[1562239905] 'agreement among raft nodes before linearized reading' (duration: 321.321878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:22.523879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:22.202337Z","time spent":"321.535733ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T16:18:22.523797Z","caller":"traceutil/trace.go:171","msg":"trace[1048847292] transaction","detail":"{read_only:false; response_revision:26551; number_of_response:1; }","duration":"326.126309ms","start":"2026-08-24T16:18:22.197633Z","end":"2026-08-24T16:18:22.52376Z","steps":["trace[1048847292] 'process raft request' (duration: 325.831181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:22.524081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.466252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-24T16:18:22.524115Z","caller":"traceutil/trace.go:171","msg":"trace[1948340873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26551; }","duration":"321.519373ms","start":"2026-08-24T16:18:22.202587Z","end":"2026-08-24T16:18:22.524107Z","steps":["trace[1948340873] 'agreement among raft nodes before linearized reading' (duration: 321.262147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:22.524107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.30071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:22.524242Z","caller":"traceutil/trace.go:171","msg":"trace[925715751] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:26551; }","duration":"208.504866ms","start":"2026-08-24T16:18:22.315709Z","end":"2026-08-24T16:18:22.524214Z","steps":["trace[925715751] 'agreement among raft nodes before linearized reading' (duration: 208.209658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:22.524292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:22.197615Z","time spent":"326.412756ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:18:22.524177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:22.20258Z","time spent":"321.549204ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T16:18:25.804778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.90763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:18:25.80486Z","caller":"traceutil/trace.go:171","msg":"trace[731933741] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26574; }","duration":"144.069704ms","start":"2026-08-24T16:18:25.660769Z","end":"2026-08-24T16:18:25.804839Z","steps":["trace[731933741] 'count revisions from in-memory index tree' (duration: 143.812688ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:25.984442Z","caller":"traceutil/trace.go:171","msg":"trace[1656666336] transaction","detail":"{read_only:false; response_revision:26576; number_of_response:1; }","duration":"144.445662ms","start":"2026-08-24T16:18:25.839949Z","end":"2026-08-24T16:18:25.984395Z","steps":["trace[1656666336] 'process raft request' (duration: 103.154429ms)","trace[1656666336] 'compare' (duration: 40.988946ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:32.228798Z","caller":"traceutil/trace.go:171","msg":"trace[1664769235] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"149.438278ms","start":"2026-08-24T16:18:32.079329Z","end":"2026-08-24T16:18:32.228767Z","steps":["trace[1664769235] 'process raft request' (duration: 99.245382ms)","trace[1664769235] 'compare' (duration: 49.723404ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:32.829463Z","caller":"traceutil/trace.go:171","msg":"trace[581156632] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"276.943428ms","start":"2026-08-24T16:18:32.552494Z","end":"2026-08-24T16:18:32.829437Z","steps":["trace[581156632] 'process raft request' (duration: 276.760953ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:32.976388Z","caller":"traceutil/trace.go:171","msg":"trace[300033211] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"421.460043ms","start":"2026-08-24T16:18:32.554897Z","end":"2026-08-24T16:18:32.976357Z","steps":["trace[300033211] 'process raft request' (duration: 360.075037ms)","trace[300033211] 'compare' (duration: 61.12831ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:32.976476Z","caller":"traceutil/trace.go:171","msg":"trace[447323593] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27485; }","duration":"387.185075ms","start":"2026-08-24T16:18:32.589261Z","end":"2026-08-24T16:18:32.976447Z","steps":["trace[447323593] 'read index received' (duration: 240.041195ms)","trace[447323593] 'applied index is now lower than readState.Index' (duration: 147.14158ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:32.976575Z","caller":"traceutil/trace.go:171","msg":"trace[1586620170] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"419.816132ms","start":"2026-08-24T16:18:32.556751Z","end":"2026-08-24T16:18:32.976567Z","steps":["trace[1586620170] 'process raft request' (duration: 419.533395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:32.976617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:32.554877Z","time spent":"421.605426ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:18:32.976662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:32.556716Z","time spent":"419.878874ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:18:32.976835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.574215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T16:18:32.976862Z","caller":"traceutil/trace.go:171","msg":"trace[1858145724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26624; }","duration":"387.631426ms","start":"2026-08-24T16:18:32.589225Z","end":"2026-08-24T16:18:32.976856Z","steps":["trace[1858145724] 'agreement among raft nodes before linearized reading' (duration: 387.525434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:32.976881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:32.589209Z","time spent":"387.668168ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T16:18:32.992113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.54121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-24T16:18:32.992159Z","caller":"traceutil/trace.go:171","msg":"trace[1990295291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26625; }","duration":"382.623792ms","start":"2026-08-24T16:18:32.609523Z","end":"2026-08-24T16:18:32.992147Z","steps":["trace[1990295291] 'agreement among raft nodes before linearized reading' (duration: 382.450728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:32.99225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.689076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:32.992278Z","caller":"traceutil/trace.go:171","msg":"trace[219106247] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:26625; }","duration":"229.749847ms","start":"2026-08-24T16:18:32.76252Z","end":"2026-08-24T16:18:32.99227Z","steps":["trace[219106247] 'agreement among raft nodes before linearized reading' (duration: 229.696936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:32.992278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.134304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:32.992348Z","caller":"traceutil/trace.go:171","msg":"trace[1795859808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26625; }","duration":"349.248157ms","start":"2026-08-24T16:18:32.643084Z","end":"2026-08-24T16:18:32.992333Z","steps":["trace[1795859808] 'agreement among raft nodes before linearized reading' (duration: 349.117163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:32.992384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:32.643069Z","time spent":"349.309718ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T16:18:32.992187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:32.609506Z","time spent":"382.674943ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T16:18:32.992442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.172085ms","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:2083"} {"level":"info","ts":"2026-08-24T16:18:32.992509Z","caller":"traceutil/trace.go:171","msg":"trace[284900737] 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:26625; }","duration":"373.281418ms","start":"2026-08-24T16:18:32.619209Z","end":"2026-08-24T16:18:32.99249Z","steps":["trace[284900737] 'agreement among raft nodes before linearized reading' (duration: 373.053802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:32.992561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:32.619191Z","time spent":"373.34438ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-24T16:18:32.992373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.865682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:32.992872Z","caller":"traceutil/trace.go:171","msg":"trace[580874549] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26625; }","duration":"262.418375ms","start":"2026-08-24T16:18:32.730426Z","end":"2026-08-24T16:18:32.992845Z","steps":["trace[580874549] 'agreement among raft nodes before linearized reading' (duration: 261.749619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:43.109962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.720924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-hwpvf\" ","response":"range_response_count:1 size:3396"} {"level":"info","ts":"2026-08-24T16:18:43.11003Z","caller":"traceutil/trace.go:171","msg":"trace[1338176158] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-hwpvf; range_end:; response_count:1; response_revision:26699; }","duration":"155.835657ms","start":"2026-08-24T16:18:42.95418Z","end":"2026-08-24T16:18:43.110016Z","steps":["trace[1338176158] 'range keys from in-memory index tree' (duration: 155.577622ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.157069Z","caller":"traceutil/trace.go:171","msg":"trace[1858994192] linearizableReadLoop","detail":"{readStateIndex:27568; appliedIndex:27565; }","duration":"133.193892ms","start":"2026-08-24T16:18:43.023854Z","end":"2026-08-24T16:18:43.157048Z","steps":["trace[1858994192] 'read index received' (duration: 12.082593ms)","trace[1858994192] 'applied index is now lower than readState.Index' (duration: 121.110449ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:18:43.157148Z","caller":"traceutil/trace.go:171","msg":"trace[2087253175] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"144.854583ms","start":"2026-08-24T16:18:43.012254Z","end":"2026-08-24T16:18:43.157108Z","steps":["trace[2087253175] 'process raft request' (duration: 144.621927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:43.157213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.337706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:43.157215Z","caller":"traceutil/trace.go:171","msg":"trace[1097215936] transaction","detail":"{read_only:false; response_revision:26702; number_of_response:1; }","duration":"133.806376ms","start":"2026-08-24T16:18:43.023369Z","end":"2026-08-24T16:18:43.157176Z","steps":["trace[1097215936] 'process raft request' (duration: 133.629092ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.15725Z","caller":"traceutil/trace.go:171","msg":"trace[721485079] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26702; }","duration":"133.421687ms","start":"2026-08-24T16:18:43.023817Z","end":"2026-08-24T16:18:43.157238Z","steps":["trace[721485079] 'agreement among raft nodes before linearized reading' (duration: 133.344435ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.367455Z","caller":"traceutil/trace.go:171","msg":"trace[1376038542] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"250.121887ms","start":"2026-08-24T16:18:43.117281Z","end":"2026-08-24T16:18:43.367403Z","steps":["trace[1376038542] 'process raft request' (duration: 196.882125ms)","trace[1376038542] 'compare' (duration: 53.054277ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:18:43.367607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.533123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T16:18:43.367703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.892967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-24T16:18:43.367695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.787272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.61\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-24T16:18:43.367769Z","caller":"traceutil/trace.go:171","msg":"trace[1754304511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26704; }","duration":"332.978419ms","start":"2026-08-24T16:18:43.034773Z","end":"2026-08-24T16:18:43.367752Z","steps":["trace[1754304511] 'agreement among raft nodes before linearized reading' (duration: 332.744203ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.367739Z","caller":"traceutil/trace.go:171","msg":"trace[122666841] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26704; }","duration":"269.733988ms","start":"2026-08-24T16:18:43.097974Z","end":"2026-08-24T16:18:43.367708Z","steps":["trace[122666841] 'agreement among raft nodes before linearized reading' (duration: 269.476081ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.367793Z","caller":"traceutil/trace.go:171","msg":"trace[2078038746] range","detail":"{range_begin:/registry/masterleases/199.19.213.61; range_end:; response_count:1; response_revision:26704; }","duration":"222.933876ms","start":"2026-08-24T16:18:43.144836Z","end":"2026-08-24T16:18:43.36777Z","steps":["trace[2078038746] 'agreement among raft nodes before linearized reading' (duration: 222.723601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:43.36782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:43.034766Z","time spent":"333.03914ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T16:18:43.367829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.60721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T16:18:43.367913Z","caller":"traceutil/trace.go:171","msg":"trace[1571947702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26704; }","duration":"328.716132ms","start":"2026-08-24T16:18:43.039178Z","end":"2026-08-24T16:18:43.367894Z","steps":["trace[1571947702] 'agreement among raft nodes before linearized reading' (duration: 328.483446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:18:43.367961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:18:43.03917Z","time spent":"328.781276ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T16:18:43.36747Z","caller":"traceutil/trace.go:171","msg":"trace[1207780238] linearizableReadLoop","detail":"{readStateIndex:27569; appliedIndex:27568; }","duration":"210.288651ms","start":"2026-08-24T16:18:43.157135Z","end":"2026-08-24T16:18:43.367424Z","steps":["trace[1207780238] 'read index received' (duration: 157.146331ms)","trace[1207780238] 'applied index is now lower than readState.Index' (duration: 53.13917ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:18:43.415166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.448169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:18:43.415242Z","caller":"traceutil/trace.go:171","msg":"trace[1113412930] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26705; }","duration":"251.583173ms","start":"2026-08-24T16:18:43.16364Z","end":"2026-08-24T16:18:43.415223Z","steps":["trace[1113412930] 'agreement among raft nodes before linearized reading' (duration: 251.399489ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.489298Z","caller":"traceutil/trace.go:171","msg":"trace[178299765] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"116.30564ms","start":"2026-08-24T16:18:43.372963Z","end":"2026-08-24T16:18:43.489269Z","steps":["trace[178299765] 'process raft request' (duration: 116.120776ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.489413Z","caller":"traceutil/trace.go:171","msg":"trace[2343223] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"115.868969ms","start":"2026-08-24T16:18:43.3735Z","end":"2026-08-24T16:18:43.489369Z","steps":["trace[2343223] 'process raft request' (duration: 115.619703ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.489522Z","caller":"traceutil/trace.go:171","msg":"trace[2030573054] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"114.983936ms","start":"2026-08-24T16:18:43.374513Z","end":"2026-08-24T16:18:43.489497Z","steps":["trace[2030573054] 'process raft request' (duration: 114.656428ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:43.489662Z","caller":"traceutil/trace.go:171","msg":"trace[754621221] transaction","detail":"{read_only:false; response_revision:26706; number_of_response:1; }","duration":"118.131725ms","start":"2026-08-24T16:18:43.371491Z","end":"2026-08-24T16:18:43.489622Z","steps":["trace[754621221] 'process raft request' (duration: 117.457028ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:18:44.169449Z","caller":"traceutil/trace.go:171","msg":"trace[1757407546] transaction","detail":"{read_only:false; response_revision:26713; number_of_response:1; }","duration":"113.650454ms","start":"2026-08-24T16:18:44.055775Z","end":"2026-08-24T16:18:44.169425Z","steps":["trace[1757407546] 'process raft request' (duration: 113.025227ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:19:05.645756Z","caller":"traceutil/trace.go:171","msg":"trace[2146491495] linearizableReadLoop","detail":"{readStateIndex:27886; appliedIndex:27885; }","duration":"174.492325ms","start":"2026-08-24T16:19:05.471238Z","end":"2026-08-24T16:19:05.645731Z","steps":["trace[2146491495] 'read index received' (duration: 174.340581ms)","trace[2146491495] 'applied index is now lower than readState.Index' (duration: 150.734µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:19:05.645879Z","caller":"traceutil/trace.go:171","msg":"trace[2013059612] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"209.690445ms","start":"2026-08-24T16:19:05.43615Z","end":"2026-08-24T16:19:05.64584Z","steps":["trace[2013059612] 'process raft request' (duration: 209.425109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:19:05.64592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.654159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:19:05.646066Z","caller":"traceutil/trace.go:171","msg":"trace[1008112905] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:27014; }","duration":"174.838643ms","start":"2026-08-24T16:19:05.471204Z","end":"2026-08-24T16:19:05.646042Z","steps":["trace[1008112905] 'agreement among raft nodes before linearized reading' (duration: 174.658409ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:19:05.662993Z","caller":"traceutil/trace.go:171","msg":"trace[1717149360] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"169.966522ms","start":"2026-08-24T16:19:05.49299Z","end":"2026-08-24T16:19:05.662956Z","steps":["trace[1717149360] 'process raft request' (duration: 169.795467ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:19:05.914306Z","caller":"traceutil/trace.go:171","msg":"trace[606516258] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"246.539377ms","start":"2026-08-24T16:19:05.667743Z","end":"2026-08-24T16:19:05.914282Z","steps":["trace[606516258] 'process raft request' (duration: 148.296689ms)","trace[606516258] 'compare' (duration: 97.785307ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:19:05.986093Z","caller":"traceutil/trace.go:171","msg":"trace[1262834082] linearizableReadLoop","detail":"{readStateIndex:27890; appliedIndex:27888; }","duration":"159.113049ms","start":"2026-08-24T16:19:05.826952Z","end":"2026-08-24T16:19:05.986065Z","steps":["trace[1262834082] 'read index received' (duration: 159.006676ms)","trace[1262834082] 'applied index is now lower than readState.Index' (duration: 105.283µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:19:05.986211Z","caller":"traceutil/trace.go:171","msg":"trace[1548686200] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"318.381233ms","start":"2026-08-24T16:19:05.667785Z","end":"2026-08-24T16:19:05.986166Z","steps":["trace[1548686200] 'process raft request' (duration: 318.058915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:19:05.986265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.284604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:19:05.986246Z","caller":"traceutil/trace.go:171","msg":"trace[972463160] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"299.200994ms","start":"2026-08-24T16:19:05.687011Z","end":"2026-08-24T16:19:05.986212Z","steps":["trace[972463160] 'process raft request' (duration: 298.997199ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:19:05.986299Z","caller":"traceutil/trace.go:171","msg":"trace[304331776] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:27018; }","duration":"159.378296ms","start":"2026-08-24T16:19:05.826911Z","end":"2026-08-24T16:19:05.98629Z","steps":["trace[304331776] 'agreement among raft nodes before linearized reading' (duration: 159.288793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:19:05.986435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:19:05.667762Z","time spent":"318.543849ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:19:06.006264Z","caller":"traceutil/trace.go:171","msg":"trace[1023730586] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"148.600917ms","start":"2026-08-24T16:19:05.857636Z","end":"2026-08-24T16:19:06.006237Z","steps":["trace[1023730586] 'process raft request' (duration: 148.343551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:19:06.006354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.319726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-24T16:19:06.006425Z","caller":"traceutil/trace.go:171","msg":"trace[1306669488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27019; }","duration":"161.440549ms","start":"2026-08-24T16:19:05.844968Z","end":"2026-08-24T16:19:06.006408Z","steps":["trace[1306669488] 'agreement among raft nodes before linearized reading' (duration: 161.179552ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:21:29.887158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25483} {"level":"info","ts":"2026-08-24T16:21:30.042795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25483,"took":"151.08057ms","hash":3631691037,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":35225600,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-24T16:21:30.042853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3631691037,"revision":25483,"compact-revision":22227} {"level":"info","ts":"2026-08-24T16:22:32.461436Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1484a2210d3c2b8","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T16:22:32.4661Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-24T16:22:32.466187Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-24T16:26:29.892795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28376} {"level":"info","ts":"2026-08-24T16:26:30.038947Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28376,"took":"142.965195ms","hash":2857790042,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":33914880,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-24T16:26:30.039026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2857790042,"revision":28376,"compact-revision":25483} {"level":"info","ts":"2026-08-24T16:31:29.900274Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30913} {"level":"info","ts":"2026-08-24T16:31:30.032846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30913,"took":"128.511003ms","hash":1638096896,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":31334400,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T16:31:30.032943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1638096896,"revision":30913,"compact-revision":28376} {"level":"info","ts":"2026-08-24T16:36:29.909163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33210} {"level":"info","ts":"2026-08-24T16:36:30.050612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33210,"took":"138.108855ms","hash":2071707966,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T16:36:30.050678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2071707966,"revision":33210,"compact-revision":30913} {"level":"warn","ts":"2026-08-24T16:40:01.575919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.583621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:676"} {"level":"info","ts":"2026-08-24T16:40:01.576119Z","caller":"traceutil/trace.go:171","msg":"trace[887723257] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:37120; }","duration":"100.748305ms","start":"2026-08-24T16:40:01.475276Z","end":"2026-08-24T16:40:01.576024Z","steps":["trace[887723257] 'agreement among raft nodes before linearized reading' (duration: 52.78215ms)","trace[887723257] 'range keys from in-memory index tree' (duration: 47.671328ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:40:01.57621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.875808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1148"} {"level":"info","ts":"2026-08-24T16:40:01.576293Z","caller":"traceutil/trace.go:171","msg":"trace[2512438] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:37120; }","duration":"100.987471ms","start":"2026-08-24T16:40:01.475285Z","end":"2026-08-24T16:40:01.576273Z","steps":["trace[2512438] 'agreement among raft nodes before linearized reading' (duration: 52.76206ms)","trace[2512438] 'range keys from in-memory index tree' (duration: 48.017256ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:40:01.576301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.900259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:895"} {"level":"warn","ts":"2026-08-24T16:40:01.576327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.326969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7489"} {"level":"warn","ts":"2026-08-24T16:40:01.576467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.998181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5054"} {"level":"info","ts":"2026-08-24T16:40:01.576514Z","caller":"traceutil/trace.go:171","msg":"trace[1459814847] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:37120; }","duration":"101.577555ms","start":"2026-08-24T16:40:01.474909Z","end":"2026-08-24T16:40:01.576487Z","steps":["trace[1459814847] 'agreement among raft nodes before linearized reading' (duration: 53.110908ms)","trace[1459814847] 'range keys from in-memory index tree' (duration: 48.000785ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:40:01.576421Z","caller":"traceutil/trace.go:171","msg":"trace[758246328] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:37120; }","duration":"101.245717ms","start":"2026-08-24T16:40:01.475151Z","end":"2026-08-24T16:40:01.576397Z","steps":["trace[758246328] 'agreement among raft nodes before linearized reading' (duration: 52.900493ms)","trace[758246328] 'range keys from in-memory index tree' (duration: 47.77771ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:40:01.576653Z","caller":"traceutil/trace.go:171","msg":"trace[1292587386] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:37120; }","duration":"101.38158ms","start":"2026-08-24T16:40:01.475148Z","end":"2026-08-24T16:40:01.576529Z","steps":["trace[1292587386] 'agreement among raft nodes before linearized reading' (duration: 52.878633ms)","trace[1292587386] 'range keys from in-memory index tree' (duration: 48.066307ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:40:01.576386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.423451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21474"} {"level":"info","ts":"2026-08-24T16:40:01.576845Z","caller":"traceutil/trace.go:171","msg":"trace[1186457654] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:37120; }","duration":"101.922943ms","start":"2026-08-24T16:40:01.474901Z","end":"2026-08-24T16:40:01.576824Z","steps":["trace[1186457654] 'agreement among raft nodes before linearized reading' (duration: 53.1655ms)","trace[1186457654] 'range keys from in-memory index tree' (duration: 47.703668ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:41:29.91811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35480} {"level":"info","ts":"2026-08-24T16:41:30.054418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35480,"took":"133.080369ms","hash":2257150894,"current-db-size-bytes":59465728,"current-db-size":"60 MB","current-db-size-in-use-bytes":32342016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T16:41:30.054478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2257150894,"revision":35480,"compact-revision":33210} {"level":"info","ts":"2026-08-24T16:43:38.63271Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1484a2210d3c2b8","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T16:43:38.637109Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-24T16:43:38.637268Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-24T16:46:29.923553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37811} {"level":"info","ts":"2026-08-24T16:46:30.046024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37811,"took":"118.260737ms","hash":3522410863,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":33546240,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-24T16:46:30.046081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3522410863,"revision":37811,"compact-revision":35480} {"level":"info","ts":"2026-08-24T16:51:29.928826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40134} {"level":"info","ts":"2026-08-24T16:51:30.053896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40134,"took":"121.56725ms","hash":3870133058,"current-db-size-bytes":61153280,"current-db-size":"61 MB","current-db-size-in-use-bytes":31735808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T16:51:30.053959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3870133058,"revision":40134,"compact-revision":37811}