{"level":"warn","ts":"2026-07-14T22:56:17.106742Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T22:56:17.106831Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-07-14T22:56:17.106898Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T22:56:17.106913Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-14T22:56:17.106932Z","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-07-14T22:56:17.107517Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-07-14T22:56:17.107584Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-07-14T22:56:17.111554Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.764123ms"} {"level":"info","ts":"2026-07-14T22:56:17.117322Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-07-14T22:56:17.117396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T22:56:17.117414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-07-14T22:56:17.117428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T22:56:17.117441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-07-14T22:56:17.117483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-07-14T22:56:17.122057Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T22:56:17.124054Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T22:56:17.125371Z","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-07-14T22:56:17.128235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T22:56:17.128587Z","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-07-14T22:56:17.128659Z","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-07-14T22:56:17.128545Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T22:56:17.128672Z","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-07-14T22:56:17.129597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-07-14T22:56:17.129717Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-14T22:56:17.133387Z","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-07-14T22:56:17.133539Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-14T22:56:17.133613Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-14T22:56:17.133701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T22:56:17.13378Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T22:56:17.417672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T22:56:17.417746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T22:56:17.417783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-07-14T22:56:17.417806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-07-14T22:56:17.417833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-14T22:56:17.417859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-07-14T22:56:17.417877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-14T22:56:17.41887Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:56:17.419988Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:56:17.420095Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:56:17.420616Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:56:17.42001Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T22:56:17.420663Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T22:56:17.420762Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T22:56:17.420768Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:56:17.420824Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:56:17.423882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-07-14T22:56:17.42401Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T22:56:52.209256Z","caller":"traceutil/trace.go:171","msg":"trace[1388520972] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"103.178224ms","start":"2026-07-14T22:56:52.106043Z","end":"2026-07-14T22:56:52.209221Z","steps":["trace[1388520972] 'process raft request' (duration: 102.701752ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:56:58.523292Z","caller":"traceutil/trace.go:171","msg":"trace[1299942493] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"245.625681ms","start":"2026-07-14T22:56:58.277618Z","end":"2026-07-14T22:56:58.523244Z","steps":["trace[1299942493] 'process raft request' (duration: 245.375335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:56:58.869564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.454843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:56:58.869706Z","caller":"traceutil/trace.go:171","msg":"trace[1220028637] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"331.489859ms","start":"2026-07-14T22:56:58.538198Z","end":"2026-07-14T22:56:58.869688Z","steps":["trace[1220028637] 'process raft request' (duration: 42.335589ms)","trace[1220028637] 'compare' (duration: 288.308ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:56:58.869768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:56:58.538175Z","time spent":"331.563031ms","remote":"127.0.0.1:45450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T22:56:59.075263Z","caller":"traceutil/trace.go:171","msg":"trace[553909530] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"144.397546ms","start":"2026-07-14T22:56:58.930501Z","end":"2026-07-14T22:56:59.074899Z","steps":["trace[553909530] 'process raft request' (duration: 124.829426ms)","trace[553909530] 'compare' (duration: 19.408917ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:56:59.078385Z","caller":"traceutil/trace.go:171","msg":"trace[432293018] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"128.772882ms","start":"2026-07-14T22:56:58.949584Z","end":"2026-07-14T22:56:59.078357Z","steps":["trace[432293018] 'process raft request' (duration: 128.212789ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:57:13.939101Z","caller":"traceutil/trace.go:171","msg":"trace[870563163] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"110.159155ms","start":"2026-07-14T22:57:13.828917Z","end":"2026-07-14T22:57:13.939076Z","steps":["trace[870563163] 'process raft request' (duration: 109.967481ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:57:14.07744Z","caller":"traceutil/trace.go:171","msg":"trace[1600712859] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"246.779869ms","start":"2026-07-14T22:57:13.830637Z","end":"2026-07-14T22:57:14.077417Z","steps":["trace[1600712859] 'process raft request' (duration: 246.636146ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.023177Z","caller":"traceutil/trace.go:171","msg":"trace[1135090799] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"239.355004ms","start":"2026-07-14T22:58:25.783783Z","end":"2026-07-14T22:58:26.023138Z","steps":["trace[1135090799] 'read index received' (duration: 193.804786ms)","trace[1135090799] 'applied index is now lower than readState.Index' (duration: 45.547088ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:26.023225Z","caller":"traceutil/trace.go:171","msg":"trace[1856047798] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"118.436232ms","start":"2026-07-14T22:58:25.904761Z","end":"2026-07-14T22:58:26.023198Z","steps":["trace[1856047798] 'process raft request' (duration: 118.303718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:26.023566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.784307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-14T22:58:26.023578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.755483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0714a0fb-15e5-4d0e-9fae-95dba9b0c1c5\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-14T22:58:26.02361Z","caller":"traceutil/trace.go:171","msg":"trace[2017956243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"166.933831ms","start":"2026-07-14T22:58:25.856664Z","end":"2026-07-14T22:58:26.023598Z","steps":["trace[2017956243] 'agreement among raft nodes before linearized reading' (duration: 166.693245ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.023647Z","caller":"traceutil/trace.go:171","msg":"trace[2046775065] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0714a0fb-15e5-4d0e-9fae-95dba9b0c1c5; range_end:; response_count:1; response_revision:1851; }","duration":"239.912877ms","start":"2026-07-14T22:58:25.783719Z","end":"2026-07-14T22:58:26.023632Z","steps":["trace[2046775065] 'agreement among raft nodes before linearized reading' (duration: 239.521778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:26.366152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.855489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T22:58:26.366413Z","caller":"traceutil/trace.go:171","msg":"trace[1109916043] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"298.006512ms","start":"2026-07-14T22:58:26.068372Z","end":"2026-07-14T22:58:26.366379Z","steps":["trace[1109916043] 'process raft request' (duration: 172.786444ms)","trace[1109916043] 'compare' (duration: 124.708525ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:26.366559Z","caller":"traceutil/trace.go:171","msg":"trace[1226050482] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1922; }","duration":"189.366918ms","start":"2026-07-14T22:58:26.177167Z","end":"2026-07-14T22:58:26.366533Z","steps":["trace[1226050482] 'read index received' (duration: 63.934116ms)","trace[1226050482] 'applied index is now lower than readState.Index' (duration: 125.431112ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:26.366562Z","caller":"traceutil/trace.go:171","msg":"trace[251736485] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"297.323844ms","start":"2026-07-14T22:58:26.069206Z","end":"2026-07-14T22:58:26.366529Z","steps":["trace[251736485] 'process raft request' (duration: 297.168201ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.366633Z","caller":"traceutil/trace.go:171","msg":"trace[988601961] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"293.970443ms","start":"2026-07-14T22:58:26.072577Z","end":"2026-07-14T22:58:26.366547Z","steps":["trace[988601961] 'process raft request' (duration: 293.880161ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.366603Z","caller":"traceutil/trace.go:171","msg":"trace[739926137] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"298.098945ms","start":"2026-07-14T22:58:26.06844Z","end":"2026-07-14T22:58:26.366539Z","steps":["trace[739926137] 'process raft request' (duration: 297.837358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:26.36685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.849447ms","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-07-14T22:58:26.366912Z","caller":"traceutil/trace.go:171","msg":"trace[171238588] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"133.937199ms","start":"2026-07-14T22:58:26.23296Z","end":"2026-07-14T22:58:26.366897Z","steps":["trace[171238588] 'agreement among raft nodes before linearized reading' (duration: 133.738324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:26.366878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.683556ms","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-07-14T22:58:26.367026Z","caller":"traceutil/trace.go:171","msg":"trace[1860399830] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"189.906181ms","start":"2026-07-14T22:58:26.177107Z","end":"2026-07-14T22:58:26.367014Z","steps":["trace[1860399830] 'agreement among raft nodes before linearized reading' (duration: 189.547982ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:37.724054Z","caller":"traceutil/trace.go:171","msg":"trace[28194544] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"134.159726ms","start":"2026-07-14T22:58:37.589865Z","end":"2026-07-14T22:58:37.724025Z","steps":["trace[28194544] 'process raft request' (duration: 133.93103ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:58.15365Z","caller":"traceutil/trace.go:171","msg":"trace[110317198] transaction","detail":"{read_only:false; response_revision:3756; number_of_response:1; }","duration":"107.780561ms","start":"2026-07-14T23:02:58.045842Z","end":"2026-07-14T23:02:58.153623Z","steps":["trace[110317198] 'process raft request' (duration: 107.551985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:22.860057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.091233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-07-14T23:04:22.860182Z","caller":"traceutil/trace.go:171","msg":"trace[42951871] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5113; }","duration":"153.692481ms","start":"2026-07-14T23:04:22.70647Z","end":"2026-07-14T23:04:22.860163Z","steps":["trace[42951871] 'read index received' (duration: 24.464165ms)","trace[42951871] 'applied index is now lower than readState.Index' (duration: 129.226646ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:04:22.86026Z","caller":"traceutil/trace.go:171","msg":"trace[2106684531] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4928; }","duration":"231.635061ms","start":"2026-07-14T23:04:22.628613Z","end":"2026-07-14T23:04:22.860248Z","steps":["trace[2106684531] 'process raft request' (duration: 102.270872ms)","trace[2106684531] 'compare' (duration: 129.042982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:04:22.86059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.097061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea50d6e1-67f6-4f99-92b5-83b4b2082cb5\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-14T23:04:22.860675Z","caller":"traceutil/trace.go:171","msg":"trace[1010500863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ea50d6e1-67f6-4f99-92b5-83b4b2082cb5; range_end:; response_count:1; response_revision:4928; }","duration":"154.247684ms","start":"2026-07-14T23:04:22.706408Z","end":"2026-07-14T23:04:22.860655Z","steps":["trace[1010500863] 'agreement among raft nodes before linearized reading' (duration: 153.991048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:25.046942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.821841ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:04:25.046981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.54113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea50d6e1-67f6-4f99-92b5-83b4b2082cb5\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-07-14T23:04:25.047103Z","caller":"traceutil/trace.go:171","msg":"trace[284978408] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4966; }","duration":"198.964614ms","start":"2026-07-14T23:04:24.848091Z","end":"2026-07-14T23:04:25.047056Z","steps":["trace[284978408] 'range keys from in-memory index tree' (duration: 198.806901ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:25.047133Z","caller":"traceutil/trace.go:171","msg":"trace[1883746711] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ea50d6e1-67f6-4f99-92b5-83b4b2082cb5; range_end:; response_count:1; response_revision:4966; }","duration":"171.740105ms","start":"2026-07-14T23:04:24.875368Z","end":"2026-07-14T23:04:25.047108Z","steps":["trace[1883746711] 'range keys from in-memory index tree' (duration: 171.343305ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:36.242383Z","caller":"traceutil/trace.go:171","msg":"trace[1934185236] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"108.861537ms","start":"2026-07-14T23:04:36.13349Z","end":"2026-07-14T23:04:36.242351Z","steps":["trace[1934185236] 'process raft request' (duration: 24.747041ms)","trace[1934185236] 'compare' (duration: 83.905511ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:04:43.931875Z","caller":"traceutil/trace.go:171","msg":"trace[134497773] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"115.747939ms","start":"2026-07-14T23:04:43.816099Z","end":"2026-07-14T23:04:43.931847Z","steps":["trace[134497773] 'process raft request' (duration: 115.599956ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:17.571587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3033} {"level":"info","ts":"2026-07-14T23:06:17.659194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3033,"took":"86.076412ms","hash":524102581,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":32366592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-14T23:06:17.659276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":524102581,"revision":3033,"compact-revision":-1} {"level":"info","ts":"2026-07-14T23:10:56.495369Z","caller":"traceutil/trace.go:171","msg":"trace[2076131792] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"215.154449ms","start":"2026-07-14T23:10:56.280189Z","end":"2026-07-14T23:10:56.495343Z","steps":["trace[2076131792] 'process raft request' (duration: 214.990276ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:10:56.683697Z","caller":"traceutil/trace.go:171","msg":"trace[1293472269] transaction","detail":"{read_only:false; response_revision:8212; number_of_response:1; }","duration":"169.36534ms","start":"2026-07-14T23:10:56.514311Z","end":"2026-07-14T23:10:56.683676Z","steps":["trace[1293472269] 'process raft request' (duration: 166.630514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:08.889512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.098856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-14T23:11:08.889639Z","caller":"traceutil/trace.go:171","msg":"trace[358551214] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8277; }","duration":"186.291361ms","start":"2026-07-14T23:11:08.703328Z","end":"2026-07-14T23:11:08.88962Z","steps":["trace[358551214] 'range keys from in-memory index tree' (duration: 185.890101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:08.889665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.930126ms","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-07-14T23:11:08.889733Z","caller":"traceutil/trace.go:171","msg":"trace[1707096449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8277; }","duration":"141.999188ms","start":"2026-07-14T23:11:08.747715Z","end":"2026-07-14T23:11:08.889714Z","steps":["trace[1707096449] 'range keys from in-memory index tree' (duration: 141.733362ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:09.919785Z","caller":"traceutil/trace.go:171","msg":"trace[170316808] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"243.052434ms","start":"2026-07-14T23:11:09.676705Z","end":"2026-07-14T23:11:09.919757Z","steps":["trace[170316808] 'process raft request' (duration: 242.751637ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:10.408641Z","caller":"traceutil/trace.go:171","msg":"trace[1927569319] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"125.338585ms","start":"2026-07-14T23:11:10.283261Z","end":"2026-07-14T23:11:10.408599Z","steps":["trace[1927569319] 'process raft request' (duration: 43.274578ms)","trace[1927569319] 'compare' (duration: 81.890363ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:11.160711Z","caller":"traceutil/trace.go:171","msg":"trace[2048660918] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"118.509739ms","start":"2026-07-14T23:11:11.042177Z","end":"2026-07-14T23:11:11.160687Z","steps":["trace[2048660918] 'process raft request' (duration: 118.334065ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:17.577252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6162} {"level":"info","ts":"2026-07-14T23:11:17.676542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6162,"took":"96.69196ms","hash":2658541143,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":23298048,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-14T23:11:17.676628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2658541143,"revision":6162,"compact-revision":3033} {"level":"warn","ts":"2026-07-14T23:11:38.126214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.285949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:11:38.126309Z","caller":"traceutil/trace.go:171","msg":"trace[440224632] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8486; }","duration":"115.420882ms","start":"2026-07-14T23:11:38.010868Z","end":"2026-07-14T23:11:38.126289Z","steps":["trace[440224632] 'count revisions from in-memory index tree' (duration: 115.213407ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:50.404733Z","caller":"traceutil/trace.go:171","msg":"trace[1128088495] linearizableReadLoop","detail":"{readStateIndex:8900; appliedIndex:8899; }","duration":"141.052464ms","start":"2026-07-14T23:11:50.263656Z","end":"2026-07-14T23:11:50.404709Z","steps":["trace[1128088495] 'read index received' (duration: 140.851879ms)","trace[1128088495] 'applied index is now lower than readState.Index' (duration: 199.795µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:50.404821Z","caller":"traceutil/trace.go:171","msg":"trace[368059701] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"180.169104ms","start":"2026-07-14T23:11:50.224596Z","end":"2026-07-14T23:11:50.404765Z","steps":["trace[368059701] 'process raft request' (duration: 179.96743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:50.405005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.320741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5bf98f8569-85jxx.18c24acdd2cc6529\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-07-14T23:11:50.405071Z","caller":"traceutil/trace.go:171","msg":"trace[294782149] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5bf98f8569-85jxx.18c24acdd2cc6529; range_end:; response_count:1; response_revision:8595; }","duration":"141.429093ms","start":"2026-07-14T23:11:50.263626Z","end":"2026-07-14T23:11:50.405055Z","steps":["trace[294782149] 'agreement among raft nodes before linearized reading' (duration: 141.189818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:50.405302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.941823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x8jc7\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-07-14T23:11:50.405473Z","caller":"traceutil/trace.go:171","msg":"trace[1580377630] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x8jc7; range_end:; response_count:1; response_revision:8595; }","duration":"125.147018ms","start":"2026-07-14T23:11:50.280272Z","end":"2026-07-14T23:11:50.405419Z","steps":["trace[1580377630] 'agreement among raft nodes before linearized reading' (duration: 124.645336ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:50.518707Z","caller":"traceutil/trace.go:171","msg":"trace[577438026] linearizableReadLoop","detail":"{readStateIndex:8901; appliedIndex:8900; }","duration":"109.628342ms","start":"2026-07-14T23:11:50.409054Z","end":"2026-07-14T23:11:50.518683Z","steps":["trace[577438026] 'read index received' (duration: 96.006891ms)","trace[577438026] 'applied index is now lower than readState.Index' (duration: 13.620461ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:11:50.518997Z","caller":"traceutil/trace.go:171","msg":"trace[1234912712] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"111.6216ms","start":"2026-07-14T23:11:50.40736Z","end":"2026-07-14T23:11:50.518982Z","steps":["trace[1234912712] 'process raft request' (duration: 97.685741ms)","trace[1234912712] 'compare' (duration: 13.531929ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:11:50.519571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.495132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228737"} {"level":"info","ts":"2026-07-14T23:11:50.519621Z","caller":"traceutil/trace.go:171","msg":"trace[1766958170] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8596; }","duration":"110.561604ms","start":"2026-07-14T23:11:50.409048Z","end":"2026-07-14T23:11:50.51961Z","steps":["trace[1766958170] 'agreement among raft nodes before linearized reading' (duration: 110.124113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:11:50.546984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.750019ms","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-07-14T23:11:50.547037Z","caller":"traceutil/trace.go:171","msg":"trace[582901170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8597; }","duration":"110.828362ms","start":"2026-07-14T23:11:50.436197Z","end":"2026-07-14T23:11:50.547025Z","steps":["trace[582901170] 'agreement among raft nodes before linearized reading' (duration: 110.686538ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:50.547316Z","caller":"traceutil/trace.go:171","msg":"trace[1158779800] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"133.480822ms","start":"2026-07-14T23:11:50.413823Z","end":"2026-07-14T23:11:50.547304Z","steps":["trace[1158779800] 'process raft request' (duration: 132.972179ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:11:51.21359Z","caller":"traceutil/trace.go:171","msg":"trace[1948275679] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"136.138567ms","start":"2026-07-14T23:11:51.077425Z","end":"2026-07-14T23:11:51.213563Z","steps":["trace[1948275679] 'process raft request' (duration: 135.944372ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:12:00.887503Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002319.wal"} {"level":"warn","ts":"2026-07-14T23:12:51.116795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.234319ms","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-07-14T23:12:51.116895Z","caller":"traceutil/trace.go:171","msg":"trace[398559501] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9235; }","duration":"127.377632ms","start":"2026-07-14T23:12:50.989498Z","end":"2026-07-14T23:12:51.116875Z","steps":["trace[398559501] 'range keys from in-memory index tree' (duration: 127.145926ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:50.446676Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:13:50.452175Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-14T23:13:50.452315Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-14T23:16:17.584388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8328} {"level":"info","ts":"2026-07-14T23:16:17.66102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8328,"took":"74.012067ms","hash":2190363670,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":24137728,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-14T23:16:17.661107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2190363670,"revision":8328,"compact-revision":6162} {"level":"info","ts":"2026-07-14T23:18:40.527195Z","caller":"traceutil/trace.go:171","msg":"trace[1578277167] transaction","detail":"{read_only:false; response_revision:11751; number_of_response:1; }","duration":"113.618944ms","start":"2026-07-14T23:18:40.413542Z","end":"2026-07-14T23:18:40.527161Z","steps":["trace[1578277167] 'process raft request' (duration: 113.420959ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:18:41.771822Z","caller":"traceutil/trace.go:171","msg":"trace[1578117242] transaction","detail":"{read_only:false; response_revision:11760; number_of_response:1; }","duration":"149.859524ms","start":"2026-07-14T23:18:41.621941Z","end":"2026-07-14T23:18:41.7718Z","steps":["trace[1578117242] 'process raft request' (duration: 149.713391ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:18:41.899358Z","caller":"traceutil/trace.go:171","msg":"trace[1868119807] transaction","detail":"{read_only:false; response_revision:11761; number_of_response:1; }","duration":"274.604348ms","start":"2026-07-14T23:18:41.624726Z","end":"2026-07-14T23:18:41.899331Z","steps":["trace[1868119807] 'process raft request' (duration: 267.813994ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:18:41.899376Z","caller":"traceutil/trace.go:171","msg":"trace[844689681] transaction","detail":"{read_only:false; response_revision:11762; number_of_response:1; }","duration":"231.717678ms","start":"2026-07-14T23:18:41.667634Z","end":"2026-07-14T23:18:41.899352Z","steps":["trace[844689681] 'process raft request' (duration: 231.572474ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:21:17.590031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10545} {"level":"info","ts":"2026-07-14T23:21:17.665109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10545,"took":"72.753856ms","hash":4276984883,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":26292224,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-14T23:21:17.665204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4276984883,"revision":10545,"compact-revision":8328} {"level":"info","ts":"2026-07-14T23:25:21.02766Z","caller":"traceutil/trace.go:171","msg":"trace[860022239] transaction","detail":"{read_only:false; response_revision:14411; number_of_response:1; }","duration":"161.402698ms","start":"2026-07-14T23:25:20.866238Z","end":"2026-07-14T23:25:21.027641Z","steps":["trace[860022239] 'process raft request' (duration: 161.296565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:25:24.310072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.855146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:25:24.310143Z","caller":"traceutil/trace.go:171","msg":"trace[1005465436] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14442; }","duration":"144.974299ms","start":"2026-07-14T23:25:24.165155Z","end":"2026-07-14T23:25:24.31013Z","steps":["trace[1005465436] 'count revisions from in-memory index tree' (duration: 144.763944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:25:24.310072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.257024ms","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-07-14T23:25:24.310716Z","caller":"traceutil/trace.go:171","msg":"trace[1080977426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14442; }","duration":"187.509641ms","start":"2026-07-14T23:25:24.122768Z","end":"2026-07-14T23:25:24.310277Z","steps":["trace[1080977426] 'range keys from in-memory index tree' (duration: 187.121941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:25:46.571202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.574017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53796421-c44c-4ad1-a07a-c0cfc8c5b2d6\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-07-14T23:25:46.571335Z","caller":"traceutil/trace.go:171","msg":"trace[880423567] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-53796421-c44c-4ad1-a07a-c0cfc8c5b2d6; range_end:; response_count:1; response_revision:14748; }","duration":"289.760711ms","start":"2026-07-14T23:25:46.281552Z","end":"2026-07-14T23:25:46.571313Z","steps":["trace[880423567] 'range keys from in-memory index tree' (duration: 289.460164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:25:46.571683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.342306ms","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-07-14T23:25:46.571911Z","caller":"traceutil/trace.go:171","msg":"trace[952339072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14748; }","duration":"152.516171ms","start":"2026-07-14T23:25:46.419269Z","end":"2026-07-14T23:25:46.571785Z","steps":["trace[952339072] 'range keys from in-memory index tree' (duration: 152.103571ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:25:46.719554Z","caller":"traceutil/trace.go:171","msg":"trace[1087226329] linearizableReadLoop","detail":"{readStateIndex:15263; appliedIndex:15261; }","duration":"136.347139ms","start":"2026-07-14T23:25:46.58315Z","end":"2026-07-14T23:25:46.719497Z","steps":["trace[1087226329] 'read index received' (duration: 82.877251ms)","trace[1087226329] 'applied index is now lower than readState.Index' (duration: 53.468158ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:25:46.719607Z","caller":"traceutil/trace.go:171","msg":"trace[1927546732] transaction","detail":"{read_only:false; response_revision:14750; number_of_response:1; }","duration":"139.63941ms","start":"2026-07-14T23:25:46.579934Z","end":"2026-07-14T23:25:46.719573Z","steps":["trace[1927546732] 'process raft request' (duration: 139.367913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:25:46.720164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.850732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:25:46.720276Z","caller":"traceutil/trace.go:171","msg":"trace[1216968349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14750; }","duration":"137.150078ms","start":"2026-07-14T23:25:46.583111Z","end":"2026-07-14T23:25:46.720261Z","steps":["trace[1216968349] 'agreement among raft nodes before linearized reading' (duration: 136.562314ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:26:17.595561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12802} {"level":"info","ts":"2026-07-14T23:26:17.673333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12802,"took":"75.053602ms","hash":1026090775,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":28930048,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-14T23:26:17.67339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1026090775,"revision":12802,"compact-revision":10545} {"level":"info","ts":"2026-07-14T23:26:38.45908Z","caller":"traceutil/trace.go:171","msg":"trace[2031995971] transaction","detail":"{read_only:false; response_revision:15381; number_of_response:1; }","duration":"113.185006ms","start":"2026-07-14T23:26:38.345874Z","end":"2026-07-14T23:26:38.459059Z","steps":["trace[2031995971] 'process raft request' (duration: 113.001852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:27:13.538204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.811293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:27:13.538431Z","caller":"traceutil/trace.go:171","msg":"trace[1377764399] transaction","detail":"{read_only:false; response_revision:15562; number_of_response:1; }","duration":"322.325642ms","start":"2026-07-14T23:27:13.216051Z","end":"2026-07-14T23:27:13.538377Z","steps":["trace[1377764399] 'process raft request' (duration: 105.249974ms)","trace[1377764399] 'compare' (duration: 216.692558ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:27:13.538698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:27:13.216038Z","time spent":"322.505116ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:27:13.538944Z","caller":"traceutil/trace.go:171","msg":"trace[1916961620] transaction","detail":"{read_only:false; response_revision:15563; number_of_response:1; }","duration":"195.432863ms","start":"2026-07-14T23:27:13.343493Z","end":"2026-07-14T23:27:13.538926Z","steps":["trace[1916961620] 'process raft request' (duration: 195.31184ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:27:13.539211Z","caller":"traceutil/trace.go:171","msg":"trace[722977065] linearizableReadLoop","detail":"{readStateIndex:16098; appliedIndex:16097; }","duration":"240.119967ms","start":"2026-07-14T23:27:13.299052Z","end":"2026-07-14T23:27:13.539172Z","steps":["trace[722977065] 'read index received' (duration: 22.245379ms)","trace[722977065] 'applied index is now lower than readState.Index' (duration: 217.870978ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:27:13.539628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.580171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-14T23:27:13.53969Z","caller":"traceutil/trace.go:171","msg":"trace[1465420139] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15563; }","duration":"325.684234ms","start":"2026-07-14T23:27:13.213989Z","end":"2026-07-14T23:27:13.539673Z","steps":["trace[1465420139] 'agreement among raft nodes before linearized reading' (duration: 325.366297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:27:13.539744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:27:13.213979Z","time spent":"325.751916ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-14T23:27:13.540038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.063053ms","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-07-14T23:27:13.54009Z","caller":"traceutil/trace.go:171","msg":"trace[740563181] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15563; }","duration":"326.143115ms","start":"2026-07-14T23:27:13.213934Z","end":"2026-07-14T23:27:13.540077Z","steps":["trace[740563181] 'agreement among raft nodes before linearized reading' (duration: 326.066723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:27:13.540131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:27:13.213909Z","time spent":"326.214126ms","remote":"127.0.0.1:45502","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-07-14T23:31:17.603961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14955} {"level":"info","ts":"2026-07-14T23:31:17.697118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14955,"took":"88.38033ms","hash":4236477983,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":30904320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-14T23:31:17.697178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4236477983,"revision":14955,"compact-revision":12802} {"level":"info","ts":"2026-07-14T23:33:08.673255Z","caller":"traceutil/trace.go:171","msg":"trace[1590179940] transaction","detail":"{read_only:false; response_revision:18173; number_of_response:1; }","duration":"159.756853ms","start":"2026-07-14T23:33:08.513469Z","end":"2026-07-14T23:33:08.673225Z","steps":["trace[1590179940] 'process raft request' (duration: 159.537898ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:33:15.785102Z","caller":"traceutil/trace.go:171","msg":"trace[770043873] linearizableReadLoop","detail":"{readStateIndex:18843; appliedIndex:18842; }","duration":"111.329989ms","start":"2026-07-14T23:33:15.673759Z","end":"2026-07-14T23:33:15.785089Z","steps":["trace[770043873] 'read index received' (duration: 111.172855ms)","trace[770043873] 'applied index is now lower than readState.Index' (duration: 156.494µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:33:15.78522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.450743ms","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-07-14T23:33:15.785203Z","caller":"traceutil/trace.go:171","msg":"trace[2104111738] transaction","detail":"{read_only:false; response_revision:18220; number_of_response:1; }","duration":"143.036388ms","start":"2026-07-14T23:33:15.642136Z","end":"2026-07-14T23:33:15.785172Z","steps":["trace[2104111738] 'process raft request' (duration: 142.847464ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:33:15.785244Z","caller":"traceutil/trace.go:171","msg":"trace[1048644648] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18220; }","duration":"111.523815ms","start":"2026-07-14T23:33:15.673712Z","end":"2026-07-14T23:33:15.785236Z","steps":["trace[1048644648] 'agreement among raft nodes before linearized reading' (duration: 111.463413ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:33:16.139906Z","caller":"traceutil/trace.go:171","msg":"trace[265587526] transaction","detail":"{read_only:false; response_revision:18222; number_of_response:1; }","duration":"213.58996ms","start":"2026-07-14T23:33:15.926288Z","end":"2026-07-14T23:33:16.139877Z","steps":["trace[265587526] 'process raft request' (duration: 213.432075ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:35:09.624504Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:35:09.629167Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-14T23:35:09.629243Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-14T23:36:17.609245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17431} {"level":"info","ts":"2026-07-14T23:36:17.709605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17431,"took":"96.446868ms","hash":1459000273,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":34529280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-14T23:36:17.709667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1459000273,"revision":17431,"compact-revision":14955} {"level":"info","ts":"2026-07-14T23:39:17.89029Z","caller":"traceutil/trace.go:171","msg":"trace[364182352] linearizableReadLoop","detail":"{readStateIndex:21701; appliedIndex:21700; }","duration":"298.869244ms","start":"2026-07-14T23:39:17.591395Z","end":"2026-07-14T23:39:17.890264Z","steps":["trace[364182352] 'read index received' (duration: 298.673748ms)","trace[364182352] 'applied index is now lower than readState.Index' (duration: 194.586µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:39:17.890444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.027738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:39:17.890478Z","caller":"traceutil/trace.go:171","msg":"trace[1927431060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20986; }","duration":"299.09857ms","start":"2026-07-14T23:39:17.591367Z","end":"2026-07-14T23:39:17.890466Z","steps":["trace[1927431060] 'agreement among raft nodes before linearized reading' (duration: 299.025588ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:39:17.890748Z","caller":"traceutil/trace.go:171","msg":"trace[3301] transaction","detail":"{read_only:false; response_revision:20986; number_of_response:1; }","duration":"309.583554ms","start":"2026-07-14T23:39:17.58113Z","end":"2026-07-14T23:39:17.890713Z","steps":["trace[3301] 'process raft request' (duration: 309.00046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:39:17.890937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:39:17.581119Z","time spent":"309.725668ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:39:18.142181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.891224ms","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-07-14T23:39:18.142293Z","caller":"traceutil/trace.go:171","msg":"trace[2056891015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20986; }","duration":"134.089761ms","start":"2026-07-14T23:39:18.008183Z","end":"2026-07-14T23:39:18.142272Z","steps":["trace[2056891015] 'count revisions from in-memory index tree' (duration: 133.643448ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:39:18.441312Z","caller":"traceutil/trace.go:171","msg":"trace[1672419982] transaction","detail":"{read_only:false; response_revision:20989; number_of_response:1; }","duration":"200.644223ms","start":"2026-07-14T23:39:18.240634Z","end":"2026-07-14T23:39:18.441278Z","steps":["trace[1672419982] 'process raft request' (duration: 181.94667ms)","trace[1672419982] 'compare' (duration: 18.5664ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:39:18.689577Z","caller":"traceutil/trace.go:171","msg":"trace[179250585] transaction","detail":"{read_only:false; response_revision:20990; number_of_response:1; }","duration":"171.029915ms","start":"2026-07-14T23:39:18.518521Z","end":"2026-07-14T23:39:18.689551Z","steps":["trace[179250585] 'process raft request' (duration: 79.851245ms)","trace[179250585] 'compare' (duration: 91.076668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:39:18.978543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.507342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:39:18.97879Z","caller":"traceutil/trace.go:171","msg":"trace[159847313] transaction","detail":"{read_only:false; response_revision:20991; number_of_response:1; }","duration":"454.225879ms","start":"2026-07-14T23:39:18.524479Z","end":"2026-07-14T23:39:18.978705Z","steps":["trace[159847313] 'process raft request' (duration: 335.476711ms)","trace[159847313] 'compare' (duration: 118.336208ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:39:18.979007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:39:18.524458Z","time spent":"454.435544ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:39:19.237513Z","caller":"traceutil/trace.go:171","msg":"trace[2045102910] transaction","detail":"{read_only:false; response_revision:20994; number_of_response:1; }","duration":"101.102101ms","start":"2026-07-14T23:39:19.136381Z","end":"2026-07-14T23:39:19.237483Z","steps":["trace[2045102910] 'process raft request' (duration: 99.504822ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:41:17.617544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19766} {"level":"info","ts":"2026-07-14T23:41:17.725633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19766,"took":"104.376489ms","hash":435225982,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":29777920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-14T23:41:17.725702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":435225982,"revision":19766,"compact-revision":17431} {"level":"info","ts":"2026-07-14T23:43:26.798026Z","caller":"traceutil/trace.go:171","msg":"trace[1129569385] transaction","detail":"{read_only:false; response_revision:23539; number_of_response:1; }","duration":"292.676713ms","start":"2026-07-14T23:43:26.505329Z","end":"2026-07-14T23:43:26.798005Z","steps":["trace[1129569385] 'process raft request' (duration: 263.108597ms)","trace[1129569385] 'compare' (duration: 29.32638ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:43:26.798012Z","caller":"traceutil/trace.go:171","msg":"trace[1034550989] linearizableReadLoop","detail":"{readStateIndex:24335; appliedIndex:24334; }","duration":"267.707337ms","start":"2026-07-14T23:43:26.53028Z","end":"2026-07-14T23:43:26.797988Z","steps":["trace[1034550989] 'read index received' (duration: 238.09367ms)","trace[1034550989] 'applied index is now lower than readState.Index' (duration: 29.612117ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:43:26.798131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.815141ms","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-07-14T23:43:26.798165Z","caller":"traceutil/trace.go:171","msg":"trace[1197495597] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23539; }","duration":"267.883022ms","start":"2026-07-14T23:43:26.530272Z","end":"2026-07-14T23:43:26.798155Z","steps":["trace[1197495597] 'agreement among raft nodes before linearized reading' (duration: 267.78963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:43:26.798393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.110055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:43:26.798396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.822567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-14T23:43:26.79843Z","caller":"traceutil/trace.go:171","msg":"trace[794783885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23539; }","duration":"213.170987ms","start":"2026-07-14T23:43:26.58525Z","end":"2026-07-14T23:43:26.798421Z","steps":["trace[794783885] 'agreement among raft nodes before linearized reading' (duration: 213.067244ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:43:26.798476Z","caller":"traceutil/trace.go:171","msg":"trace[1863101237] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23539; }","duration":"226.992881ms","start":"2026-07-14T23:43:26.571462Z","end":"2026-07-14T23:43:26.798455Z","steps":["trace[1863101237] 'agreement among raft nodes before linearized reading' (duration: 226.730955ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:17.623365Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21993} {"level":"info","ts":"2026-07-14T23:46:17.736912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21993,"took":"109.685329ms","hash":1010043899,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":44498944,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-14T23:46:17.736987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1010043899,"revision":21993,"compact-revision":19766} {"level":"info","ts":"2026-07-14T23:46:32.450447Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066a1.wal"} {"level":"warn","ts":"2026-07-14T23:46:36.548673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.434495ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-14T23:46:36.548791Z","caller":"traceutil/trace.go:171","msg":"trace[65573024] linearizableReadLoop","detail":"{readStateIndex:26361; appliedIndex:26360; }","duration":"118.234156ms","start":"2026-07-14T23:46:36.430538Z","end":"2026-07-14T23:46:36.548772Z","steps":["trace[65573024] 'read index received' (duration: 54.481µs)","trace[65573024] 'applied index is now lower than readState.Index' (duration: 118.178245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:36.549231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.683207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:36.549282Z","caller":"traceutil/trace.go:171","msg":"trace[1015322052] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25515; }","duration":"118.765909ms","start":"2026-07-14T23:46:36.430502Z","end":"2026-07-14T23:46:36.549268Z","steps":["trace[1015322052] 'agreement among raft nodes before linearized reading' (duration: 118.676686ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:36.687083Z","caller":"traceutil/trace.go:171","msg":"trace[469605879] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"116.684928ms","start":"2026-07-14T23:46:36.570375Z","end":"2026-07-14T23:46:36.68706Z","steps":["trace[469605879] 'process raft request' (duration: 116.505614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:36.766756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.123276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:46:36.766772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.132863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:36.766831Z","caller":"traceutil/trace.go:171","msg":"trace[1084175908] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25517; }","duration":"161.2872ms","start":"2026-07-14T23:46:36.605527Z","end":"2026-07-14T23:46:36.766814Z","steps":["trace[1084175908] 'agreement among raft nodes before linearized reading' (duration: 161.119016ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:36.766862Z","caller":"traceutil/trace.go:171","msg":"trace[2103320121] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25517; }","duration":"165.332768ms","start":"2026-07-14T23:46:36.601515Z","end":"2026-07-14T23:46:36.766847Z","steps":["trace[2103320121] 'agreement among raft nodes before linearized reading' (duration: 165.138274ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:38.501693Z","caller":"traceutil/trace.go:171","msg":"trace[1786070140] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"198.418219ms","start":"2026-07-14T23:46:38.303248Z","end":"2026-07-14T23:46:38.501667Z","steps":["trace[1786070140] 'process raft request' (duration: 198.166413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:38.895871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.305615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:38.895956Z","caller":"traceutil/trace.go:171","msg":"trace[1187443863] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"203.909643ms","start":"2026-07-14T23:46:38.692031Z","end":"2026-07-14T23:46:38.895941Z","steps":["trace[1187443863] 'process raft request' (duration: 41.417044ms)","trace[1187443863] 'compare' (duration: 161.942546ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:38.901632Z","caller":"traceutil/trace.go:171","msg":"trace[2050108843] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"129.722025ms","start":"2026-07-14T23:46:38.771887Z","end":"2026-07-14T23:46:38.901609Z","steps":["trace[2050108843] 'process raft request' (duration: 129.556521ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:43.710248Z","caller":"traceutil/trace.go:171","msg":"trace[2132065002] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"222.532306ms","start":"2026-07-14T23:46:43.487687Z","end":"2026-07-14T23:46:43.710219Z","steps":["trace[2132065002] 'process raft request' (duration: 222.363152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:43.961419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.411201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:46:43.9615Z","caller":"traceutil/trace.go:171","msg":"trace[887106249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25582; }","duration":"177.524683ms","start":"2026-07-14T23:46:43.78396Z","end":"2026-07-14T23:46:43.961484Z","steps":["trace[887106249] 'range keys from in-memory index tree' (duration: 177.280227ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:43.961755Z","caller":"traceutil/trace.go:171","msg":"trace[1844732821] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"161.193967ms","start":"2026-07-14T23:46:43.80055Z","end":"2026-07-14T23:46:43.961744Z","steps":["trace[1844732821] 'process raft request' (duration: 62.136636ms)","trace[1844732821] 'compare' (duration: 98.712923ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:43.961789Z","caller":"traceutil/trace.go:171","msg":"trace[1632137529] transaction","detail":"{read_only:false; response_revision:25584; number_of_response:1; }","duration":"105.6351ms","start":"2026-07-14T23:46:43.856126Z","end":"2026-07-14T23:46:43.961761Z","steps":["trace[1632137529] 'process raft request' (duration: 105.556038ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:44.093937Z","caller":"traceutil/trace.go:171","msg":"trace[36986082] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"142.253189ms","start":"2026-07-14T23:46:43.95165Z","end":"2026-07-14T23:46:44.093903Z","steps":["trace[36986082] 'process raft request' (duration: 141.667674ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:44.094271Z","caller":"traceutil/trace.go:171","msg":"trace[595353468] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"123.646307ms","start":"2026-07-14T23:46:43.970593Z","end":"2026-07-14T23:46:44.094239Z","steps":["trace[595353468] 'process raft request' (duration: 123.296549ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:44.325606Z","caller":"traceutil/trace.go:171","msg":"trace[1110097293] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"175.154326ms","start":"2026-07-14T23:46:44.150424Z","end":"2026-07-14T23:46:44.325579Z","steps":["trace[1110097293] 'process raft request' (duration: 173.820464ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:53.273232Z","caller":"traceutil/trace.go:171","msg":"trace[777255078] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"317.26075ms","start":"2026-07-14T23:46:52.955948Z","end":"2026-07-14T23:46:53.273209Z","steps":["trace[777255078] 'process raft request' (duration: 317.114767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:53.273379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:52.955914Z","time spent":"317.390293ms","remote":"127.0.0.1:45528","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-07-14T23:46:53.447876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.060143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:53.448089Z","caller":"traceutil/trace.go:171","msg":"trace[1331549046] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26510; }","duration":"480.917949ms","start":"2026-07-14T23:46:52.967155Z","end":"2026-07-14T23:46:53.448073Z","steps":["trace[1331549046] 'read index received' (duration: 305.903596ms)","trace[1331549046] 'applied index is now lower than readState.Index' (duration: 175.013143ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:53.44812Z","caller":"traceutil/trace.go:171","msg":"trace[733132418] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"489.187819ms","start":"2026-07-14T23:46:52.9589Z","end":"2026-07-14T23:46:53.448088Z","steps":["trace[733132418] 'process raft request' (duration: 344.824449ms)","trace[733132418] 'compare' (duration: 143.699445ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:53.448137Z","caller":"traceutil/trace.go:171","msg":"trace[46769928] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"462.80041ms","start":"2026-07-14T23:46:52.985296Z","end":"2026-07-14T23:46:53.448097Z","steps":["trace[46769928] 'process raft request' (duration: 462.688397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:53.448272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:52.95887Z","time spent":"489.317583ms","remote":"127.0.0.1:45528","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-07-14T23:46:53.448311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.411375ms","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-07-14T23:46:53.448342Z","caller":"traceutil/trace.go:171","msg":"trace[1208395381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25664; }","duration":"218.463976ms","start":"2026-07-14T23:46:53.229867Z","end":"2026-07-14T23:46:53.448331Z","steps":["trace[1208395381] 'agreement among raft nodes before linearized reading' (duration: 218.355474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:53.448202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.940401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:46:53.448328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.146534ms","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-07-14T23:46:53.448441Z","caller":"traceutil/trace.go:171","msg":"trace[137869123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25664; }","duration":"481.300878ms","start":"2026-07-14T23:46:52.967119Z","end":"2026-07-14T23:46:53.44842Z","steps":["trace[137869123] 'agreement among raft nodes before linearized reading' (duration: 481.015092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:53.448357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:52.985271Z","time spent":"462.978473ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:46:53.448499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:52.967106Z","time spent":"481.38112ms","remote":"127.0.0.1:45528","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":"info","ts":"2026-07-14T23:46:53.448442Z","caller":"traceutil/trace.go:171","msg":"trace[1933796376] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25664; }","duration":"373.183177ms","start":"2026-07-14T23:46:53.075213Z","end":"2026-07-14T23:46:53.448396Z","steps":["trace[1933796376] 'agreement among raft nodes before linearized reading' (duration: 372.952141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:53.448713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:53.075193Z","time spent":"373.503054ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:46:53.737655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.108589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:53.738106Z","caller":"traceutil/trace.go:171","msg":"trace[2043031648] linearizableReadLoop","detail":"{readStateIndex:26515; appliedIndex:26513; }","duration":"244.492897ms","start":"2026-07-14T23:46:53.493596Z","end":"2026-07-14T23:46:53.738089Z","steps":["trace[2043031648] 'read index received' (duration: 93.833205ms)","trace[2043031648] 'applied index is now lower than readState.Index' (duration: 150.658952ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:53.738121Z","caller":"traceutil/trace.go:171","msg":"trace[1879754208] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"286.268769ms","start":"2026-07-14T23:46:53.451827Z","end":"2026-07-14T23:46:53.738095Z","steps":["trace[1879754208] 'process raft request' (duration: 135.641518ms)","trace[1879754208] 'compare' (duration: 149.973796ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:53.738161Z","caller":"traceutil/trace.go:171","msg":"trace[811807659] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"285.88696ms","start":"2026-07-14T23:46:53.452241Z","end":"2026-07-14T23:46:53.738128Z","steps":["trace[811807659] 'process raft request' (duration: 285.514751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:53.738577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.961728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-07-14T23:46:53.738669Z","caller":"traceutil/trace.go:171","msg":"trace[1178181622] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25666; }","duration":"245.092172ms","start":"2026-07-14T23:46:53.49356Z","end":"2026-07-14T23:46:53.738652Z","steps":["trace[1178181622] 'agreement among raft nodes before linearized reading' (duration: 244.886296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:53.738754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.60993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-14T23:46:53.738872Z","caller":"traceutil/trace.go:171","msg":"trace[984999157] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25666; }","duration":"178.816035ms","start":"2026-07-14T23:46:53.560033Z","end":"2026-07-14T23:46:53.738849Z","steps":["trace[984999157] 'agreement among raft nodes before linearized reading' (duration: 178.177759ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:53.943296Z","caller":"traceutil/trace.go:171","msg":"trace[1348653805] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"165.40887ms","start":"2026-07-14T23:46:53.777861Z","end":"2026-07-14T23:46:53.94327Z","steps":["trace[1348653805] 'process raft request' (duration: 146.122442ms)","trace[1348653805] 'compare' (duration: 18.970099ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:54.215547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.442646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:54.215755Z","caller":"traceutil/trace.go:171","msg":"trace[745179462] linearizableReadLoop","detail":"{readStateIndex:26518; appliedIndex:26516; }","duration":"130.055904ms","start":"2026-07-14T23:46:54.085684Z","end":"2026-07-14T23:46:54.21574Z","steps":["trace[745179462] 'read index received' (duration: 59.842472ms)","trace[745179462] 'applied index is now lower than readState.Index' (duration: 70.212492ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:54.215829Z","caller":"traceutil/trace.go:171","msg":"trace[2054141485] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"221.614162ms","start":"2026-07-14T23:46:53.994206Z","end":"2026-07-14T23:46:54.21582Z","steps":["trace[2054141485] 'process raft request' (duration: 82.824427ms)","trace[2054141485] 'compare' (duration: 138.16206ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:54.215902Z","caller":"traceutil/trace.go:171","msg":"trace[346156407] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"221.401617ms","start":"2026-07-14T23:46:53.994478Z","end":"2026-07-14T23:46:54.21588Z","steps":["trace[346156407] 'process raft request' (duration: 221.171682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:54.216059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.373641ms","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-07-14T23:46:54.216106Z","caller":"traceutil/trace.go:171","msg":"trace[988791246] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25669; }","duration":"130.452313ms","start":"2026-07-14T23:46:54.085643Z","end":"2026-07-14T23:46:54.216096Z","steps":["trace[988791246] 'agreement among raft nodes before linearized reading' (duration: 130.381622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:54.228086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.529346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:54.228174Z","caller":"traceutil/trace.go:171","msg":"trace[1254428338] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25671; }","duration":"114.66763ms","start":"2026-07-14T23:46:54.113487Z","end":"2026-07-14T23:46:54.228154Z","steps":["trace[1254428338] 'agreement among raft nodes before linearized reading' (duration: 114.473965ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:54.369591Z","caller":"traceutil/trace.go:171","msg":"trace[1806913128] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"138.136709ms","start":"2026-07-14T23:46:54.231431Z","end":"2026-07-14T23:46:54.369567Z","steps":["trace[1806913128] 'process raft request' (duration: 135.383812ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:54.710772Z","caller":"traceutil/trace.go:171","msg":"trace[623765426] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"150.976561ms","start":"2026-07-14T23:46:54.559778Z","end":"2026-07-14T23:46:54.710754Z","steps":["trace[623765426] 'process raft request' (duration: 150.928099ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:54.710791Z","caller":"traceutil/trace.go:171","msg":"trace[237420243] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"337.366189ms","start":"2026-07-14T23:46:54.373398Z","end":"2026-07-14T23:46:54.710764Z","steps":["trace[237420243] 'process raft request' (duration: 238.01477ms)","trace[237420243] 'compare' (duration: 99.175884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:54.710892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:54.373375Z","time spent":"337.465452ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:46:54.710887Z","caller":"traceutil/trace.go:171","msg":"trace[1605894911] linearizableReadLoop","detail":"{readStateIndex:26522; appliedIndex:26521; }","duration":"225.935217ms","start":"2026-07-14T23:46:54.48494Z","end":"2026-07-14T23:46:54.710875Z","steps":["trace[1605894911] 'read index received' (duration: 126.488146ms)","trace[1605894911] 'applied index is now lower than readState.Index' (duration: 99.445891ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:54.711073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.133544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:46:54.711096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.148492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-14T23:46:54.711133Z","caller":"traceutil/trace.go:171","msg":"trace[1286699547] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25674; }","duration":"226.201413ms","start":"2026-07-14T23:46:54.484924Z","end":"2026-07-14T23:46:54.711126Z","steps":["trace[1286699547] 'agreement among raft nodes before linearized reading' (duration: 226.04476ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:54.711132Z","caller":"traceutil/trace.go:171","msg":"trace[663560480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25674; }","duration":"125.213886ms","start":"2026-07-14T23:46:54.585909Z","end":"2026-07-14T23:46:54.711123Z","steps":["trace[663560480] 'agreement among raft nodes before linearized reading' (duration: 125.122784ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:55.404738Z","caller":"traceutil/trace.go:171","msg":"trace[1454217336] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"126.672621ms","start":"2026-07-14T23:46:55.278045Z","end":"2026-07-14T23:46:55.404717Z","steps":["trace[1454217336] 'process raft request' (duration: 124.418996ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:55.843114Z","caller":"traceutil/trace.go:171","msg":"trace[540015848] linearizableReadLoop","detail":"{readStateIndex:26528; appliedIndex:26526; }","duration":"256.646392ms","start":"2026-07-14T23:46:55.58643Z","end":"2026-07-14T23:46:55.843076Z","steps":["trace[540015848] 'read index received' (duration: 217.206556ms)","trace[540015848] 'applied index is now lower than readState.Index' (duration: 39.436676ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:55.843208Z","caller":"traceutil/trace.go:171","msg":"trace[1429976698] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"390.169089ms","start":"2026-07-14T23:46:55.452908Z","end":"2026-07-14T23:46:55.843077Z","steps":["trace[1429976698] 'process raft request' (duration: 389.79353ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:55.842791Z","caller":"traceutil/trace.go:171","msg":"trace[1619562126] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"389.919643ms","start":"2026-07-14T23:46:55.452843Z","end":"2026-07-14T23:46:55.842763Z","steps":["trace[1619562126] 'process raft request' (duration: 350.800375ms)","trace[1619562126] 'compare' (duration: 38.912273ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:55.843363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:55.452885Z","time spent":"390.391224ms","remote":"127.0.0.1:45528","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-07-14T23:46:55.843505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:55.452818Z","time spent":"390.579659ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:46:55.845309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.875256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:46:55.84533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.237661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:55.845372Z","caller":"traceutil/trace.go:171","msg":"trace[1681841677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25679; }","duration":"258.972369ms","start":"2026-07-14T23:46:55.58638Z","end":"2026-07-14T23:46:55.845352Z","steps":["trace[1681841677] 'agreement among raft nodes before linearized reading' (duration: 256.875008ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:55.845392Z","caller":"traceutil/trace.go:171","msg":"trace[2116979877] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25679; }","duration":"218.351583ms","start":"2026-07-14T23:46:55.627024Z","end":"2026-07-14T23:46:55.845376Z","steps":["trace[2116979877] 'agreement among raft nodes before linearized reading' (duration: 218.240931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:55.845473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.346206ms","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-07-14T23:46:55.845548Z","caller":"traceutil/trace.go:171","msg":"trace[232791796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25679; }","duration":"103.454468ms","start":"2026-07-14T23:46:55.742077Z","end":"2026-07-14T23:46:55.845531Z","steps":["trace[232791796] 'agreement among raft nodes before linearized reading' (duration: 103.223843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:55.845595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.093544ms","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-07-14T23:46:55.845667Z","caller":"traceutil/trace.go:171","msg":"trace[1609788383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25679; }","duration":"104.215177ms","start":"2026-07-14T23:46:55.741439Z","end":"2026-07-14T23:46:55.845654Z","steps":["trace[1609788383] 'agreement among raft nodes before linearized reading' (duration: 103.993022ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:02.006102Z","caller":"traceutil/trace.go:171","msg":"trace[1481183623] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"134.50677ms","start":"2026-07-14T23:47:01.871571Z","end":"2026-07-14T23:47:02.006078Z","steps":["trace[1481183623] 'process raft request' (duration: 134.367527ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:02.243779Z","caller":"traceutil/trace.go:171","msg":"trace[549200884] linearizableReadLoop","detail":"{readStateIndex:26587; appliedIndex:26585; }","duration":"369.523459ms","start":"2026-07-14T23:47:01.874231Z","end":"2026-07-14T23:47:02.243755Z","steps":["trace[549200884] 'read index received' (duration: 131.744184ms)","trace[549200884] 'applied index is now lower than readState.Index' (duration: 237.778144ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:02.243914Z","caller":"traceutil/trace.go:171","msg":"trace[1897969181] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"371.482876ms","start":"2026-07-14T23:47:01.872375Z","end":"2026-07-14T23:47:02.243858Z","steps":["trace[1897969181] 'process raft request' (duration: 358.136422ms)","trace[1897969181] 'compare' (duration: 13.116879ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:02.244042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.798905ms","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-07-14T23:47:02.244104Z","caller":"traceutil/trace.go:171","msg":"trace[1438397316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25736; }","duration":"369.911017ms","start":"2026-07-14T23:47:01.874177Z","end":"2026-07-14T23:47:02.244088Z","steps":["trace[1438397316] 'agreement among raft nodes before linearized reading' (duration: 369.671282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:02.244095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:01.872367Z","time spent":"371.635059ms","remote":"127.0.0.1:45528","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-07-14T23:47:02.244146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:01.874157Z","time spent":"369.984189ms","remote":"127.0.0.1:45528","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-07-14T23:47:02.244578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.639251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:47:02.244601Z","caller":"traceutil/trace.go:171","msg":"trace[1458148322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25737; }","duration":"369.695123ms","start":"2026-07-14T23:47:01.874901Z","end":"2026-07-14T23:47:02.244597Z","steps":["trace[1458148322] 'agreement among raft nodes before linearized reading' (duration: 369.626831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:02.244616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:01.874886Z","time spent":"369.727503ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:47:02.244739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.161604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-14T23:47:02.244729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.585518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:02.24479Z","caller":"traceutil/trace.go:171","msg":"trace[256404951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25737; }","duration":"264.251726ms","start":"2026-07-14T23:47:01.980525Z","end":"2026-07-14T23:47:02.244777Z","steps":["trace[256404951] 'agreement among raft nodes before linearized reading' (duration: 264.109902ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:02.244806Z","caller":"traceutil/trace.go:171","msg":"trace[2144503239] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25737; }","duration":"319.70727ms","start":"2026-07-14T23:47:01.925081Z","end":"2026-07-14T23:47:02.244788Z","steps":["trace[2144503239] 'agreement among raft nodes before linearized reading' (duration: 319.574167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:02.244864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:01.925066Z","time spent":"319.785722ms","remote":"127.0.0.1:42356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:47:02.585251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.142642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:47:02.585708Z","caller":"traceutil/trace.go:171","msg":"trace[944153920] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"337.182215ms","start":"2026-07-14T23:47:02.248501Z","end":"2026-07-14T23:47:02.585683Z","steps":["trace[944153920] 'process raft request' (duration: 235.495859ms)","trace[944153920] 'compare' (duration: 100.806234ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:02.585782Z","caller":"traceutil/trace.go:171","msg":"trace[1625714298] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"337.192955ms","start":"2026-07-14T23:47:02.248573Z","end":"2026-07-14T23:47:02.585766Z","steps":["trace[1625714298] 'process raft request' (duration: 336.971279ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:02.58582Z","caller":"traceutil/trace.go:171","msg":"trace[355938249] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"336.125559ms","start":"2026-07-14T23:47:02.249634Z","end":"2026-07-14T23:47:02.58576Z","steps":["trace[355938249] 'process raft request' (duration: 336.033576ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:02.585947Z","caller":"traceutil/trace.go:171","msg":"trace[770650395] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"336.015026ms","start":"2026-07-14T23:47:02.249921Z","end":"2026-07-14T23:47:02.585936Z","steps":["trace[770650395] 'process raft request' (duration: 335.795521ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:02.585955Z","caller":"traceutil/trace.go:171","msg":"trace[686839947] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26590; }","duration":"187.481156ms","start":"2026-07-14T23:47:02.39845Z","end":"2026-07-14T23:47:02.585931Z","steps":["trace[686839947] 'read index received' (duration: 85.542844ms)","trace[686839947] 'applied index is now lower than readState.Index' (duration: 101.932792ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:02.585859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:02.248566Z","time spent":"337.246067ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:47:02.585858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:02.248477Z","time spent":"337.296358ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:47:02.586176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.715301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:47:02.586246Z","caller":"traceutil/trace.go:171","msg":"trace[392451169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25743; }","duration":"187.807643ms","start":"2026-07-14T23:47:02.398421Z","end":"2026-07-14T23:47:02.586229Z","steps":["trace[392451169] 'agreement among raft nodes before linearized reading' (duration: 187.595068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:02.586053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:02.249911Z","time spent":"336.064017ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:47:02.586465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:02.249612Z","time spent":"336.363434ms","remote":"127.0.0.1:45528","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-07-14T23:47:02.896609Z","caller":"traceutil/trace.go:171","msg":"trace[1122829395] linearizableReadLoop","detail":"{readStateIndex:26596; appliedIndex:26595; }","duration":"143.989792ms","start":"2026-07-14T23:47:02.7526Z","end":"2026-07-14T23:47:02.89659Z","steps":["trace[1122829395] 'read index received' (duration: 143.833298ms)","trace[1122829395] 'applied index is now lower than readState.Index' (duration: 155.914µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:02.896694Z","caller":"traceutil/trace.go:171","msg":"trace[1456099395] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"147.137307ms","start":"2026-07-14T23:47:02.749549Z","end":"2026-07-14T23:47:02.896687Z","steps":["trace[1456099395] 'process raft request' (duration: 146.926252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:02.896853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.249067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-14T23:47:02.896883Z","caller":"traceutil/trace.go:171","msg":"trace[643616799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25745; }","duration":"144.320649ms","start":"2026-07-14T23:47:02.752553Z","end":"2026-07-14T23:47:02.896873Z","steps":["trace[643616799] 'agreement among raft nodes before linearized reading' (duration: 144.220137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.413599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.974424ms","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-07-14T23:47:10.413688Z","caller":"traceutil/trace.go:171","msg":"trace[1907460919] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25862; }","duration":"258.100756ms","start":"2026-07-14T23:47:10.15557Z","end":"2026-07-14T23:47:10.413671Z","steps":["trace[1907460919] 'range keys from in-memory index tree' (duration: 257.885441ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:10.430132Z","caller":"traceutil/trace.go:171","msg":"trace[61659727] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"226.4528ms","start":"2026-07-14T23:47:10.203661Z","end":"2026-07-14T23:47:10.430114Z","steps":["trace[61659727] 'process raft request' (duration: 226.324727ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:10.636865Z","caller":"traceutil/trace.go:171","msg":"trace[1155139010] transaction","detail":"{read_only:false; response_revision:25865; number_of_response:1; }","duration":"361.301689ms","start":"2026-07-14T23:47:10.275536Z","end":"2026-07-14T23:47:10.636838Z","steps":["trace[1155139010] 'process raft request' (duration: 361.215497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.63702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:10.275523Z","time spent":"361.418281ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:47:10.636877Z","caller":"traceutil/trace.go:171","msg":"trace[512479100] transaction","detail":"{read_only:false; response_revision:25864; number_of_response:1; }","duration":"364.62534ms","start":"2026-07-14T23:47:10.272228Z","end":"2026-07-14T23:47:10.636853Z","steps":["trace[512479100] 'process raft request' (duration: 364.366504ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:10.637196Z","caller":"traceutil/trace.go:171","msg":"trace[2020270067] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"361.546525ms","start":"2026-07-14T23:47:10.275623Z","end":"2026-07-14T23:47:10.637169Z","steps":["trace[2020270067] 'process raft request' (duration: 361.174106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.63722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:10.272202Z","time spent":"364.949268ms","remote":"127.0.0.1:45528","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-07-14T23:47:10.637194Z","caller":"traceutil/trace.go:171","msg":"trace[755733319] linearizableReadLoop","detail":"{readStateIndex:26719; appliedIndex:26715; }","duration":"125.927403ms","start":"2026-07-14T23:47:10.511234Z","end":"2026-07-14T23:47:10.637161Z","steps":["trace[755733319] 'read index received' (duration: 125.40966ms)","trace[755733319] 'applied index is now lower than readState.Index' (duration: 516.303µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:10.637243Z","caller":"traceutil/trace.go:171","msg":"trace[201023545] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"317.671131ms","start":"2026-07-14T23:47:10.319559Z","end":"2026-07-14T23:47:10.63723Z","steps":["trace[201023545] 'process raft request' (duration: 317.477257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.63734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:10.275598Z","time spent":"361.672007ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:47:10.637363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:10.319544Z","time spent":"317.745664ms","remote":"127.0.0.1:45528","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-07-14T23:47:10.63751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.24962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18c24cc90c7a5c35\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-07-14T23:47:10.637584Z","caller":"traceutil/trace.go:171","msg":"trace[1287001906] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18c24cc90c7a5c35; range_end:; response_count:1; response_revision:25867; }","duration":"126.339802ms","start":"2026-07-14T23:47:10.511226Z","end":"2026-07-14T23:47:10.637565Z","steps":["trace[1287001906] 'agreement among raft nodes before linearized reading' (duration: 126.078386ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:10.867315Z","caller":"traceutil/trace.go:171","msg":"trace[1793307743] linearizableReadLoop","detail":"{readStateIndex:26720; appliedIndex:26719; }","duration":"230.017746ms","start":"2026-07-14T23:47:10.637275Z","end":"2026-07-14T23:47:10.867293Z","steps":["trace[1793307743] 'read index received' (duration: 215.913874ms)","trace[1793307743] 'applied index is now lower than readState.Index' (duration: 14.103032ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:10.867387Z","caller":"traceutil/trace.go:171","msg":"trace[1175099015] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"258.117848ms","start":"2026-07-14T23:47:10.609225Z","end":"2026-07-14T23:47:10.867343Z","steps":["trace[1175099015] 'process raft request' (duration: 244.037316ms)","trace[1175099015] 'compare' (duration: 13.849226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:10.867492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.060722ms","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-07-14T23:47:10.867518Z","caller":"traceutil/trace.go:171","msg":"trace[381813477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25868; }","duration":"245.134343ms","start":"2026-07-14T23:47:10.622373Z","end":"2026-07-14T23:47:10.867508Z","steps":["trace[381813477] 'agreement among raft nodes before linearized reading' (duration: 245.018491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.867549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.128018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T23:47:10.867615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.537817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:10.867625Z","caller":"traceutil/trace.go:171","msg":"trace[1596757181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25868; }","duration":"244.225791ms","start":"2026-07-14T23:47:10.62338Z","end":"2026-07-14T23:47:10.867606Z","steps":["trace[1596757181] 'agreement among raft nodes before linearized reading' (duration: 243.932894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.86767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.45084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:47:10.867733Z","caller":"traceutil/trace.go:171","msg":"trace[390880791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25868; }","duration":"233.538351ms","start":"2026-07-14T23:47:10.63418Z","end":"2026-07-14T23:47:10.867718Z","steps":["trace[390880791] 'agreement among raft nodes before linearized reading' (duration: 233.310146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.867753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.257869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f586334-964d-44b3-af9a-772366e7c5ee\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-14T23:47:10.867819Z","caller":"traceutil/trace.go:171","msg":"trace[1629447157] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f586334-964d-44b3-af9a-772366e7c5ee; range_end:; response_count:1; response_revision:25868; }","duration":"262.36584ms","start":"2026-07-14T23:47:10.605434Z","end":"2026-07-14T23:47:10.8678Z","steps":["trace[1629447157] 'agreement among raft nodes before linearized reading' (duration: 262.154955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.86793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.401947ms","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-07-14T23:47:10.867953Z","caller":"traceutil/trace.go:171","msg":"trace[193216475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25868; }","duration":"247.459689ms","start":"2026-07-14T23:47:10.620486Z","end":"2026-07-14T23:47:10.867946Z","steps":["trace[193216475] 'agreement among raft nodes before linearized reading' (duration: 247.388727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.867948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.205743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:10.868017Z","caller":"traceutil/trace.go:171","msg":"trace[1210241118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25868; }","duration":"280.315685ms","start":"2026-07-14T23:47:10.58768Z","end":"2026-07-14T23:47:10.867995Z","steps":["trace[1210241118] 'agreement among raft nodes before linearized reading' (duration: 280.166082ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:10.868041Z","caller":"traceutil/trace.go:171","msg":"trace[184638383] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25868; }","duration":"272.578757ms","start":"2026-07-14T23:47:10.59505Z","end":"2026-07-14T23:47:10.867629Z","steps":["trace[184638383] 'agreement among raft nodes before linearized reading' (duration: 272.543007ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:10.879027Z","caller":"traceutil/trace.go:171","msg":"trace[1900242075] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"238.984223ms","start":"2026-07-14T23:47:10.640024Z","end":"2026-07-14T23:47:10.879008Z","steps":["trace[1900242075] 'process raft request' (duration: 238.586954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:10.879262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.666474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-14T23:47:10.879353Z","caller":"traceutil/trace.go:171","msg":"trace[823818959] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25869; }","duration":"108.820148ms","start":"2026-07-14T23:47:10.770515Z","end":"2026-07-14T23:47:10.879335Z","steps":["trace[823818959] 'agreement among raft nodes before linearized reading' (duration: 108.608513ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:11.159253Z","caller":"traceutil/trace.go:171","msg":"trace[1914147853] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"286.960936ms","start":"2026-07-14T23:47:10.87226Z","end":"2026-07-14T23:47:11.159221Z","steps":["trace[1914147853] 'process raft request' (duration: 215.868283ms)","trace[1914147853] 'compare' (duration: 70.903899ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:11.159523Z","caller":"traceutil/trace.go:171","msg":"trace[1323758170] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"286.944546ms","start":"2026-07-14T23:47:10.872551Z","end":"2026-07-14T23:47:11.159495Z","steps":["trace[1323758170] 'process raft request' (duration: 286.624618ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:11.159841Z","caller":"traceutil/trace.go:171","msg":"trace[435981241] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"287.227972ms","start":"2026-07-14T23:47:10.872581Z","end":"2026-07-14T23:47:11.159809Z","steps":["trace[435981241] 'process raft request' (duration: 286.816922ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:11.159887Z","caller":"traceutil/trace.go:171","msg":"trace[1227940465] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"287.230742ms","start":"2026-07-14T23:47:10.872632Z","end":"2026-07-14T23:47:11.159863Z","steps":["trace[1227940465] 'process raft request' (duration: 287.071419ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:11.160008Z","caller":"traceutil/trace.go:171","msg":"trace[1455680101] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"236.597876ms","start":"2026-07-14T23:47:10.923397Z","end":"2026-07-14T23:47:11.159995Z","steps":["trace[1455680101] 'process raft request' (duration: 236.543084ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:11.160034Z","caller":"traceutil/trace.go:171","msg":"trace[116217381] linearizableReadLoop","detail":"{readStateIndex:26726; appliedIndex:26721; }","duration":"281.279268ms","start":"2026-07-14T23:47:10.87874Z","end":"2026-07-14T23:47:11.16002Z","steps":["trace[116217381] 'read index received' (duration: 209.403966ms)","trace[116217381] 'applied index is now lower than readState.Index' (duration: 71.871902ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:11.160054Z","caller":"traceutil/trace.go:171","msg":"trace[303936364] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"284.391013ms","start":"2026-07-14T23:47:10.875639Z","end":"2026-07-14T23:47:11.16003Z","steps":["trace[303936364] 'process raft request' (duration: 284.141748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:11.160159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.258161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:11.160204Z","caller":"traceutil/trace.go:171","msg":"trace[1016691801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25875; }","duration":"291.335662ms","start":"2026-07-14T23:47:10.868857Z","end":"2026-07-14T23:47:11.160193Z","steps":["trace[1016691801] 'agreement among raft nodes before linearized reading' (duration: 291.23879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:11.160246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.105613ms","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-07-14T23:47:11.160289Z","caller":"traceutil/trace.go:171","msg":"trace[38493979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25875; }","duration":"123.147604ms","start":"2026-07-14T23:47:11.037129Z","end":"2026-07-14T23:47:11.160277Z","steps":["trace[38493979] 'agreement among raft nodes before linearized reading' (duration: 123.039042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:11.160424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.610871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-07-14T23:47:11.160546Z","caller":"traceutil/trace.go:171","msg":"trace[447401905] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25875; }","duration":"277.732774ms","start":"2026-07-14T23:47:10.882786Z","end":"2026-07-14T23:47:11.160518Z","steps":["trace[447401905] 'agreement among raft nodes before linearized reading' (duration: 277.367494ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:11.450074Z","caller":"traceutil/trace.go:171","msg":"trace[102058857] linearizableReadLoop","detail":"{readStateIndex:26730; appliedIndex:26728; }","duration":"281.836483ms","start":"2026-07-14T23:47:11.168216Z","end":"2026-07-14T23:47:11.450053Z","steps":["trace[102058857] 'read index received' (duration: 6.57065ms)","trace[102058857] 'applied index is now lower than readState.Index' (duration: 275.264743ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:11.450195Z","caller":"traceutil/trace.go:171","msg":"trace[1602737509] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"284.692272ms","start":"2026-07-14T23:47:11.165491Z","end":"2026-07-14T23:47:11.450183Z","steps":["trace[1602737509] 'process raft request' (duration: 282.772325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:11.450409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.590733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-14T23:47:11.45049Z","caller":"traceutil/trace.go:171","msg":"trace[330830188] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25877; }","duration":"273.707476ms","start":"2026-07-14T23:47:11.176763Z","end":"2026-07-14T23:47:11.45047Z","steps":["trace[330830188] 'agreement among raft nodes before linearized reading' (duration: 273.487941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:11.450706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.467898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-07-14T23:47:11.450736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.457186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-14T23:47:11.450795Z","caller":"traceutil/trace.go:171","msg":"trace[1082043663] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25877; }","duration":"282.549499ms","start":"2026-07-14T23:47:11.168197Z","end":"2026-07-14T23:47:11.450746Z","steps":["trace[1082043663] 'agreement among raft nodes before linearized reading' (duration: 282.381435ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:11.450807Z","caller":"traceutil/trace.go:171","msg":"trace[2077934096] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25877; }","duration":"185.558799ms","start":"2026-07-14T23:47:11.265235Z","end":"2026-07-14T23:47:11.450794Z","steps":["trace[2077934096] 'agreement among raft nodes before linearized reading' (duration: 185.428455ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:13.019404Z","caller":"traceutil/trace.go:171","msg":"trace[1552693237] linearizableReadLoop","detail":"{readStateIndex:26741; appliedIndex:26740; }","duration":"132.921562ms","start":"2026-07-14T23:47:12.886452Z","end":"2026-07-14T23:47:13.019373Z","steps":["trace[1552693237] 'read index received' (duration: 132.648796ms)","trace[1552693237] 'applied index is now lower than readState.Index' (duration: 271.576µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:13.019523Z","caller":"traceutil/trace.go:171","msg":"trace[860961461] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"146.305107ms","start":"2026-07-14T23:47:12.87317Z","end":"2026-07-14T23:47:13.019476Z","steps":["trace[860961461] 'process raft request' (duration: 146.008109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:13.019913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.432584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f586334-964d-44b3-af9a-772366e7c5ee\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-07-14T23:47:13.020019Z","caller":"traceutil/trace.go:171","msg":"trace[1761169032] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f586334-964d-44b3-af9a-772366e7c5ee; range_end:; response_count:1; response_revision:25888; }","duration":"133.578787ms","start":"2026-07-14T23:47:12.886414Z","end":"2026-07-14T23:47:13.019992Z","steps":["trace[1761169032] 'agreement among raft nodes before linearized reading' (duration: 133.079415ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:13.37618Z","caller":"traceutil/trace.go:171","msg":"trace[1889603368] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"130.535154ms","start":"2026-07-14T23:47:13.245623Z","end":"2026-07-14T23:47:13.376158Z","steps":["trace[1889603368] 'process raft request' (duration: 130.448252ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:13.376386Z","caller":"traceutil/trace.go:171","msg":"trace[710173051] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"130.402652ms","start":"2026-07-14T23:47:13.245967Z","end":"2026-07-14T23:47:13.37637Z","steps":["trace[710173051] 'process raft request' (duration: 130.142705ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:13.376479Z","caller":"traceutil/trace.go:171","msg":"trace[828890728] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"131.718763ms","start":"2026-07-14T23:47:13.244743Z","end":"2026-07-14T23:47:13.376462Z","steps":["trace[828890728] 'process raft request' (duration: 131.20746ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:13.376795Z","caller":"traceutil/trace.go:171","msg":"trace[1221345046] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"130.404592ms","start":"2026-07-14T23:47:13.246384Z","end":"2026-07-14T23:47:13.376789Z","steps":["trace[1221345046] 'process raft request' (duration: 129.948431ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:17.392333Z","caller":"traceutil/trace.go:171","msg":"trace[1654781213] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"127.405778ms","start":"2026-07-14T23:47:17.264903Z","end":"2026-07-14T23:47:17.392309Z","steps":["trace[1654781213] 'process raft request' (duration: 127.006529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.671779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.197329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:47:17.671894Z","caller":"traceutil/trace.go:171","msg":"trace[1315339242] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"275.586011ms","start":"2026-07-14T23:47:17.396289Z","end":"2026-07-14T23:47:17.671875Z","steps":["trace[1315339242] 'process raft request' (duration: 123.205487ms)","trace[1315339242] 'compare' (duration: 151.906322ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:17.758238Z","caller":"traceutil/trace.go:171","msg":"trace[733214261] linearizableReadLoop","detail":"{readStateIndex:26801; appliedIndex:26797; }","duration":"353.209653ms","start":"2026-07-14T23:47:17.405Z","end":"2026-07-14T23:47:17.75821Z","steps":["trace[733214261] 'read index received' (duration: 114.501296ms)","trace[733214261] 'applied index is now lower than readState.Index' (duration: 238.707177ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:17.758694Z","caller":"traceutil/trace.go:171","msg":"trace[719686864] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"362.122669ms","start":"2026-07-14T23:47:17.396551Z","end":"2026-07-14T23:47:17.758674Z","steps":["trace[719686864] 'process raft request' (duration: 361.359171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.758834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:17.396532Z","time spent":"362.216391ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:47:17.759098Z","caller":"traceutil/trace.go:171","msg":"trace[2032512139] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"362.357004ms","start":"2026-07-14T23:47:17.396725Z","end":"2026-07-14T23:47:17.759082Z","steps":["trace[2032512139] 'process raft request' (duration: 361.331979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.759229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:17.396703Z","time spent":"362.438336ms","remote":"127.0.0.1:45528","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-07-14T23:47:17.759344Z","caller":"traceutil/trace.go:171","msg":"trace[1454852636] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"361.578846ms","start":"2026-07-14T23:47:17.397752Z","end":"2026-07-14T23:47:17.759331Z","steps":["trace[1454852636] 'process raft request' (duration: 360.354356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.759421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:17.39774Z","time spent":"361.644037ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:47:17.759647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.774487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-6b69954d59-rm58z.18c24cc383ed7761\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-07-14T23:47:17.759705Z","caller":"traceutil/trace.go:171","msg":"trace[453790736] range","detail":"{range_begin:/registry/events/openstack/magnum-api-6b69954d59-rm58z.18c24cc383ed7761; range_end:; response_count:1; response_revision:25947; }","duration":"353.875588ms","start":"2026-07-14T23:47:17.405817Z","end":"2026-07-14T23:47:17.759692Z","steps":["trace[453790736] 'agreement among raft nodes before linearized reading' (duration: 353.711184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.759734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:17.405803Z","time spent":"353.92626ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":878,"request content":"key:\"/registry/events/openstack/magnum-api-6b69954d59-rm58z.18c24cc383ed7761\" "} {"level":"info","ts":"2026-07-14T23:47:17.759862Z","caller":"traceutil/trace.go:171","msg":"trace[277564522] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"246.489356ms","start":"2026-07-14T23:47:17.513366Z","end":"2026-07-14T23:47:17.759856Z","steps":["trace[277564522] 'process raft request' (duration: 244.794736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.759972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.971295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-6b69954d59-rm58z\" ","response":"range_response_count:1 size:8862"} {"level":"info","ts":"2026-07-14T23:47:17.759992Z","caller":"traceutil/trace.go:171","msg":"trace[610438313] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-6b69954d59-rm58z; range_end:; response_count:1; response_revision:25947; }","duration":"355.006185ms","start":"2026-07-14T23:47:17.404981Z","end":"2026-07-14T23:47:17.759987Z","steps":["trace[610438313] 'agreement among raft nodes before linearized reading' (duration: 354.945344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.760005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:17.404971Z","time spent":"355.031027ms","remote":"127.0.0.1:45450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":8885,"request content":"key:\"/registry/pods/openstack/magnum-api-6b69954d59-rm58z\" "} {"level":"warn","ts":"2026-07-14T23:47:17.760113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.792257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:17.760126Z","caller":"traceutil/trace.go:171","msg":"trace[305084879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25947; }","duration":"174.839028ms","start":"2026-07-14T23:47:17.585283Z","end":"2026-07-14T23:47:17.760122Z","steps":["trace[305084879] 'agreement among raft nodes before linearized reading' (duration: 174.809828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.760356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.560203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-14T23:47:17.760376Z","caller":"traceutil/trace.go:171","msg":"trace[136005815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25947; }","duration":"290.613114ms","start":"2026-07-14T23:47:17.469755Z","end":"2026-07-14T23:47:17.760368Z","steps":["trace[136005815] 'agreement among raft nodes before linearized reading' (duration: 290.559933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:17.760357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.829213ms","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-07-14T23:47:17.760503Z","caller":"traceutil/trace.go:171","msg":"trace[1857060929] 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:25947; }","duration":"260.993668ms","start":"2026-07-14T23:47:17.499471Z","end":"2026-07-14T23:47:17.760464Z","steps":["trace[1857060929] 'agreement among raft nodes before linearized reading' (duration: 260.773181ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:23.191183Z","caller":"traceutil/trace.go:171","msg":"trace[1436126054] transaction","detail":"{read_only:false; response_revision:25998; number_of_response:1; }","duration":"121.465584ms","start":"2026-07-14T23:47:23.069697Z","end":"2026-07-14T23:47:23.191162Z","steps":["trace[1436126054] 'process raft request' (duration: 121.343651ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:23.542168Z","caller":"traceutil/trace.go:171","msg":"trace[2061588571] linearizableReadLoop","detail":"{readStateIndex:26855; appliedIndex:26854; }","duration":"110.741624ms","start":"2026-07-14T23:47:23.431408Z","end":"2026-07-14T23:47:23.54215Z","steps":["trace[2061588571] 'read index received' (duration: 110.655492ms)","trace[2061588571] 'applied index is now lower than readState.Index' (duration: 85.552µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:23.542346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.911309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-14T23:47:23.54237Z","caller":"traceutil/trace.go:171","msg":"trace[302249545] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25999; }","duration":"110.99414ms","start":"2026-07-14T23:47:23.431369Z","end":"2026-07-14T23:47:23.542363Z","steps":["trace[302249545] 'agreement among raft nodes before linearized reading' (duration: 110.853737ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:23.542669Z","caller":"traceutil/trace.go:171","msg":"trace[352862381] transaction","detail":"{read_only:false; response_revision:25999; number_of_response:1; }","duration":"130.620527ms","start":"2026-07-14T23:47:23.412041Z","end":"2026-07-14T23:47:23.542661Z","steps":["trace[352862381] 'process raft request' (duration: 129.95171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:23.835286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.362266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:23.835354Z","caller":"traceutil/trace.go:171","msg":"trace[253001794] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25999; }","duration":"103.468799ms","start":"2026-07-14T23:47:23.731869Z","end":"2026-07-14T23:47:23.835338Z","steps":["trace[253001794] 'count revisions from in-memory index tree' (duration: 103.221903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:23.835484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.515399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:47:23.835533Z","caller":"traceutil/trace.go:171","msg":"trace[1971089689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25999; }","duration":"143.607131ms","start":"2026-07-14T23:47:23.691914Z","end":"2026-07-14T23:47:23.835521Z","steps":["trace[1971089689] 'range keys from in-memory index tree' (duration: 143.373586ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:24.026482Z","caller":"traceutil/trace.go:171","msg":"trace[503897115] linearizableReadLoop","detail":"{readStateIndex:26857; appliedIndex:26856; }","duration":"165.961353ms","start":"2026-07-14T23:47:23.860505Z","end":"2026-07-14T23:47:24.026466Z","steps":["trace[503897115] 'read index received' (duration: 143.422877ms)","trace[503897115] 'applied index is now lower than readState.Index' (duration: 22.537786ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:24.026544Z","caller":"traceutil/trace.go:171","msg":"trace[160233386] transaction","detail":"{read_only:false; response_revision:26001; number_of_response:1; }","duration":"186.909451ms","start":"2026-07-14T23:47:23.839594Z","end":"2026-07-14T23:47:24.026503Z","steps":["trace[160233386] 'process raft request' (duration: 164.320263ms)","trace[160233386] 'compare' (duration: 22.473105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:24.026609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.344075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-14T23:47:24.026613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.686777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-14T23:47:24.026625Z","caller":"traceutil/trace.go:171","msg":"trace[2108879468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26001; }","duration":"242.400265ms","start":"2026-07-14T23:47:23.78422Z","end":"2026-07-14T23:47:24.02662Z","steps":["trace[2108879468] 'agreement among raft nodes before linearized reading' (duration: 242.305073ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:24.026644Z","caller":"traceutil/trace.go:171","msg":"trace[2059336374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26001; }","duration":"236.731728ms","start":"2026-07-14T23:47:23.789901Z","end":"2026-07-14T23:47:24.026633Z","steps":["trace[2059336374] 'agreement among raft nodes before linearized reading' (duration: 236.622775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:24.02669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.15594ms","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-07-14T23:47:24.026702Z","caller":"traceutil/trace.go:171","msg":"trace[1490157469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26001; }","duration":"242.18577ms","start":"2026-07-14T23:47:23.784512Z","end":"2026-07-14T23:47:24.026698Z","steps":["trace[1490157469] 'agreement among raft nodes before linearized reading' (duration: 242.151369ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:24.029483Z","caller":"traceutil/trace.go:171","msg":"trace[1304684816] transaction","detail":"{read_only:false; response_revision:26002; number_of_response:1; }","duration":"165.034291ms","start":"2026-07-14T23:47:23.864431Z","end":"2026-07-14T23:47:24.029465Z","steps":["trace[1304684816] 'process raft request' (duration: 164.921568ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:29.25754Z","caller":"traceutil/trace.go:171","msg":"trace[73877965] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"224.863612ms","start":"2026-07-14T23:47:29.032653Z","end":"2026-07-14T23:47:29.257517Z","steps":["trace[73877965] 'process raft request' (duration: 224.712218ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:29.336216Z","caller":"traceutil/trace.go:171","msg":"trace[1098231239] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"122.50526ms","start":"2026-07-14T23:47:29.213247Z","end":"2026-07-14T23:47:29.335752Z","steps":["trace[1098231239] 'process raft request' (duration: 121.693659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:29.712348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.768816ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"warn","ts":"2026-07-14T23:47:29.712427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:47:29.411364Z","time spent":"301.059058ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-14T23:47:29.765413Z","caller":"traceutil/trace.go:171","msg":"trace[1613449432] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"205.595035ms","start":"2026-07-14T23:47:29.559791Z","end":"2026-07-14T23:47:29.765386Z","steps":["trace[1613449432] 'process raft request' (duration: 205.364229ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:29.765459Z","caller":"traceutil/trace.go:171","msg":"trace[1496466197] linearizableReadLoop","detail":"{readStateIndex:26910; appliedIndex:26909; }","duration":"115.959672ms","start":"2026-07-14T23:47:29.649443Z","end":"2026-07-14T23:47:29.765402Z","steps":["trace[1496466197] 'read index received' (duration: 115.903321ms)","trace[1496466197] 'applied index is now lower than readState.Index' (duration: 49.491µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:29.765909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.423233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:29.765953Z","caller":"traceutil/trace.go:171","msg":"trace[471146039] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26052; }","duration":"116.555876ms","start":"2026-07-14T23:47:29.649387Z","end":"2026-07-14T23:47:29.765942Z","steps":["trace[471146039] 'agreement among raft nodes before linearized reading' (duration: 116.359941ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:29.929864Z","caller":"traceutil/trace.go:171","msg":"trace[1083960609] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"151.973515ms","start":"2026-07-14T23:47:29.777862Z","end":"2026-07-14T23:47:29.929835Z","steps":["trace[1083960609] 'process raft request' (duration: 151.880392ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:29.929866Z","caller":"traceutil/trace.go:171","msg":"trace[1701165092] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"216.632793ms","start":"2026-07-14T23:47:29.71321Z","end":"2026-07-14T23:47:29.929843Z","steps":["trace[1701165092] 'process raft request' (duration: 120.972093ms)","trace[1701165092] 'compare' (duration: 95.067835ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:29.984964Z","caller":"traceutil/trace.go:171","msg":"trace[455008201] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"105.322073ms","start":"2026-07-14T23:47:29.879613Z","end":"2026-07-14T23:47:29.984935Z","steps":["trace[455008201] 'process raft request' (duration: 105.167769ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:34.929295Z","caller":"traceutil/trace.go:171","msg":"trace[1430562206] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"110.224321ms","start":"2026-07-14T23:47:34.81904Z","end":"2026-07-14T23:47:34.929264Z","steps":["trace[1430562206] 'process raft request' (duration: 82.918529ms)","trace[1430562206] 'compare' (duration: 27.022176ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:34.94203Z","caller":"traceutil/trace.go:171","msg":"trace[1148935815] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"120.949072ms","start":"2026-07-14T23:47:34.821056Z","end":"2026-07-14T23:47:34.942005Z","steps":["trace[1148935815] 'process raft request' (duration: 120.479831ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:34.942244Z","caller":"traceutil/trace.go:171","msg":"trace[471105749] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"122.165142ms","start":"2026-07-14T23:47:34.820052Z","end":"2026-07-14T23:47:34.942217Z","steps":["trace[471105749] 'process raft request' (duration: 121.326802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:37.06501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.177035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:47:37.065098Z","caller":"traceutil/trace.go:171","msg":"trace[707033090] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26127; }","duration":"151.318858ms","start":"2026-07-14T23:47:36.913756Z","end":"2026-07-14T23:47:37.065075Z","steps":["trace[707033090] 'count revisions from in-memory index tree' (duration: 151.090582ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:37.065243Z","caller":"traceutil/trace.go:171","msg":"trace[922460750] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"130.249238ms","start":"2026-07-14T23:47:36.934958Z","end":"2026-07-14T23:47:37.065208Z","steps":["trace[922460750] 'process raft request' (duration: 42.793907ms)","trace[922460750] 'compare' (duration: 87.299127ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:37.207407Z","caller":"traceutil/trace.go:171","msg":"trace[729344468] linearizableReadLoop","detail":"{readStateIndex:26990; appliedIndex:26988; }","duration":"218.837265ms","start":"2026-07-14T23:47:36.988553Z","end":"2026-07-14T23:47:37.20739Z","steps":["trace[729344468] 'read index received' (duration: 210.785261ms)","trace[729344468] 'applied index is now lower than readState.Index' (duration: 8.051164ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:37.207437Z","caller":"traceutil/trace.go:171","msg":"trace[1935483688] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"260.208047ms","start":"2026-07-14T23:47:36.947214Z","end":"2026-07-14T23:47:37.207422Z","steps":["trace[1935483688] 'process raft request' (duration: 252.130822ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:37.207492Z","caller":"traceutil/trace.go:171","msg":"trace[1230485003] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"260.134406ms","start":"2026-07-14T23:47:36.947321Z","end":"2026-07-14T23:47:37.207456Z","steps":["trace[1230485003] 'process raft request' (duration: 260.029073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:37.207548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.988149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-14T23:47:37.207574Z","caller":"traceutil/trace.go:171","msg":"trace[1054425747] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26130; }","duration":"219.02523ms","start":"2026-07-14T23:47:36.988541Z","end":"2026-07-14T23:47:37.207566Z","steps":["trace[1054425747] 'agreement among raft nodes before linearized reading' (duration: 218.914787ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:40.329257Z","caller":"traceutil/trace.go:171","msg":"trace[1336052209] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"126.599379ms","start":"2026-07-14T23:47:40.202636Z","end":"2026-07-14T23:47:40.329235Z","steps":["trace[1336052209] 'process raft request' (duration: 60.362343ms)","trace[1336052209] 'compare' (duration: 66.095583ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:40.329524Z","caller":"traceutil/trace.go:171","msg":"trace[1644981622] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"126.247481ms","start":"2026-07-14T23:47:40.203265Z","end":"2026-07-14T23:47:40.329512Z","steps":["trace[1644981622] 'process raft request' (duration: 125.938384ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:40.329593Z","caller":"traceutil/trace.go:171","msg":"trace[1629083733] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"125.304388ms","start":"2026-07-14T23:47:40.204262Z","end":"2026-07-14T23:47:40.329567Z","steps":["trace[1629083733] 'process raft request' (duration: 125.253827ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:40.329616Z","caller":"traceutil/trace.go:171","msg":"trace[373577366] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"126.187859ms","start":"2026-07-14T23:47:40.203394Z","end":"2026-07-14T23:47:40.329582Z","steps":["trace[373577366] 'process raft request' (duration: 126.015924ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:40.922532Z","caller":"traceutil/trace.go:171","msg":"trace[1202897631] linearizableReadLoop","detail":"{readStateIndex:27020; appliedIndex:27018; }","duration":"102.601908ms","start":"2026-07-14T23:47:40.819902Z","end":"2026-07-14T23:47:40.922503Z","steps":["trace[1202897631] 'read index received' (duration: 21.154983ms)","trace[1202897631] 'applied index is now lower than readState.Index' (duration: 81.445405ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:40.922749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.817263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:47:40.922839Z","caller":"traceutil/trace.go:171","msg":"trace[902339855] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:26159; }","duration":"102.968056ms","start":"2026-07-14T23:47:40.819849Z","end":"2026-07-14T23:47:40.922818Z","steps":["trace[902339855] 'agreement among raft nodes before linearized reading' (duration: 102.764941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:45.568698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.788691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:47:45.568963Z","caller":"traceutil/trace.go:171","msg":"trace[1048730079] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"161.566566ms","start":"2026-07-14T23:47:45.407375Z","end":"2026-07-14T23:47:45.568941Z","steps":["trace[1048730079] 'process raft request' (duration: 161.499365ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:45.568977Z","caller":"traceutil/trace.go:171","msg":"trace[1596144384] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"248.276419ms","start":"2026-07-14T23:47:45.320674Z","end":"2026-07-14T23:47:45.56895Z","steps":["trace[1596144384] 'process raft request' (duration: 112.154489ms)","trace[1596144384] 'compare' (duration: 135.686049ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:56.604526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.928181ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-14T23:47:56.604607Z","caller":"traceutil/trace.go:171","msg":"trace[956682184] linearizableReadLoop","detail":"{readStateIndex:27142; appliedIndex:27141; }","duration":"210.109813ms","start":"2026-07-14T23:47:56.394482Z","end":"2026-07-14T23:47:56.604592Z","steps":["trace[956682184] 'read index received' (duration: 61.042608ms)","trace[956682184] 'applied index is now lower than readState.Index' (duration: 149.066155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:47:56.604838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.344039ms","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-07-14T23:47:56.604855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.241979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:47:56.60489Z","caller":"traceutil/trace.go:171","msg":"trace[1568378817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26278; }","duration":"208.29834ms","start":"2026-07-14T23:47:56.396581Z","end":"2026-07-14T23:47:56.60488Z","steps":["trace[1568378817] 'agreement among raft nodes before linearized reading' (duration: 208.198637ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:56.604888Z","caller":"traceutil/trace.go:171","msg":"trace[814931315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26278; }","duration":"210.438631ms","start":"2026-07-14T23:47:56.394435Z","end":"2026-07-14T23:47:56.604874Z","steps":["trace[814931315] 'agreement among raft nodes before linearized reading' (duration: 210.260616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:56.605027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.746138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-6b69954d59-rm58z.18c24cc383ed7761\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-07-14T23:47:56.6051Z","caller":"traceutil/trace.go:171","msg":"trace[1134491786] range","detail":"{range_begin:/registry/events/openstack/magnum-api-6b69954d59-rm58z.18c24cc383ed7761; range_end:; response_count:1; response_revision:26278; }","duration":"198.845431ms","start":"2026-07-14T23:47:56.406235Z","end":"2026-07-14T23:47:56.60508Z","steps":["trace[1134491786] 'agreement among raft nodes before linearized reading' (duration: 198.631196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:56.605143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.116812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-14T23:47:56.605182Z","caller":"traceutil/trace.go:171","msg":"trace[1893786178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26278; }","duration":"205.150963ms","start":"2026-07-14T23:47:56.400016Z","end":"2026-07-14T23:47:56.605167Z","steps":["trace[1893786178] 'agreement among raft nodes before linearized reading' (duration: 205.064301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:47:56.605388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.945523ms","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-07-14T23:47:56.60546Z","caller":"traceutil/trace.go:171","msg":"trace[1127052757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26278; }","duration":"206.052135ms","start":"2026-07-14T23:47:56.399391Z","end":"2026-07-14T23:47:56.605443Z","steps":["trace[1127052757] 'agreement among raft nodes before linearized reading' (duration: 205.792759ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:56.761134Z","caller":"traceutil/trace.go:171","msg":"trace[1308646642] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"152.822824ms","start":"2026-07-14T23:47:56.608283Z","end":"2026-07-14T23:47:56.761105Z","steps":["trace[1308646642] 'process raft request' (duration: 136.023087ms)","trace[1308646642] 'compare' (duration: 16.542761ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:47:56.761148Z","caller":"traceutil/trace.go:171","msg":"trace[780510308] transaction","detail":"{read_only:false; response_revision:26282; number_of_response:1; }","duration":"152.488707ms","start":"2026-07-14T23:47:56.608637Z","end":"2026-07-14T23:47:56.761125Z","steps":["trace[780510308] 'process raft request' (duration: 152.414595ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:56.761175Z","caller":"traceutil/trace.go:171","msg":"trace[2097101873] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"152.827184ms","start":"2026-07-14T23:47:56.608334Z","end":"2026-07-14T23:47:56.761161Z","steps":["trace[2097101873] 'process raft request' (duration: 152.676221ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:47:56.761292Z","caller":"traceutil/trace.go:171","msg":"trace[2052379353] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"151.635927ms","start":"2026-07-14T23:47:56.609616Z","end":"2026-07-14T23:47:56.761252Z","steps":["trace[2052379353] 'process raft request' (duration: 151.468142ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:06.908849Z","caller":"traceutil/trace.go:171","msg":"trace[1789694023] linearizableReadLoop","detail":"{readStateIndex:27231; appliedIndex:27230; }","duration":"107.607279ms","start":"2026-07-14T23:48:06.80122Z","end":"2026-07-14T23:48:06.908827Z","steps":["trace[1789694023] 'read index received' (duration: 107.354583ms)","trace[1789694023] 'applied index is now lower than readState.Index' (duration: 251.866µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:48:06.908971Z","caller":"traceutil/trace.go:171","msg":"trace[1687197224] transaction","detail":"{read_only:false; response_revision:26365; number_of_response:1; }","duration":"182.949945ms","start":"2026-07-14T23:48:06.726008Z","end":"2026-07-14T23:48:06.908958Z","steps":["trace[1687197224] 'process raft request' (duration: 182.627117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:06.909444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.148492ms","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-07-14T23:48:06.909544Z","caller":"traceutil/trace.go:171","msg":"trace[688407578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26365; }","duration":"108.296615ms","start":"2026-07-14T23:48:06.801221Z","end":"2026-07-14T23:48:06.909518Z","steps":["trace[688407578] 'agreement among raft nodes before linearized reading' (duration: 107.982798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:06.909777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.496089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-14T23:48:06.909835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.614534ms","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-07-14T23:48:06.909894Z","caller":"traceutil/trace.go:171","msg":"trace[56573304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26365; }","duration":"108.718446ms","start":"2026-07-14T23:48:06.801157Z","end":"2026-07-14T23:48:06.909876Z","steps":["trace[56573304] 'agreement among raft nodes before linearized reading' (duration: 108.557892ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:06.909854Z","caller":"traceutil/trace.go:171","msg":"trace[701725258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26365; }","duration":"103.609672ms","start":"2026-07-14T23:48:06.806223Z","end":"2026-07-14T23:48:06.909833Z","steps":["trace[701725258] 'agreement among raft nodes before linearized reading' (duration: 103.397807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:06.909976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.964144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:48:06.910038Z","caller":"traceutil/trace.go:171","msg":"trace[656854655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26365; }","duration":"105.055917ms","start":"2026-07-14T23:48:06.804969Z","end":"2026-07-14T23:48:06.910025Z","steps":["trace[656854655] 'agreement among raft nodes before linearized reading' (duration: 104.879292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:07.159117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.212714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:48:07.159249Z","caller":"traceutil/trace.go:171","msg":"trace[661368274] transaction","detail":"{read_only:false; response_revision:26366; number_of_response:1; }","duration":"244.271272ms","start":"2026-07-14T23:48:06.914951Z","end":"2026-07-14T23:48:07.159222Z","steps":["trace[661368274] 'process raft request' (duration: 109.854503ms)","trace[661368274] 'compare' (duration: 133.571609ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:48:07.201685Z","caller":"traceutil/trace.go:171","msg":"trace[885112793] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"286.057084ms","start":"2026-07-14T23:48:06.915607Z","end":"2026-07-14T23:48:07.201664Z","steps":["trace[885112793] 'process raft request' (duration: 285.88282ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.201982Z","caller":"traceutil/trace.go:171","msg":"trace[1048391440] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"283.593546ms","start":"2026-07-14T23:48:06.918376Z","end":"2026-07-14T23:48:07.201969Z","steps":["trace[1048391440] 'process raft request' (duration: 283.492382ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.201982Z","caller":"traceutil/trace.go:171","msg":"trace[1873123160] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"284.154009ms","start":"2026-07-14T23:48:06.917792Z","end":"2026-07-14T23:48:07.201946Z","steps":["trace[1873123160] 'process raft request' (duration: 284.006925ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.202004Z","caller":"traceutil/trace.go:171","msg":"trace[1973986462] transaction","detail":"{read_only:false; response_revision:26368; number_of_response:1; }","duration":"285.521672ms","start":"2026-07-14T23:48:06.916459Z","end":"2026-07-14T23:48:07.201981Z","steps":["trace[1973986462] 'process raft request' (duration: 285.144302ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.202238Z","caller":"traceutil/trace.go:171","msg":"trace[765590410] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"283.053812ms","start":"2026-07-14T23:48:06.919171Z","end":"2026-07-14T23:48:07.202225Z","steps":["trace[765590410] 'process raft request' (duration: 282.921969ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.202283Z","caller":"traceutil/trace.go:171","msg":"trace[1189302561] linearizableReadLoop","detail":"{readStateIndex:27238; appliedIndex:27231; }","duration":"243.325119ms","start":"2026-07-14T23:48:06.958946Z","end":"2026-07-14T23:48:07.202271Z","steps":["trace[1189302561] 'read index received' (duration: 65.952828ms)","trace[1189302561] 'applied index is now lower than readState.Index' (duration: 177.371101ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:48:07.202259Z","caller":"traceutil/trace.go:171","msg":"trace[2109187081] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"283.600437ms","start":"2026-07-14T23:48:06.918608Z","end":"2026-07-14T23:48:07.202209Z","steps":["trace[2109187081] 'process raft request' (duration: 283.30922ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.202451Z","caller":"traceutil/trace.go:171","msg":"trace[313197654] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"201.950966ms","start":"2026-07-14T23:48:07.000368Z","end":"2026-07-14T23:48:07.202319Z","steps":["trace[313197654] 'process raft request' (duration: 201.821833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:07.202493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.462857ms","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-07-14T23:48:07.202537Z","caller":"traceutil/trace.go:171","msg":"trace[1073183554] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26373; }","duration":"105.564889ms","start":"2026-07-14T23:48:07.09696Z","end":"2026-07-14T23:48:07.202525Z","steps":["trace[1073183554] 'agreement among raft nodes before linearized reading' (duration: 105.475077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:07.202529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.579925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-14T23:48:07.202589Z","caller":"traceutil/trace.go:171","msg":"trace[1685135365] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26373; }","duration":"243.648867ms","start":"2026-07-14T23:48:06.958916Z","end":"2026-07-14T23:48:07.202565Z","steps":["trace[1685135365] 'agreement among raft nodes before linearized reading' (duration: 243.460193ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.523138Z","caller":"traceutil/trace.go:171","msg":"trace[1769473426] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"313.355687ms","start":"2026-07-14T23:48:07.209738Z","end":"2026-07-14T23:48:07.523094Z","steps":["trace[1769473426] 'process raft request' (duration: 305.479076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:07.523277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:07.209726Z","time spent":"313.479129ms","remote":"127.0.0.1:45744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3020,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:48:07.817632Z","caller":"traceutil/trace.go:171","msg":"trace[690014759] linearizableReadLoop","detail":"{readStateIndex:27241; appliedIndex:27239; }","duration":"394.268827ms","start":"2026-07-14T23:48:07.423337Z","end":"2026-07-14T23:48:07.817606Z","steps":["trace[690014759] 'read index received' (duration: 91.862756ms)","trace[690014759] 'applied index is now lower than readState.Index' (duration: 302.404971ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:48:07.817709Z","caller":"traceutil/trace.go:171","msg":"trace[1812640223] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"357.218499ms","start":"2026-07-14T23:48:07.460436Z","end":"2026-07-14T23:48:07.817655Z","steps":["trace[1812640223] 'process raft request' (duration: 357.090036ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.817827Z","caller":"traceutil/trace.go:171","msg":"trace[1830638559] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"344.617184ms","start":"2026-07-14T23:48:07.473193Z","end":"2026-07-14T23:48:07.817811Z","steps":["trace[1830638559] 'process raft request' (duration: 344.376978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:07.817861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:07.460408Z","time spent":"357.366842ms","remote":"127.0.0.1:45528","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-07-14T23:48:07.817836Z","caller":"traceutil/trace.go:171","msg":"trace[1937255814] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"479.093394ms","start":"2026-07-14T23:48:07.338651Z","end":"2026-07-14T23:48:07.817744Z","steps":["trace[1937255814] 'process raft request' (duration: 440.871409ms)","trace[1937255814] 'compare' (duration: 37.877197ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:48:07.817948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:07.473176Z","time spent":"344.684975ms","remote":"127.0.0.1:45528","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-07-14T23:48:07.818043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.669877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:48:07.818145Z","caller":"traceutil/trace.go:171","msg":"trace[504474878] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26377; }","duration":"394.81479ms","start":"2026-07-14T23:48:07.423308Z","end":"2026-07-14T23:48:07.818123Z","steps":["trace[504474878] 'agreement among raft nodes before linearized reading' (duration: 394.654157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:07.818191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:07.338627Z","time spent":"479.36181ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:48:07.818214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:07.423295Z","time spent":"394.883122ms","remote":"127.0.0.1:55410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:48:07.826737Z","caller":"traceutil/trace.go:171","msg":"trace[1023553527] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"173.613559ms","start":"2026-07-14T23:48:07.653074Z","end":"2026-07-14T23:48:07.826687Z","steps":["trace[1023553527] 'process raft request' (duration: 173.116916ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:07.826743Z","caller":"traceutil/trace.go:171","msg":"trace[1137693550] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"172.801279ms","start":"2026-07-14T23:48:07.653892Z","end":"2026-07-14T23:48:07.826693Z","steps":["trace[1137693550] 'process raft request' (duration: 172.628374ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:08.080194Z","caller":"traceutil/trace.go:171","msg":"trace[963368002] linearizableReadLoop","detail":"{readStateIndex:27246; appliedIndex:27245; }","duration":"165.359408ms","start":"2026-07-14T23:48:07.914803Z","end":"2026-07-14T23:48:08.080163Z","steps":["trace[963368002] 'read index received' (duration: 161.484235ms)","trace[963368002] 'applied index is now lower than readState.Index' (duration: 3.873933ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:48:08.080336Z","caller":"traceutil/trace.go:171","msg":"trace[480923700] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"180.953447ms","start":"2026-07-14T23:48:07.899353Z","end":"2026-07-14T23:48:08.080307Z","steps":["trace[480923700] 'process raft request' (duration: 176.9511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:08.080448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.625636ms","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-07-14T23:48:08.080525Z","caller":"traceutil/trace.go:171","msg":"trace[1380136240] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26380; }","duration":"165.756499ms","start":"2026-07-14T23:48:07.914746Z","end":"2026-07-14T23:48:08.080502Z","steps":["trace[1380136240] 'agreement among raft nodes before linearized reading' (duration: 165.518273ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:08.238782Z","caller":"traceutil/trace.go:171","msg":"trace[960548219] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"130.865262ms","start":"2026-07-14T23:48:08.107888Z","end":"2026-07-14T23:48:08.238754Z","steps":["trace[960548219] 'process raft request' (duration: 120.134222ms)","trace[960548219] 'compare' (duration: 10.548206ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:48:08.542099Z","caller":"traceutil/trace.go:171","msg":"trace[1161296920] linearizableReadLoop","detail":"{readStateIndex:27248; appliedIndex:27247; }","duration":"174.967492ms","start":"2026-07-14T23:48:08.367106Z","end":"2026-07-14T23:48:08.542074Z","steps":["trace[1161296920] 'read index received' (duration: 174.817159ms)","trace[1161296920] 'applied index is now lower than readState.Index' (duration: 149.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:48:08.542203Z","caller":"traceutil/trace.go:171","msg":"trace[333116999] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"221.780596ms","start":"2026-07-14T23:48:08.320394Z","end":"2026-07-14T23:48:08.542175Z","steps":["trace[333116999] 'process raft request' (duration: 221.53402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:08.542397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.279499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:48:08.54251Z","caller":"traceutil/trace.go:171","msg":"trace[1951172077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26382; }","duration":"175.413353ms","start":"2026-07-14T23:48:08.367062Z","end":"2026-07-14T23:48:08.542475Z","steps":["trace[1951172077] 'agreement among raft nodes before linearized reading' (duration: 175.140956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:08.542538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.416923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-6b69954d59-rm58z.18c24cc383ed7761\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-07-14T23:48:08.542627Z","caller":"traceutil/trace.go:171","msg":"trace[1436781640] range","detail":"{range_begin:/registry/events/openstack/magnum-api-6b69954d59-rm58z.18c24cc383ed7761; range_end:; response_count:1; response_revision:26382; }","duration":"135.519115ms","start":"2026-07-14T23:48:08.407084Z","end":"2026-07-14T23:48:08.542603Z","steps":["trace[1436781640] 'agreement among raft nodes before linearized reading' (duration: 135.216688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:08.861942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.79853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:48:08.862123Z","caller":"traceutil/trace.go:171","msg":"trace[1884139115] transaction","detail":"{read_only:false; response_revision:26384; number_of_response:1; }","duration":"315.162029ms","start":"2026-07-14T23:48:08.546935Z","end":"2026-07-14T23:48:08.862097Z","steps":["trace[1884139115] 'process raft request' (duration: 149.129735ms)","trace[1884139115] 'compare' (duration: 165.525232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:48:08.862184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:08.546915Z","time spent":"315.241582ms","remote":"127.0.0.1:45528","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-07-14T23:48:08.862248Z","caller":"traceutil/trace.go:171","msg":"trace[1962749229] linearizableReadLoop","detail":"{readStateIndex:27250; appliedIndex:27249; }","duration":"276.636017ms","start":"2026-07-14T23:48:08.58558Z","end":"2026-07-14T23:48:08.862216Z","steps":["trace[1962749229] 'read index received' (duration: 110.444569ms)","trace[1962749229] 'applied index is now lower than readState.Index' (duration: 166.186988ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:48:08.862426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.844792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:48:08.862508Z","caller":"traceutil/trace.go:171","msg":"trace[2127025176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26384; }","duration":"276.919324ms","start":"2026-07-14T23:48:08.585564Z","end":"2026-07-14T23:48:08.862483Z","steps":["trace[2127025176] 'agreement among raft nodes before linearized reading' (duration: 276.744519ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:08.863372Z","caller":"traceutil/trace.go:171","msg":"trace[888520043] transaction","detail":"{read_only:false; response_revision:26385; number_of_response:1; }","duration":"187.813923ms","start":"2026-07-14T23:48:08.674942Z","end":"2026-07-14T23:48:08.862756Z","steps":["trace[888520043] 'process raft request' (duration: 187.357162ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:09.294477Z","caller":"traceutil/trace.go:171","msg":"trace[798132227] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"127.603293ms","start":"2026-07-14T23:48:09.166844Z","end":"2026-07-14T23:48:09.294447Z","steps":["trace[798132227] 'process raft request' (duration: 127.47594ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:09.53654Z","caller":"traceutil/trace.go:171","msg":"trace[763380303] linearizableReadLoop","detail":"{readStateIndex:27253; appliedIndex:27252; }","duration":"241.989607ms","start":"2026-07-14T23:48:09.294523Z","end":"2026-07-14T23:48:09.536512Z","steps":["trace[763380303] 'read index received' (duration: 210.728929ms)","trace[763380303] 'applied index is now lower than readState.Index' (duration: 31.259428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:48:09.536604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.707535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:48:09.536665Z","caller":"traceutil/trace.go:171","msg":"trace[1850406410] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26387; }","duration":"247.826188ms","start":"2026-07-14T23:48:09.288819Z","end":"2026-07-14T23:48:09.536645Z","steps":["trace[1850406410] 'agreement among raft nodes before linearized reading' (duration: 247.711405ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:09.536648Z","caller":"traceutil/trace.go:171","msg":"trace[669610823] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"323.106352ms","start":"2026-07-14T23:48:09.213506Z","end":"2026-07-14T23:48:09.536612Z","steps":["trace[669610823] 'process raft request' (duration: 291.64507ms)","trace[669610823] 'compare' (duration: 31.232927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:48:09.536765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.649792ms","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-07-14T23:48:09.536822Z","caller":"traceutil/trace.go:171","msg":"trace[626681011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26387; }","duration":"329.720913ms","start":"2026-07-14T23:48:09.207085Z","end":"2026-07-14T23:48:09.536806Z","steps":["trace[626681011] 'agreement among raft nodes before linearized reading' (duration: 329.563999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:09.536864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:09.20708Z","time spent":"329.772335ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:48:09.536863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:09.213493Z","time spent":"323.272687ms","remote":"127.0.0.1:45528","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-07-14T23:48:09.537622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.719622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-14T23:48:09.537807Z","caller":"traceutil/trace.go:171","msg":"trace[1076601127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26387; }","duration":"329.945198ms","start":"2026-07-14T23:48:09.207829Z","end":"2026-07-14T23:48:09.537774Z","steps":["trace[1076601127] 'agreement among raft nodes before linearized reading' (duration: 328.79256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:09.53793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:09.207815Z","time spent":"330.093581ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T23:48:09.538779Z","caller":"traceutil/trace.go:171","msg":"trace[621845381] transaction","detail":"{read_only:false; response_revision:26388; number_of_response:1; }","duration":"239.595559ms","start":"2026-07-14T23:48:09.29917Z","end":"2026-07-14T23:48:09.538765Z","steps":["trace[621845381] 'process raft request' (duration: 239.053115ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:10.023213Z","caller":"traceutil/trace.go:171","msg":"trace[108254684] transaction","detail":"{read_only:false; response_revision:26391; number_of_response:1; }","duration":"191.471452ms","start":"2026-07-14T23:48:09.831708Z","end":"2026-07-14T23:48:10.023179Z","steps":["trace[108254684] 'process raft request' (duration: 191.205766ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:48:10.139201Z","caller":"traceutil/trace.go:171","msg":"trace[1493526014] transaction","detail":"{read_only:false; response_revision:26392; number_of_response:1; }","duration":"307.407723ms","start":"2026-07-14T23:48:09.83176Z","end":"2026-07-14T23:48:10.139167Z","steps":["trace[1493526014] 'process raft request' (duration: 304.650926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:10.139326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:09.831738Z","time spent":"307.523176ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:48:10.139564Z","caller":"traceutil/trace.go:171","msg":"trace[1139107774] transaction","detail":"{read_only:false; response_revision:26393; number_of_response:1; }","duration":"306.893869ms","start":"2026-07-14T23:48:09.832635Z","end":"2026-07-14T23:48:10.139529Z","steps":["trace[1139107774] 'process raft request' (duration: 306.462299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:48:10.139744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:48:09.832591Z","time spent":"307.074594ms","remote":"127.0.0.1:45528","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-07-14T23:48:10.140006Z","caller":"traceutil/trace.go:171","msg":"trace[1301882114] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"220.117306ms","start":"2026-07-14T23:48:09.919554Z","end":"2026-07-14T23:48:10.139671Z","steps":["trace[1301882114] 'process raft request' (duration: 219.8685ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:51:17.632486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25248} {"level":"info","ts":"2026-07-14T23:51:17.772225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25248,"took":"134.961031ms","hash":2330210259,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":38563840,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-07-14T23:51:17.772317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2330210259,"revision":25248,"compact-revision":21993} {"level":"info","ts":"2026-07-14T23:52:19.22923Z","caller":"traceutil/trace.go:171","msg":"trace[714092830] transaction","detail":"{read_only:false; response_revision:28916; number_of_response:1; }","duration":"121.547595ms","start":"2026-07-14T23:52:19.107657Z","end":"2026-07-14T23:52:19.229204Z","steps":["trace[714092830] 'process raft request' (duration: 121.399752ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:52:19.310201Z","caller":"traceutil/trace.go:171","msg":"trace[1785479009] transaction","detail":"{read_only:false; response_revision:28917; number_of_response:1; }","duration":"197.950248ms","start":"2026-07-14T23:52:19.112231Z","end":"2026-07-14T23:52:19.310181Z","steps":["trace[1785479009] 'process raft request' (duration: 197.338444ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:52:19.310548Z","caller":"traceutil/trace.go:171","msg":"trace[310964032] transaction","detail":"{read_only:false; response_revision:28918; number_of_response:1; }","duration":"193.044119ms","start":"2026-07-14T23:52:19.117484Z","end":"2026-07-14T23:52:19.310528Z","steps":["trace[310964032] 'process raft request' (duration: 192.265131ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:52:19.31076Z","caller":"traceutil/trace.go:171","msg":"trace[1289957692] transaction","detail":"{read_only:false; response_revision:28919; number_of_response:1; }","duration":"193.011539ms","start":"2026-07-14T23:52:19.117739Z","end":"2026-07-14T23:52:19.310751Z","steps":["trace[1289957692] 'process raft request' (duration: 192.059766ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:52:19.310883Z","caller":"traceutil/trace.go:171","msg":"trace[499918031] transaction","detail":"{read_only:false; response_revision:28920; number_of_response:1; }","duration":"136.737064ms","start":"2026-07-14T23:52:19.17413Z","end":"2026-07-14T23:52:19.310867Z","steps":["trace[499918031] 'process raft request' (duration: 135.71125ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:52:19.543571Z","caller":"traceutil/trace.go:171","msg":"trace[472221439] transaction","detail":"{read_only:false; response_revision:28921; number_of_response:1; }","duration":"195.52588ms","start":"2026-07-14T23:52:19.348005Z","end":"2026-07-14T23:52:19.543531Z","steps":["trace[472221439] 'process raft request' (duration: 112.509058ms)","trace[472221439] 'compare' (duration: 82.599722ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:52:19.874268Z","caller":"traceutil/trace.go:171","msg":"trace[1456059313] transaction","detail":"{read_only:false; response_revision:28922; number_of_response:1; }","duration":"420.03215ms","start":"2026-07-14T23:52:19.454205Z","end":"2026-07-14T23:52:19.874237Z","steps":["trace[1456059313] 'process raft request' (duration: 419.531278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:52:19.874459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:52:19.45418Z","time spent":"420.197105ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:52:20.382039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.680491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:52:20.382125Z","caller":"traceutil/trace.go:171","msg":"trace[1218547032] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:28923; }","duration":"152.817614ms","start":"2026-07-14T23:52:20.229291Z","end":"2026-07-14T23:52:20.382109Z","steps":["trace[1218547032] 'count revisions from in-memory index tree' (duration: 152.60821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:52:20.383809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.738644ms","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-07-14T23:52:20.383989Z","caller":"traceutil/trace.go:171","msg":"trace[831333876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28923; }","duration":"141.9674ms","start":"2026-07-14T23:52:20.24196Z","end":"2026-07-14T23:52:20.383927Z","steps":["trace[831333876] 'range keys from in-memory index tree' (duration: 140.071205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:52:20.774732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.313648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:52:20.774889Z","caller":"traceutil/trace.go:171","msg":"trace[2069526345] linearizableReadLoop","detail":"{readStateIndex:29853; appliedIndex:29852; }","duration":"188.180221ms","start":"2026-07-14T23:52:20.586688Z","end":"2026-07-14T23:52:20.774868Z","steps":["trace[2069526345] 'read index received' (duration: 48.151µs)","trace[2069526345] 'applied index is now lower than readState.Index' (duration: 188.12988ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:52:20.775008Z","caller":"traceutil/trace.go:171","msg":"trace[1590674620] transaction","detail":"{read_only:false; response_revision:28925; number_of_response:1; }","duration":"337.740266ms","start":"2026-07-14T23:52:20.43725Z","end":"2026-07-14T23:52:20.77499Z","steps":["trace[1590674620] 'process raft request' (duration: 108.056719ms)","trace[1590674620] 'compare' (duration: 228.56168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:52:20.775105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:52:20.437228Z","time spent":"337.827559ms","remote":"127.0.0.1:45528","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-07-14T23:52:20.775317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.702639ms","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-07-14T23:52:20.775439Z","caller":"traceutil/trace.go:171","msg":"trace[432738136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28925; }","duration":"187.859493ms","start":"2026-07-14T23:52:20.587551Z","end":"2026-07-14T23:52:20.77541Z","steps":["trace[432738136] 'agreement among raft nodes before linearized reading' (duration: 187.572656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:52:20.775495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.749915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:52:20.775599Z","caller":"traceutil/trace.go:171","msg":"trace[1671932333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28925; }","duration":"188.923229ms","start":"2026-07-14T23:52:20.586649Z","end":"2026-07-14T23:52:20.775572Z","steps":["trace[1671932333] 'agreement among raft nodes before linearized reading' (duration: 188.725244ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:52:38.079871Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:52:38.085078Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-14T23:52:38.085181Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-14T23:56:17.638228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28309} {"level":"info","ts":"2026-07-14T23:56:17.783495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28309,"took":"141.425899ms","hash":3236785631,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-14T23:56:17.783577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3236785631,"revision":28309,"compact-revision":25248} {"level":"info","ts":"2026-07-15T00:01:17.643221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30726} {"level":"info","ts":"2026-07-15T00:01:17.783091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30726,"took":"134.974194ms","hash":911739562,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:01:17.783151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":911739562,"revision":30726,"compact-revision":28309} {"level":"info","ts":"2026-07-15T00:06:17.64849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33102} {"level":"info","ts":"2026-07-15T00:06:17.789153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33102,"took":"137.766852ms","hash":216992424,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:06:17.789221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":216992424,"revision":33102,"compact-revision":30726} {"level":"info","ts":"2026-07-15T00:11:17.655552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35380} {"level":"info","ts":"2026-07-15T00:11:17.791248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35380,"took":"132.34309ms","hash":3557043725,"current-db-size-bytes":59707392,"current-db-size":"60 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:11:17.791335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3557043725,"revision":35380,"compact-revision":33102} {"level":"info","ts":"2026-07-15T00:13:39.421208Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T00:13:39.424738Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T00:13:39.424828Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}