{"level":"warn","ts":"2026-10-15T06:34:00.261022Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:34:00.261113Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.113:2380","--initial-cluster=instance=https://199.19.213.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.113: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-10-15T06:34:00.261181Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:34:00.261191Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.113:2380"]} {"level":"info","ts":"2026-10-15T06:34:00.261209Z","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-10-15T06:34:00.261867Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.113:2379"]} {"level":"info","ts":"2026-10-15T06:34:00.262009Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.113:2380"],"listen-peer-urls":["https://199.19.213.113:2380"],"advertise-client-urls":["https://199.19.213.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.113: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-10-15T06:34:00.266399Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.098153ms"} {"level":"info","ts":"2026-10-15T06:34:00.276446Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7b14f43c75605232","cluster-id":"5fcefcc435d3e9a2"} {"level":"info","ts":"2026-10-15T06:34:00.276615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T06:34:00.276669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 became follower at term 0"} {"level":"info","ts":"2026-10-15T06:34:00.276689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7b14f43c75605232 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T06:34:00.2767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 became follower at term 1"} {"level":"info","ts":"2026-10-15T06:34:00.276776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 switched to configuration voters=(8868982106703811122)"} {"level":"warn","ts":"2026-10-15T06:34:00.285977Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T06:34:00.288191Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T06:34:00.290306Z","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-10-15T06:34:00.294501Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7b14f43c75605232","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T06:34:00.294835Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7b14f43c75605232","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T06:34:00.295119Z","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-10-15T06:34:00.295189Z","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-10-15T06:34:00.295202Z","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-10-15T06:34:00.296209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 switched to configuration voters=(8868982106703811122)"} {"level":"info","ts":"2026-10-15T06:34:00.296342Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5fcefcc435d3e9a2","local-member-id":"7b14f43c75605232","added-peer-id":"7b14f43c75605232","added-peer-peer-urls":["https://199.19.213.113:2380"]} {"level":"info","ts":"2026-10-15T06:34:00.298607Z","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-10-15T06:34:00.298777Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.113:2380"} {"level":"info","ts":"2026-10-15T06:34:00.298824Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.113:2380"} {"level":"info","ts":"2026-10-15T06:34:00.299073Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7b14f43c75605232","initial-advertise-peer-urls":["https://199.19.213.113:2380"],"listen-peer-urls":["https://199.19.213.113:2380"],"advertise-client-urls":["https://199.19.213.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T06:34:00.299132Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T06:34:01.277761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T06:34:01.277839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T06:34:01.277879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 received MsgPreVoteResp from 7b14f43c75605232 at term 1"} {"level":"info","ts":"2026-10-15T06:34:01.277901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 became candidate at term 2"} {"level":"info","ts":"2026-10-15T06:34:01.277913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 received MsgVoteResp from 7b14f43c75605232 at term 2"} {"level":"info","ts":"2026-10-15T06:34:01.277932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7b14f43c75605232 became leader at term 2"} {"level":"info","ts":"2026-10-15T06:34:01.277947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7b14f43c75605232 elected leader 7b14f43c75605232 at term 2"} {"level":"info","ts":"2026-10-15T06:34:01.282009Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:34:01.282186Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7b14f43c75605232","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.113:2379]}","request-path":"/0/members/7b14f43c75605232/attributes","cluster-id":"5fcefcc435d3e9a2","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T06:34:01.282284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:34:01.282386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:34:01.282665Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T06:34:01.282783Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T06:34:01.284214Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5fcefcc435d3e9a2","local-member-id":"7b14f43c75605232","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:34:01.284586Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:34:01.284971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:34:01.286109Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.113:2379"} {"level":"info","ts":"2026-10-15T06:34:01.289059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T06:34:15.766107Z","caller":"traceutil/trace.go:171","msg":"trace[1385969513] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"110.21351ms","start":"2026-10-15T06:34:15.655877Z","end":"2026-10-15T06:34:15.76609Z","steps":["trace[1385969513] 'process raft request' (duration: 110.103697ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:34:21.251302Z","caller":"traceutil/trace.go:171","msg":"trace[193629138] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"141.203056ms","start":"2026-10-15T06:34:21.110064Z","end":"2026-10-15T06:34:21.251268Z","steps":["trace[193629138] 'process raft request' (duration: 140.98859ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:34:53.651087Z","caller":"traceutil/trace.go:171","msg":"trace[340264500] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"160.052594ms","start":"2026-10-15T06:34:53.491004Z","end":"2026-10-15T06:34:53.651056Z","steps":["trace[340264500] 'process raft request' (duration: 96.195983ms)","trace[340264500] 'compare' (duration: 63.714187ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:34:53.80854Z","caller":"traceutil/trace.go:171","msg":"trace[748973565] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"269.525254ms","start":"2026-10-15T06:34:53.538985Z","end":"2026-10-15T06:34:53.80851Z","steps":["trace[748973565] 'process raft request' (duration: 235.633789ms)","trace[748973565] 'compare' (duration: 33.37042ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:34:54.135303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.872531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:34:54.135628Z","caller":"traceutil/trace.go:171","msg":"trace[2144785127] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"300.688623ms","start":"2026-10-15T06:34:53.834903Z","end":"2026-10-15T06:34:54.135592Z","steps":["trace[2144785127] 'process raft request' (duration: 90.927028ms)","trace[2144785127] 'compare' (duration: 208.704886ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:34:54.135664Z","caller":"traceutil/trace.go:171","msg":"trace[580181353] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"250.54316ms","start":"2026-10-15T06:34:53.885081Z","end":"2026-10-15T06:34:54.135625Z","steps":["trace[580181353] 'process raft request' (duration: 250.355034ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:34:54.13569Z","caller":"traceutil/trace.go:171","msg":"trace[1374011626] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"119.64637ms","start":"2026-10-15T06:34:54.01602Z","end":"2026-10-15T06:34:54.135667Z","steps":["trace[1374011626] 'read index received' (duration: 106.96611ms)","trace[1374011626] 'applied index is now lower than readState.Index' (duration: 12.6783ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:34:54.135772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:34:53.834868Z","time spent":"300.829207ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:34:54.13596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.457606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:34:54.136316Z","caller":"traceutil/trace.go:171","msg":"trace[106602945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"102.875907ms","start":"2026-10-15T06:34:54.033421Z","end":"2026-10-15T06:34:54.136297Z","steps":["trace[106602945] 'agreement among raft nodes before linearized reading' (duration: 102.472136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:34:54.135953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.932778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T06:34:54.136438Z","caller":"traceutil/trace.go:171","msg":"trace[1996012647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"120.461483ms","start":"2026-10-15T06:34:54.015961Z","end":"2026-10-15T06:34:54.136423Z","steps":["trace[1996012647] 'agreement among raft nodes before linearized reading' (duration: 119.800304ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:04.402486Z","caller":"traceutil/trace.go:171","msg":"trace[527019214] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"125.560793ms","start":"2026-10-15T06:36:04.276897Z","end":"2026-10-15T06:36:04.402458Z","steps":["trace[527019214] 'process raft request' (duration: 125.415969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:30.301359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.454859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T06:36:30.301471Z","caller":"traceutil/trace.go:171","msg":"trace[304416640] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"242.58565ms","start":"2026-10-15T06:36:30.058867Z","end":"2026-10-15T06:36:30.301453Z","steps":["trace[304416640] 'read index received' (duration: 54.591µs)","trace[304416640] 'applied index is now lower than readState.Index' (duration: 242.526629ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:36:30.301687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.603876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:36:30.301732Z","caller":"traceutil/trace.go:171","msg":"trace[153838760] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1863; }","duration":"215.714589ms","start":"2026-10-15T06:36:30.086005Z","end":"2026-10-15T06:36:30.30172Z","steps":["trace[153838760] 'agreement among raft nodes before linearized reading' (duration: 215.601416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:30.301764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.894199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T06:36:30.301879Z","caller":"traceutil/trace.go:171","msg":"trace[19321320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1863; }","duration":"243.059753ms","start":"2026-10-15T06:36:30.058797Z","end":"2026-10-15T06:36:30.301857Z","steps":["trace[19321320] 'agreement among raft nodes before linearized reading' (duration: 242.776185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:30.302028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.465542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:36:30.302059Z","caller":"traceutil/trace.go:171","msg":"trace[1530904789] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1863; }","duration":"124.544715ms","start":"2026-10-15T06:36:30.177507Z","end":"2026-10-15T06:36:30.302052Z","steps":["trace[1530904789] 'agreement among raft nodes before linearized reading' (duration: 124.482233ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:30.815291Z","caller":"traceutil/trace.go:171","msg":"trace[430166620] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"120.426021ms","start":"2026-10-15T06:36:30.694819Z","end":"2026-10-15T06:36:30.815245Z","steps":["trace[430166620] 'process raft request' (duration: 53.828815ms)","trace[430166620] 'compare' (duration: 66.307678ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:43.59522Z","caller":"traceutil/trace.go:171","msg":"trace[1816939207] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"146.440258ms","start":"2026-10-15T06:36:43.448758Z","end":"2026-10-15T06:36:43.595198Z","steps":["trace[1816939207] 'read index received' (duration: 146.194832ms)","trace[1816939207] 'applied index is now lower than readState.Index' (duration: 244.556µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:43.595313Z","caller":"traceutil/trace.go:171","msg":"trace[1069728614] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"151.426976ms","start":"2026-10-15T06:36:43.443852Z","end":"2026-10-15T06:36:43.595279Z","steps":["trace[1069728614] 'process raft request' (duration: 151.163128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:43.595374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.594632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:36:43.595413Z","caller":"traceutil/trace.go:171","msg":"trace[736522802] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1973; }","duration":"146.646304ms","start":"2026-10-15T06:36:43.448751Z","end":"2026-10-15T06:36:43.595398Z","steps":["trace[736522802] 'agreement among raft nodes before linearized reading' (duration: 146.570072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:43.595572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.376613ms","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-10-15T06:36:43.595665Z","caller":"traceutil/trace.go:171","msg":"trace[552923290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1973; }","duration":"143.547209ms","start":"2026-10-15T06:36:43.452103Z","end":"2026-10-15T06:36:43.59565Z","steps":["trace[552923290] 'agreement among raft nodes before linearized reading' (duration: 143.305902ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:15.978534Z","caller":"traceutil/trace.go:171","msg":"trace[1931880884] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"108.729362ms","start":"2026-10-15T06:37:15.86977Z","end":"2026-10-15T06:37:15.978499Z","steps":["trace[1931880884] 'process raft request' (duration: 71.35731ms)","trace[1931880884] 'compare' (duration: 37.242088ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:37:21.997043Z","caller":"traceutil/trace.go:171","msg":"trace[328355783] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"153.775966ms","start":"2026-10-15T06:37:21.843238Z","end":"2026-10-15T06:37:21.997014Z","steps":["trace[328355783] 'process raft request' (duration: 153.5691ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:17.467532Z","caller":"traceutil/trace.go:171","msg":"trace[1874206944] linearizableReadLoop","detail":"{readStateIndex:2766; appliedIndex:2765; }","duration":"106.868131ms","start":"2026-10-15T06:38:17.360639Z","end":"2026-10-15T06:38:17.467507Z","steps":["trace[1874206944] 'read index received' (duration: 106.617924ms)","trace[1874206944] 'applied index is now lower than readState.Index' (duration: 249.117µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:38:17.467704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.013985ms","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-10-15T06:38:17.467745Z","caller":"traceutil/trace.go:171","msg":"trace[356098624] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2662; }","duration":"107.153149ms","start":"2026-10-15T06:38:17.360582Z","end":"2026-10-15T06:38:17.467735Z","steps":["trace[356098624] 'agreement among raft nodes before linearized reading' (duration: 107.033126ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:17.4678Z","caller":"traceutil/trace.go:171","msg":"trace[431501953] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"107.978792ms","start":"2026-10-15T06:38:17.359797Z","end":"2026-10-15T06:38:17.467776Z","steps":["trace[431501953] 'process raft request' (duration: 107.55527ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:50.595556Z","caller":"traceutil/trace.go:171","msg":"trace[408011470] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"125.434374ms","start":"2026-10-15T06:38:50.470077Z","end":"2026-10-15T06:38:50.595512Z","steps":["trace[408011470] 'process raft request' (duration: 125.030393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:50.847421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.25169ms","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-10-15T06:38:50.847548Z","caller":"traceutil/trace.go:171","msg":"trace[1148258899] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2864; }","duration":"158.471225ms","start":"2026-10-15T06:38:50.689049Z","end":"2026-10-15T06:38:50.847521Z","steps":["trace[1148258899] 'count revisions from in-memory index tree' (duration: 158.092136ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:41:31.25599Z","caller":"traceutil/trace.go:171","msg":"trace[1213039995] transaction","detail":"{read_only:false; response_revision:3839; number_of_response:1; }","duration":"103.123409ms","start":"2026-10-15T06:41:31.152837Z","end":"2026-10-15T06:41:31.25596Z","steps":["trace[1213039995] 'process raft request' (duration: 100.121676ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:41:31.256642Z","caller":"traceutil/trace.go:171","msg":"trace[691214738] transaction","detail":"{read_only:false; response_revision:3840; number_of_response:1; }","duration":"103.714975ms","start":"2026-10-15T06:41:31.152895Z","end":"2026-10-15T06:41:31.25661Z","steps":["trace[691214738] 'process raft request' (duration: 103.52072ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:41:31.257028Z","caller":"traceutil/trace.go:171","msg":"trace[508852732] transaction","detail":"{read_only:false; response_revision:3841; number_of_response:1; }","duration":"101.188316ms","start":"2026-10-15T06:41:31.155824Z","end":"2026-10-15T06:41:31.257012Z","steps":["trace[508852732] 'process raft request' (duration: 100.737444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:29.13087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.892522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-10-15T06:43:29.131168Z","caller":"traceutil/trace.go:171","msg":"trace[1810972470] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5036; }","duration":"122.204061ms","start":"2026-10-15T06:43:29.008899Z","end":"2026-10-15T06:43:29.131103Z","steps":["trace[1810972470] 'range keys from in-memory index tree' (duration: 121.685547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:29.139346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.300048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-15T06:43:29.13946Z","caller":"traceutil/trace.go:171","msg":"trace[637848663] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5036; }","duration":"124.454882ms","start":"2026-10-15T06:43:29.014976Z","end":"2026-10-15T06:43:29.139431Z","steps":["trace[637848663] 'range keys from in-memory index tree' (duration: 124.077912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:29.877198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.361842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-10-15T06:43:29.877378Z","caller":"traceutil/trace.go:171","msg":"trace[1915128378] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5063; }","duration":"100.584958ms","start":"2026-10-15T06:43:29.776762Z","end":"2026-10-15T06:43:29.877347Z","steps":["trace[1915128378] 'agreement among raft nodes before linearized reading' (duration: 74.816542ms)","trace[1915128378] 'range keys from in-memory index tree' (duration: 25.393536ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:30.606553Z","caller":"traceutil/trace.go:171","msg":"trace[1035219675] linearizableReadLoop","detail":"{readStateIndex:5266; appliedIndex:5264; }","duration":"203.078468ms","start":"2026-10-15T06:43:30.403443Z","end":"2026-10-15T06:43:30.606522Z","steps":["trace[1035219675] 'read index received' (duration: 13.882781ms)","trace[1035219675] 'applied index is now lower than readState.Index' (duration: 189.194307ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:30.606625Z","caller":"traceutil/trace.go:171","msg":"trace[1199468577] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"214.737937ms","start":"2026-10-15T06:43:30.391846Z","end":"2026-10-15T06:43:30.606584Z","steps":["trace[1199468577] 'process raft request' (duration: 192.477607ms)","trace[1199468577] 'compare' (duration: 22.041554ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:30.606918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.778763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-10-15T06:43:30.606976Z","caller":"traceutil/trace.go:171","msg":"trace[997147743] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01; range_end:; response_count:1; response_revision:5072; }","duration":"123.901357ms","start":"2026-10-15T06:43:30.483057Z","end":"2026-10-15T06:43:30.606959Z","steps":["trace[997147743] 'agreement among raft nodes before linearized reading' (duration: 123.65805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:30.60791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.439715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210296"} {"level":"info","ts":"2026-10-15T06:43:30.607987Z","caller":"traceutil/trace.go:171","msg":"trace[23503570] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5072; }","duration":"204.561538ms","start":"2026-10-15T06:43:30.403408Z","end":"2026-10-15T06:43:30.607969Z","steps":["trace[23503570] 'agreement among raft nodes before linearized reading' (duration: 203.273823ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:31.047167Z","caller":"traceutil/trace.go:171","msg":"trace[4288357] linearizableReadLoop","detail":"{readStateIndex:5267; appliedIndex:5266; }","duration":"410.792614ms","start":"2026-10-15T06:43:30.636344Z","end":"2026-10-15T06:43:31.047137Z","steps":["trace[4288357] 'read index received' (duration: 380.718869ms)","trace[4288357] 'applied index is now lower than readState.Index' (duration: 30.072405ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:31.04724Z","caller":"traceutil/trace.go:171","msg":"trace[1796783517] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"433.735542ms","start":"2026-10-15T06:43:30.613465Z","end":"2026-10-15T06:43:31.047201Z","steps":["trace[1796783517] 'process raft request' (duration: 403.526974ms)","trace[1796783517] 'compare' (duration: 29.991192ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:31.047469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.613443Z","time spent":"433.907628ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3879,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:31.048233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.818862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526883"} {"level":"info","ts":"2026-10-15T06:43:31.048352Z","caller":"traceutil/trace.go:171","msg":"trace[2040863422] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:5073; }","duration":"412.031317ms","start":"2026-10-15T06:43:30.636302Z","end":"2026-10-15T06:43:31.048333Z","steps":["trace[2040863422] 'agreement among raft nodes before linearized reading' (duration: 410.993369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:31.048459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.636283Z","time spent":"412.160741ms","remote":"127.0.0.1:57730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":22,"response size":526905,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-10-15T06:43:31.547503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922973748755235379,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T06:43:31.835077Z","caller":"traceutil/trace.go:171","msg":"trace[188019062] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"1.101865393s","start":"2026-10-15T06:43:30.733184Z","end":"2026-10-15T06:43:31.835049Z","steps":["trace[188019062] 'process raft request' (duration: 1.101819421s)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:31.835178Z","caller":"traceutil/trace.go:171","msg":"trace[2002305532] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"1.10907888s","start":"2026-10-15T06:43:30.726051Z","end":"2026-10-15T06:43:31.83513Z","steps":["trace[2002305532] 'process raft request' (duration: 1.108807342s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:31.835267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.733156Z","time spent":"1.102013156s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:31.835377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.72602Z","time spent":"1.109252895s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:32.046843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.240325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:43:32.046951Z","caller":"traceutil/trace.go:171","msg":"trace[784684065] linearizableReadLoop","detail":"{readStateIndex:5270; appliedIndex:5267; }","duration":"999.67728ms","start":"2026-10-15T06:43:31.047254Z","end":"2026-10-15T06:43:32.046931Z","steps":["trace[784684065] 'read index received' (duration: 787.711658ms)","trace[784684065] 'applied index is now lower than readState.Index' (duration: 211.963492ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:32.047103Z","caller":"traceutil/trace.go:171","msg":"trace[822266941] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"662.726351ms","start":"2026-10-15T06:43:31.384315Z","end":"2026-10-15T06:43:32.047041Z","steps":["trace[822266941] 'process raft request' (duration: 487.137867ms)","trace[822266941] 'compare' (duration: 174.811533ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:32.047188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:31.384295Z","time spent":"662.845315ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:32.047219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292322844s","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-10-15T06:43:32.047312Z","caller":"traceutil/trace.go:171","msg":"trace[586524574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5076; }","duration":"1.292446248s","start":"2026-10-15T06:43:30.754844Z","end":"2026-10-15T06:43:32.04729Z","steps":["trace[586524574] 'agreement among raft nodes before linearized reading' (duration: 1.292205862s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.754833Z","time spent":"1.29251742s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T06:43:32.047449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296753586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T06:43:32.047518Z","caller":"traceutil/trace.go:171","msg":"trace[1169635929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5076; }","duration":"1.296819658s","start":"2026-10-15T06:43:30.75068Z","end":"2026-10-15T06:43:32.0475Z","steps":["trace[1169635929] 'agreement among raft nodes before linearized reading' (duration: 1.296633013s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097055601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h9dvj\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-15T06:43:32.047561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.750611Z","time spent":"1.296938871s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T06:43:32.047575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121522451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-10-15T06:43:32.047596Z","caller":"traceutil/trace.go:171","msg":"trace[1949097843] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h9dvj; range_end:; response_count:1; response_revision:5076; }","duration":"1.097167673s","start":"2026-10-15T06:43:30.950412Z","end":"2026-10-15T06:43:32.04758Z","steps":["trace[1949097843] 'agreement among raft nodes before linearized reading' (duration: 1.096999608s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165037664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe\" ","response":"range_response_count:1 size:3760"} {"level":"warn","ts":"2026-10-15T06:43:32.047638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.950366Z","time spent":"1.097262576s","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-h9dvj\" "} {"level":"info","ts":"2026-10-15T06:43:32.047635Z","caller":"traceutil/trace.go:171","msg":"trace[707345781] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01; range_end:; response_count:1; response_revision:5076; }","duration":"1.121629624s","start":"2026-10-15T06:43:30.925985Z","end":"2026-10-15T06:43:32.047615Z","steps":["trace[707345781] 'agreement among raft nodes before linearized reading' (duration: 1.12147085s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30343763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T06:43:32.047684Z","caller":"traceutil/trace.go:171","msg":"trace[1263005621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5076; }","duration":"1.303613814s","start":"2026-10-15T06:43:30.744061Z","end":"2026-10-15T06:43:32.047675Z","steps":["trace[1263005621] 'agreement among raft nodes before linearized reading' (duration: 1.303402418s)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:32.047675Z","caller":"traceutil/trace.go:171","msg":"trace[679198810] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe; range_end:; response_count:1; response_revision:5076; }","duration":"1.165139877s","start":"2026-10-15T06:43:30.882518Z","end":"2026-10-15T06:43:32.047658Z","steps":["trace[679198810] 'agreement among raft nodes before linearized reading' (duration: 1.164992203s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.925964Z","time spent":"1.121709767s","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3916,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01\" "} {"level":"warn","ts":"2026-10-15T06:43:32.047707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.744047Z","time spent":"1.303653105s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T06:43:32.047687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402154016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-10-15T06:43:32.047695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013421707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T06:43:32.047735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.005053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-15T06:43:32.047727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.882499Z","time spent":"1.165211529s","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3782,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe\" "} {"level":"info","ts":"2026-10-15T06:43:32.047757Z","caller":"traceutil/trace.go:171","msg":"trace[1400763401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5076; }","duration":"1.013528471s","start":"2026-10-15T06:43:31.034214Z","end":"2026-10-15T06:43:32.047742Z","steps":["trace[1400763401] 'agreement among raft nodes before linearized reading' (duration: 1.013431088s)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:32.047751Z","caller":"traceutil/trace.go:171","msg":"trace[1898345043] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5076; }","duration":"1.402236848s","start":"2026-10-15T06:43:30.645495Z","end":"2026-10-15T06:43:32.047732Z","steps":["trace[1898345043] 'agreement among raft nodes before linearized reading' (duration: 1.401899699s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:31.034189Z","time spent":"1.013629173s","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T06:43:32.047838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.645486Z","time spent":"1.402338681s","remote":"127.0.0.1:57730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1839,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-10-15T06:43:32.047851Z","caller":"traceutil/trace.go:171","msg":"trace[684733572] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5076; }","duration":"313.106385ms","start":"2026-10-15T06:43:31.734675Z","end":"2026-10-15T06:43:32.047781Z","steps":["trace[684733572] 'agreement among raft nodes before linearized reading' (duration: 312.994382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.654283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T06:43:32.047704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.979314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-10-15T06:43:32.047872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.863868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:43:32.047892Z","caller":"traceutil/trace.go:171","msg":"trace[1626795419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5076; }","duration":"773.698294ms","start":"2026-10-15T06:43:31.274187Z","end":"2026-10-15T06:43:32.047885Z","steps":["trace[1626795419] 'agreement among raft nodes before linearized reading' (duration: 773.577521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:31.734653Z","time spent":"313.230408ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-10-15T06:43:32.047918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:31.274179Z","time spent":"773.734895ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T06:43:32.047918Z","caller":"traceutil/trace.go:171","msg":"trace[2030194644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5076; }","duration":"777.225331ms","start":"2026-10-15T06:43:31.270673Z","end":"2026-10-15T06:43:32.047898Z","steps":["trace[2030194644] 'agreement among raft nodes before linearized reading' (duration: 776.917722ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:32.047925Z","caller":"traceutil/trace.go:171","msg":"trace[1080903319] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5076; }","duration":"107.9567ms","start":"2026-10-15T06:43:31.939952Z","end":"2026-10-15T06:43:32.047908Z","steps":["trace[1080903319] 'agreement among raft nodes before linearized reading' (duration: 107.835257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.047966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:31.270655Z","time spent":"777.296643ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T06:43:32.04801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052857938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:43:32.048148Z","caller":"traceutil/trace.go:171","msg":"trace[1192144760] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:5076; }","duration":"1.053039943s","start":"2026-10-15T06:43:30.995092Z","end":"2026-10-15T06:43:32.048132Z","steps":["trace[1192144760] 'agreement among raft nodes before linearized reading' (duration: 1.052870359s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.048198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:30.995072Z","time spent":"1.053108026s","remote":"127.0.0.1:52350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-15T06:43:32.048198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.677474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526883"} {"level":"info","ts":"2026-10-15T06:43:32.048257Z","caller":"traceutil/trace.go:171","msg":"trace[1761718409] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:5076; }","duration":"649.773786ms","start":"2026-10-15T06:43:31.398464Z","end":"2026-10-15T06:43:32.048237Z","steps":["trace[1761718409] 'agreement among raft nodes before linearized reading' (duration: 649.275222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:32.048319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:31.398432Z","time spent":"649.873959ms","remote":"127.0.0.1:57730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":22,"response size":526905,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-10-15T06:43:32.600903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.252675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:43:32.601131Z","caller":"traceutil/trace.go:171","msg":"trace[2039454592] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"548.16576ms","start":"2026-10-15T06:43:32.052934Z","end":"2026-10-15T06:43:32.6011Z","steps":["trace[2039454592] 'process raft request' (duration: 120.641158ms)","trace[2039454592] 'compare' (duration: 427.08637ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:32.601224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.052713Z","time spent":"548.476849ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:33.388729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.874868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:43:33.389041Z","caller":"traceutil/trace.go:171","msg":"trace[1991806723] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"1.33556382s","start":"2026-10-15T06:43:32.053451Z","end":"2026-10-15T06:43:33.389015Z","steps":["trace[1991806723] 'process raft request' (duration: 1.335430456s)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:33.38904Z","caller":"traceutil/trace.go:171","msg":"trace[767536090] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"1.335667263s","start":"2026-10-15T06:43:32.053335Z","end":"2026-10-15T06:43:33.389003Z","steps":["trace[767536090] 'process raft request' (duration: 731.392084ms)","trace[767536090] 'compare' (duration: 603.762105ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:33.38914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.053444Z","time spent":"1.335643652s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:33.389198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.053274Z","time spent":"1.335840248s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:33.389253Z","caller":"traceutil/trace.go:171","msg":"trace[402641747] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"1.335644222s","start":"2026-10-15T06:43:32.05359Z","end":"2026-10-15T06:43:33.389234Z","steps":["trace[402641747] 'process raft request' (duration: 1.335370625s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.389374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.053555Z","time spent":"1.335763015s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:33.389526Z","caller":"traceutil/trace.go:171","msg":"trace[1528891965] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"1.333835723s","start":"2026-10-15T06:43:32.055676Z","end":"2026-10-15T06:43:33.389512Z","steps":["trace[1528891965] 'process raft request' (duration: 1.333494383s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.389611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.055658Z","time spent":"1.333904824s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:33.389612Z","caller":"traceutil/trace.go:171","msg":"trace[1186508551] linearizableReadLoop","detail":"{readStateIndex:5274; appliedIndex:5270; }","duration":"1.335852508s","start":"2026-10-15T06:43:32.053744Z","end":"2026-10-15T06:43:33.389597Z","steps":["trace[1186508551] 'read index received' (duration: 119.844366ms)","trace[1186508551] 'applied index is now lower than readState.Index' (duration: 1.216006662s)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:33.38961Z","caller":"traceutil/trace.go:171","msg":"trace[1324180256] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"1.155111442s","start":"2026-10-15T06:43:32.234472Z","end":"2026-10-15T06:43:33.389584Z","steps":["trace[1324180256] 'process raft request' (duration: 1.154985938s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.389764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328886967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T06:43:33.389785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.234446Z","time spent":"1.155237895s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:33.389854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102896521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:43:33.389902Z","caller":"traceutil/trace.go:171","msg":"trace[1146924187] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5082; }","duration":"1.102985793s","start":"2026-10-15T06:43:32.286902Z","end":"2026-10-15T06:43:33.389888Z","steps":["trace[1146924187] 'agreement among raft nodes before linearized reading' (duration: 1.102838059s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.389909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244224216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T06:43:33.389937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.286878Z","time spent":"1.103050814s","remote":"127.0.0.1:58000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":74,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-10-15T06:43:33.38986Z","caller":"traceutil/trace.go:171","msg":"trace[1337849529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5082; }","duration":"1.329055752s","start":"2026-10-15T06:43:32.060787Z","end":"2026-10-15T06:43:33.389842Z","steps":["trace[1337849529] 'agreement among raft nodes before linearized reading' (duration: 1.328928898s)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:33.389969Z","caller":"traceutil/trace.go:171","msg":"trace[493077323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5082; }","duration":"1.244323519s","start":"2026-10-15T06:43:32.145628Z","end":"2026-10-15T06:43:33.389952Z","steps":["trace[493077323] 'agreement among raft nodes before linearized reading' (duration: 1.244104163s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.389992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307878521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"warn","ts":"2026-10-15T06:43:33.390026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.145605Z","time spent":"1.244409871s","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T06:43:33.390043Z","caller":"traceutil/trace.go:171","msg":"trace[1279771761] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5082; }","duration":"1.307961143s","start":"2026-10-15T06:43:32.082068Z","end":"2026-10-15T06:43:33.390029Z","steps":["trace[1279771761] 'agreement among raft nodes before linearized reading' (duration: 1.307816809s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.390034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.060762Z","time spent":"1.329251717s","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T06:43:33.390085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.082054Z","time spent":"1.308020605s","remote":"127.0.0.1:57730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3528,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-10-15T06:43:33.390107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3289913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-ls6pb\" ","response":"range_response_count:1 size:5148"} {"level":"warn","ts":"2026-10-15T06:43:33.38999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.321844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-10-15T06:43:33.390159Z","caller":"traceutil/trace.go:171","msg":"trace[1800130097] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-ls6pb; range_end:; response_count:1; response_revision:5082; }","duration":"1.329058082s","start":"2026-10-15T06:43:32.061085Z","end":"2026-10-15T06:43:33.390143Z","steps":["trace[1800130097] 'agreement among raft nodes before linearized reading' (duration: 1.328892038s)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:33.390179Z","caller":"traceutil/trace.go:171","msg":"trace[521044353] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01; range_end:; response_count:1; response_revision:5082; }","duration":"335.545331ms","start":"2026-10-15T06:43:33.054619Z","end":"2026-10-15T06:43:33.390164Z","steps":["trace[521044353] 'agreement among raft nodes before linearized reading' (duration: 335.109099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.390206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.061077Z","time spent":"1.329118833s","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5170,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-ls6pb\" "} {"level":"warn","ts":"2026-10-15T06:43:33.390216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.054599Z","time spent":"335.605062ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3916,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01\" "} {"level":"warn","ts":"2026-10-15T06:43:33.390441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.650912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-15T06:43:33.390443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.637273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-10-15T06:43:33.3905Z","caller":"traceutil/trace.go:171","msg":"trace[1786437992] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5082; }","duration":"196.758815ms","start":"2026-10-15T06:43:33.193724Z","end":"2026-10-15T06:43:33.390483Z","steps":["trace[1786437992] 'agreement among raft nodes before linearized reading' (duration: 196.597921ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:33.390507Z","caller":"traceutil/trace.go:171","msg":"trace[617630386] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe; range_end:; response_count:1; response_revision:5082; }","duration":"335.707925ms","start":"2026-10-15T06:43:33.054783Z","end":"2026-10-15T06:43:33.390491Z","steps":["trace[617630386] 'agreement among raft nodes before linearized reading' (duration: 335.55578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.390553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.054777Z","time spent":"335.765076ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3782,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe\" "} {"level":"warn","ts":"2026-10-15T06:43:33.391093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33736819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62907"} {"level":"info","ts":"2026-10-15T06:43:33.391143Z","caller":"traceutil/trace.go:171","msg":"trace[1830214552] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5082; }","duration":"1.337450292s","start":"2026-10-15T06:43:32.053681Z","end":"2026-10-15T06:43:33.391131Z","steps":["trace[1830214552] 'agreement among raft nodes before linearized reading' (duration: 1.336026633s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.391174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:32.053672Z","time spent":"1.337493763s","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":7,"response size":62929,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-15T06:43:33.908703Z","caller":"traceutil/trace.go:171","msg":"trace[1637782577] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"508.612146ms","start":"2026-10-15T06:43:33.400055Z","end":"2026-10-15T06:43:33.908667Z","steps":["trace[1637782577] 'process raft request' (duration: 508.432141ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:33.90873Z","caller":"traceutil/trace.go:171","msg":"trace[1159836810] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"512.263186ms","start":"2026-10-15T06:43:33.396431Z","end":"2026-10-15T06:43:33.908695Z","steps":["trace[1159836810] 'process raft request' (duration: 511.890016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.908922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.400025Z","time spent":"508.76845ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4933,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:33.908976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.396418Z","time spent":"512.464411ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:33.908988Z","caller":"traceutil/trace.go:171","msg":"trace[1978350529] linearizableReadLoop","detail":"{readStateIndex:5279; appliedIndex:5277; }","duration":"432.778047ms","start":"2026-10-15T06:43:33.476191Z","end":"2026-10-15T06:43:33.908969Z","steps":["trace[1978350529] 'read index received' (duration: 432.034386ms)","trace[1978350529] 'applied index is now lower than readState.Index' (duration: 742.081µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:33.909104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.020367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:43:33.909154Z","caller":"traceutil/trace.go:171","msg":"trace[562161961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5085; }","duration":"509.072219ms","start":"2026-10-15T06:43:33.400068Z","end":"2026-10-15T06:43:33.90914Z","steps":["trace[562161961] 'agreement among raft nodes before linearized reading' (duration: 508.993806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:33.909198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.400024Z","time spent":"509.163491ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T06:43:34.409974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922973748755235400,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T06:43:34.485373Z","caller":"traceutil/trace.go:171","msg":"trace[346871845] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"644.150741ms","start":"2026-10-15T06:43:33.841196Z","end":"2026-10-15T06:43:34.485347Z","steps":["trace[346871845] 'process raft request' (duration: 643.938415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.485528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.841168Z","time spent":"644.281284ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:34.485733Z","caller":"traceutil/trace.go:171","msg":"trace[1524840544] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"643.921795ms","start":"2026-10-15T06:43:33.841768Z","end":"2026-10-15T06:43:34.48569Z","steps":["trace[1524840544] 'process raft request' (duration: 643.541345ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:34.485873Z","caller":"traceutil/trace.go:171","msg":"trace[1971158378] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"607.716572ms","start":"2026-10-15T06:43:33.878141Z","end":"2026-10-15T06:43:34.485857Z","steps":["trace[1971158378] 'process raft request' (duration: 607.426524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.485961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.878097Z","time spent":"607.820475ms","remote":"127.0.0.1:52272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:34.485958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.841744Z","time spent":"644.106169ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:34.675909Z","caller":"traceutil/trace.go:171","msg":"trace[1238480684] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"761.138409ms","start":"2026-10-15T06:43:33.91475Z","end":"2026-10-15T06:43:34.675889Z","steps":["trace[1238480684] 'process raft request' (duration: 754.412275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.676017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.914728Z","time spent":"761.234041ms","remote":"127.0.0.1:57798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:34.676219Z","caller":"traceutil/trace.go:171","msg":"trace[645179015] linearizableReadLoop","detail":"{readStateIndex:5285; appliedIndex:5280; }","duration":"767.182175ms","start":"2026-10-15T06:43:33.909028Z","end":"2026-10-15T06:43:34.67621Z","steps":["trace[645179015] 'read index received' (duration: 576.337462ms)","trace[645179015] 'applied index is now lower than readState.Index' (duration: 190.844153ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:34.6764Z","caller":"traceutil/trace.go:171","msg":"trace[1393921922] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"760.634495ms","start":"2026-10-15T06:43:33.915757Z","end":"2026-10-15T06:43:34.676392Z","steps":["trace[1393921922] 'process raft request' (duration: 760.360067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.676474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.915741Z","time spent":"760.702806ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:34.676667Z","caller":"traceutil/trace.go:171","msg":"trace[1711157804] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"759.293058ms","start":"2026-10-15T06:43:33.917366Z","end":"2026-10-15T06:43:34.676659Z","steps":["trace[1711157804] 'process raft request' (duration: 758.805905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.67688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103333952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-10-15T06:43:34.676924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195507759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-15T06:43:34.676933Z","caller":"traceutil/trace.go:171","msg":"trace[150639798] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5091; }","duration":"1.103433054s","start":"2026-10-15T06:43:33.573485Z","end":"2026-10-15T06:43:34.676918Z","steps":["trace[150639798] 'agreement among raft nodes before linearized reading' (duration: 1.103220819s)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:34.67695Z","caller":"traceutil/trace.go:171","msg":"trace[1825506250] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5091; }","duration":"1.19555586s","start":"2026-10-15T06:43:33.481385Z","end":"2026-10-15T06:43:34.676941Z","steps":["trace[1825506250] 'agreement among raft nodes before linearized reading' (duration: 1.195461787s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.676964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.573468Z","time spent":"1.103490316s","remote":"127.0.0.1:57796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T06:43:34.676971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.481368Z","time spent":"1.195597122s","remote":"127.0.0.1:57730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1364,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-10-15T06:43:34.67719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.234674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-15T06:43:34.67721Z","caller":"traceutil/trace.go:171","msg":"trace[1107995454] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5091; }","duration":"489.315046ms","start":"2026-10-15T06:43:34.187888Z","end":"2026-10-15T06:43:34.677203Z","steps":["trace[1107995454] 'agreement among raft nodes before linearized reading' (duration: 489.238524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.677221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.663144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-15T06:43:34.677245Z","caller":"traceutil/trace.go:171","msg":"trace[977517406] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5091; }","duration":"106.718676ms","start":"2026-10-15T06:43:34.570519Z","end":"2026-10-15T06:43:34.677238Z","steps":["trace[977517406] 'agreement among raft nodes before linearized reading' (duration: 106.642564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.67734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.024506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe\" ","response":"range_response_count:1 size:3760"} {"level":"warn","ts":"2026-10-15T06:43:34.677226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:34.187869Z","time spent":"489.353978ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-15T06:43:34.677435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.697243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-10-15T06:43:34.677369Z","caller":"traceutil/trace.go:171","msg":"trace[1643832351] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c76cdad0-6f76-400f-afc8-5384103540fe; range_end:; response_count:1; response_revision:5091; }","duration":"281.103198ms","start":"2026-10-15T06:43:34.396257Z","end":"2026-10-15T06:43:34.677361Z","steps":["trace[1643832351] 'agreement among raft nodes before linearized reading' (duration: 280.980424ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:34.677458Z","caller":"traceutil/trace.go:171","msg":"trace[1129474903] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-37e5a46e-981f-4eee-9b34-e8fd29d55c01; range_end:; response_count:1; response_revision:5091; }","duration":"281.758735ms","start":"2026-10-15T06:43:34.395693Z","end":"2026-10-15T06:43:34.677452Z","steps":["trace[1129474903] 'agreement among raft nodes before linearized reading' (duration: 281.688433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.677599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:33.917331Z","time spent":"759.35146ms","remote":"127.0.0.1:57918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:35.068458Z","caller":"traceutil/trace.go:171","msg":"trace[96749225] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"135.504255ms","start":"2026-10-15T06:43:34.932926Z","end":"2026-10-15T06:43:35.06843Z","steps":["trace[96749225] 'process raft request' (duration: 74.42241ms)","trace[96749225] 'compare' (duration: 54.252518ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:35.068666Z","caller":"traceutil/trace.go:171","msg":"trace[1245386809] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"135.554777ms","start":"2026-10-15T06:43:34.933085Z","end":"2026-10-15T06:43:35.06864Z","steps":["trace[1245386809] 'process raft request' (duration: 135.238088ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:35.069327Z","caller":"traceutil/trace.go:171","msg":"trace[201692166] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"135.749581ms","start":"2026-10-15T06:43:34.933566Z","end":"2026-10-15T06:43:35.069316Z","steps":["trace[201692166] 'process raft request' (duration: 134.839576ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:36.766134Z","caller":"traceutil/trace.go:171","msg":"trace[48696358] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"122.977172ms","start":"2026-10-15T06:43:36.643063Z","end":"2026-10-15T06:43:36.76604Z","steps":["trace[48696358] 'process raft request' (duration: 89.254887ms)","trace[48696358] 'compare' (duration: 33.398276ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:36.784858Z","caller":"traceutil/trace.go:171","msg":"trace[873718895] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"134.668792ms","start":"2026-10-15T06:43:36.650098Z","end":"2026-10-15T06:43:36.784767Z","steps":["trace[873718895] 'process raft request' (duration: 134.419546ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:51.046434Z","caller":"traceutil/trace.go:171","msg":"trace[752108860] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"157.697124ms","start":"2026-10-15T06:43:50.888712Z","end":"2026-10-15T06:43:51.046409Z","steps":["trace[752108860] 'process raft request' (duration: 157.536809ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:51.713058Z","caller":"traceutil/trace.go:171","msg":"trace[1606000642] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"140.996807ms","start":"2026-10-15T06:43:51.572049Z","end":"2026-10-15T06:43:51.713046Z","steps":["trace[1606000642] 'process raft request' (duration: 134.834298ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:51.713057Z","caller":"traceutil/trace.go:171","msg":"trace[1764921462] linearizableReadLoop","detail":"{readStateIndex:5718; appliedIndex:5717; }","duration":"137.209062ms","start":"2026-10-15T06:43:51.575832Z","end":"2026-10-15T06:43:51.713041Z","steps":["trace[1764921462] 'read index received' (duration: 131.013383ms)","trace[1764921462] 'applied index is now lower than readState.Index' (duration: 6.195079ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:51.713161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.325695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T06:43:51.71318Z","caller":"traceutil/trace.go:171","msg":"trace[1083752182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5509; }","duration":"137.406248ms","start":"2026-10-15T06:43:51.575769Z","end":"2026-10-15T06:43:51.713175Z","steps":["trace[1083752182] 'agreement among raft nodes before linearized reading' (duration: 137.319076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:51.713358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.64074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T06:43:51.713432Z","caller":"traceutil/trace.go:171","msg":"trace[682289594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5509; }","duration":"131.747993ms","start":"2026-10-15T06:43:51.581668Z","end":"2026-10-15T06:43:51.713416Z","steps":["trace[682289594] 'agreement among raft nodes before linearized reading' (duration: 131.529387ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:52.22568Z","caller":"traceutil/trace.go:171","msg":"trace[1906704796] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"119.540118ms","start":"2026-10-15T06:43:52.106104Z","end":"2026-10-15T06:43:52.225644Z","steps":["trace[1906704796] 'process raft request' (duration: 119.214679ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:44:01.31711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2923} {"level":"info","ts":"2026-10-15T06:44:01.398767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2923,"took":"80.100456ms","hash":1782070281,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":31420416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T06:44:01.398862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1782070281,"revision":2923,"compact-revision":-1} {"level":"info","ts":"2026-10-15T06:49:01.331503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5716} {"level":"info","ts":"2026-10-15T06:49:01.577811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5716,"took":"244.299365ms","hash":3698215602,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":26501120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T06:49:01.577921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3698215602,"revision":5716,"compact-revision":2923} {"level":"warn","ts":"2026-10-15T06:49:25.166305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.561506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:49:25.166426Z","caller":"traceutil/trace.go:171","msg":"trace[2013266331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8127; }","duration":"131.724009ms","start":"2026-10-15T06:49:25.034678Z","end":"2026-10-15T06:49:25.166402Z","steps":["trace[2013266331] 'range keys from in-memory index tree' (duration: 131.500423ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:17.621598Z","caller":"traceutil/trace.go:171","msg":"trace[31377028] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"123.136567ms","start":"2026-10-15T06:50:17.498414Z","end":"2026-10-15T06:50:17.621551Z","steps":["trace[31377028] 'process raft request' (duration: 28.506604ms)","trace[31377028] 'compare' (duration: 94.347195ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:50:18.272091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.020098ms","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-10-15T06:50:18.272222Z","caller":"traceutil/trace.go:171","msg":"trace[119884602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8607; }","duration":"101.198713ms","start":"2026-10-15T06:50:18.170996Z","end":"2026-10-15T06:50:18.272195Z","steps":["trace[119884602] 'range keys from in-memory index tree' (duration: 100.774552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:20.247333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.697892ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T06:50:20.247468Z","caller":"traceutil/trace.go:171","msg":"trace[828327053] linearizableReadLoop","detail":"{readStateIndex:8923; appliedIndex:8922; }","duration":"234.905221ms","start":"2026-10-15T06:50:20.012542Z","end":"2026-10-15T06:50:20.247447Z","steps":["trace[828327053] 'read index received' (duration: 58.960982ms)","trace[828327053] 'applied index is now lower than readState.Index' (duration: 175.942739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:50:20.247839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.084468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T06:50:20.247938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.449268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h9dvj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T06:50:20.247981Z","caller":"traceutil/trace.go:171","msg":"trace[268129682] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h9dvj; range_end:; response_count:1; response_revision:8617; }","duration":"142.51624ms","start":"2026-10-15T06:50:20.105448Z","end":"2026-10-15T06:50:20.247965Z","steps":["trace[268129682] 'agreement among raft nodes before linearized reading' (duration: 142.317955ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:20.247994Z","caller":"traceutil/trace.go:171","msg":"trace[2110961184] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8617; }","duration":"130.239312ms","start":"2026-10-15T06:50:20.117707Z","end":"2026-10-15T06:50:20.247946Z","steps":["trace[2110961184] 'agreement among raft nodes before linearized reading' (duration: 130.043197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:20.247997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.434615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-10-15T06:50:20.24809Z","caller":"traceutil/trace.go:171","msg":"trace[505614712] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8617; }","duration":"235.644671ms","start":"2026-10-15T06:50:20.012423Z","end":"2026-10-15T06:50:20.248068Z","steps":["trace[505614712] 'agreement among raft nodes before linearized reading' (duration: 235.351533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:20.248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.251603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T06:50:20.248318Z","caller":"traceutil/trace.go:171","msg":"trace[1873004943] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8617; }","duration":"158.624373ms","start":"2026-10-15T06:50:20.089677Z","end":"2026-10-15T06:50:20.248302Z","steps":["trace[1873004943] 'agreement among raft nodes before linearized reading' (duration: 158.101739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:20.248771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.806476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:50:20.248865Z","caller":"traceutil/trace.go:171","msg":"trace[282080866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8617; }","duration":"215.927509ms","start":"2026-10-15T06:50:20.032921Z","end":"2026-10-15T06:50:20.248848Z","steps":["trace[282080866] 'agreement among raft nodes before linearized reading' (duration: 214.715826ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:31.393816Z","caller":"traceutil/trace.go:171","msg":"trace[364207982] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"167.96301ms","start":"2026-10-15T06:50:31.225795Z","end":"2026-10-15T06:50:31.393758Z","steps":["trace[364207982] 'process raft request' (duration: 167.730984ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.80077Z","caller":"traceutil/trace.go:171","msg":"trace[1236539709] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"421.39235ms","start":"2026-10-15T06:50:32.379353Z","end":"2026-10-15T06:50:32.800745Z","steps":["trace[1236539709] 'process raft request' (duration: 421.237136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.800932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.379328Z","time spent":"421.503473ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:50:32.823933Z","caller":"traceutil/trace.go:171","msg":"trace[488344476] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"423.870738ms","start":"2026-10-15T06:50:32.400035Z","end":"2026-10-15T06:50:32.823906Z","steps":["trace[488344476] 'process raft request' (duration: 423.550919ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.823938Z","caller":"traceutil/trace.go:171","msg":"trace[92148520] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"313.592825ms","start":"2026-10-15T06:50:32.510311Z","end":"2026-10-15T06:50:32.823903Z","steps":["trace[92148520] 'process raft request' (duration: 313.443391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.824052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.400005Z","time spent":"423.983671ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:50:32.82406Z","caller":"traceutil/trace.go:171","msg":"trace[1729768973] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8985; }","duration":"403.333034ms","start":"2026-10-15T06:50:32.420711Z","end":"2026-10-15T06:50:32.824044Z","steps":["trace[1729768973] 'read index received' (duration: 379.908799ms)","trace[1729768973] 'applied index is now lower than readState.Index' (duration: 23.423284ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:50:32.824164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.510284Z","time spent":"313.78323ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:50:32.82426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.544599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T06:50:32.824252Z","caller":"traceutil/trace.go:171","msg":"trace[605479711] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"189.576734ms","start":"2026-10-15T06:50:32.634625Z","end":"2026-10-15T06:50:32.824202Z","steps":["trace[605479711] 'process raft request' (duration: 189.198783ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.824308Z","caller":"traceutil/trace.go:171","msg":"trace[1779956541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8681; }","duration":"403.631531ms","start":"2026-10-15T06:50:32.420665Z","end":"2026-10-15T06:50:32.824296Z","steps":["trace[1779956541] 'agreement among raft nodes before linearized reading' (duration: 403.473967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.824346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.420644Z","time spent":"403.694533ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T06:50:32.824373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.669197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-15T06:50:32.824421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.292807ms","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-10-15T06:50:32.82445Z","caller":"traceutil/trace.go:171","msg":"trace[1891864193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8681; }","duration":"293.343408ms","start":"2026-10-15T06:50:32.531097Z","end":"2026-10-15T06:50:32.82444Z","steps":["trace[1891864193] 'agreement among raft nodes before linearized reading' (duration: 293.262956ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.824455Z","caller":"traceutil/trace.go:171","msg":"trace[1527697771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8681; }","duration":"293.78935ms","start":"2026-10-15T06:50:32.530642Z","end":"2026-10-15T06:50:32.824431Z","steps":["trace[1527697771] 'agreement among raft nodes before linearized reading' (duration: 293.602125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.824499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.864445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T06:50:32.824588Z","caller":"traceutil/trace.go:171","msg":"trace[1690653695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8681; }","duration":"293.003889ms","start":"2026-10-15T06:50:32.531563Z","end":"2026-10-15T06:50:32.824567Z","steps":["trace[1690653695] 'agreement among raft nodes before linearized reading' (duration: 292.701261ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.969384Z","caller":"traceutil/trace.go:171","msg":"trace[1757037706] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"139.965069ms","start":"2026-10-15T06:50:32.82938Z","end":"2026-10-15T06:50:32.969345Z","steps":["trace[1757037706] 'process raft request' (duration: 139.863556ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.96941Z","caller":"traceutil/trace.go:171","msg":"trace[1968245638] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"140.39639ms","start":"2026-10-15T06:50:32.828986Z","end":"2026-10-15T06:50:32.969382Z","steps":["trace[1968245638] 'process raft request' (duration: 140.198394ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.969407Z","caller":"traceutil/trace.go:171","msg":"trace[551955128] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"140.446581ms","start":"2026-10-15T06:50:32.828914Z","end":"2026-10-15T06:50:32.969361Z","steps":["trace[551955128] 'process raft request' (duration: 140.137783ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:59.323669Z","caller":"traceutil/trace.go:171","msg":"trace[87171647] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"120.050172ms","start":"2026-10-15T06:50:59.20359Z","end":"2026-10-15T06:50:59.32364Z","steps":["trace[87171647] 'process raft request' (duration: 119.855687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:02.02403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.30318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-15T06:51:02.024148Z","caller":"traceutil/trace.go:171","msg":"trace[1931934607] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8891; }","duration":"460.466954ms","start":"2026-10-15T06:51:01.563652Z","end":"2026-10-15T06:51:02.024119Z","steps":["trace[1931934607] 'range keys from in-memory index tree' (duration: 460.087234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:02.024202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:01.563637Z","time spent":"460.551967ms","remote":"127.0.0.1:57796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-15T06:51:02.02469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.78695ms","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-10-15T06:51:02.024819Z","caller":"traceutil/trace.go:171","msg":"trace[2039711794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8891; }","duration":"149.949504ms","start":"2026-10-15T06:51:01.874841Z","end":"2026-10-15T06:51:02.02479Z","steps":["trace[2039711794] 'range keys from in-memory index tree' (duration: 149.540922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:02.026049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.309673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:51:02.026293Z","caller":"traceutil/trace.go:171","msg":"trace[1977582419] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8891; }","duration":"461.080092ms","start":"2026-10-15T06:51:01.565166Z","end":"2026-10-15T06:51:02.026246Z","steps":["trace[1977582419] 'count revisions from in-memory index tree' (duration: 459.2347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:02.026604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:01.565128Z","time spent":"461.455772ms","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":23,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-10-15T06:51:02.183918Z","caller":"traceutil/trace.go:171","msg":"trace[869056484] linearizableReadLoop","detail":"{readStateIndex:9209; appliedIndex:9208; }","duration":"139.357073ms","start":"2026-10-15T06:51:02.044487Z","end":"2026-10-15T06:51:02.183844Z","steps":["trace[869056484] 'read index received' (duration: 139.140167ms)","trace[869056484] 'applied index is now lower than readState.Index' (duration: 215.086µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:51:02.18418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.675142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:51:02.184266Z","caller":"traceutil/trace.go:171","msg":"trace[800568439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8892; }","duration":"139.821435ms","start":"2026-10-15T06:51:02.04443Z","end":"2026-10-15T06:51:02.184251Z","steps":["trace[800568439] 'agreement among raft nodes before linearized reading' (duration: 139.670641ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:02.184711Z","caller":"traceutil/trace.go:171","msg":"trace[2137212838] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"140.400442ms","start":"2026-10-15T06:51:02.044298Z","end":"2026-10-15T06:51:02.184698Z","steps":["trace[2137212838] 'process raft request' (duration: 139.404484ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:13.738025Z","caller":"traceutil/trace.go:171","msg":"trace[1272590227] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"158.53101ms","start":"2026-10-15T06:51:13.579472Z","end":"2026-10-15T06:51:13.738003Z","steps":["trace[1272590227] 'process raft request' (duration: 92.577186ms)","trace[1272590227] 'compare' (duration: 65.204783ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:51:15.716844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.632066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:51:15.717007Z","caller":"traceutil/trace.go:171","msg":"trace[775311232] transaction","detail":"{read_only:false; response_revision:8963; number_of_response:1; }","duration":"600.236869ms","start":"2026-10-15T06:51:15.116746Z","end":"2026-10-15T06:51:15.716983Z","steps":["trace[775311232] 'process raft request' (duration: 374.378017ms)","trace[775311232] 'compare' (duration: 225.387319ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:51:15.717069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.116735Z","time spent":"600.30327ms","remote":"127.0.0.1:57742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:51:15.886752Z","caller":"traceutil/trace.go:171","msg":"trace[1830601007] transaction","detail":"{read_only:false; response_revision:8964; number_of_response:1; }","duration":"766.609644ms","start":"2026-10-15T06:51:15.120064Z","end":"2026-10-15T06:51:15.886674Z","steps":["trace[1830601007] 'process raft request' (duration: 740.519406ms)","trace[1830601007] 'compare' (duration: 25.591934ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:51:15.88682Z","caller":"traceutil/trace.go:171","msg":"trace[2008958711] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"765.270917ms","start":"2026-10-15T06:51:15.12151Z","end":"2026-10-15T06:51:15.886781Z","steps":["trace[2008958711] 'process raft request' (duration: 765.03913ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:15.886961Z","caller":"traceutil/trace.go:171","msg":"trace[1468681995] linearizableReadLoop","detail":"{readStateIndex:9285; appliedIndex:9282; }","duration":"764.127786ms","start":"2026-10-15T06:51:15.122791Z","end":"2026-10-15T06:51:15.886918Z","steps":["trace[1468681995] 'read index received' (duration: 368.272389ms)","trace[1468681995] 'applied index is now lower than readState.Index' (duration: 395.853777ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:51:15.887077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.120055Z","time spent":"766.867841ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4618,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:51:15.887298Z","caller":"traceutil/trace.go:171","msg":"trace[1982938559] transaction","detail":"{read_only:false; response_revision:8966; number_of_response:1; }","duration":"749.474363ms","start":"2026-10-15T06:51:15.137812Z","end":"2026-10-15T06:51:15.887287Z","steps":["trace[1982938559] 'process raft request' (duration: 748.903607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.887618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.137793Z","time spent":"749.728289ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:51:15.887678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.121502Z","time spent":"765.480633ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:51:15.887813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.02112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bq2p7\" ","response":"range_response_count:1 size:4633"} {"level":"info","ts":"2026-10-15T06:51:15.887846Z","caller":"traceutil/trace.go:171","msg":"trace[1724717576] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bq2p7; range_end:; response_count:1; response_revision:8966; }","duration":"765.096522ms","start":"2026-10-15T06:51:15.122741Z","end":"2026-10-15T06:51:15.887837Z","steps":["trace[1724717576] 'agreement among raft nodes before linearized reading' (duration: 765.02959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.887863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.122721Z","time spent":"765.138413ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4655,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bq2p7\" "} {"level":"warn","ts":"2026-10-15T06:51:15.899447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.088331ms","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-10-15T06:51:15.899544Z","caller":"traceutil/trace.go:171","msg":"trace[36542693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8968; }","duration":"457.240455ms","start":"2026-10-15T06:51:15.44228Z","end":"2026-10-15T06:51:15.89952Z","steps":["trace[36542693] 'agreement among raft nodes before linearized reading' (duration: 456.993859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.899586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.442261Z","time spent":"457.316057ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T06:51:15.89947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.477773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bq2p7\" ","response":"range_response_count:1 size:4633"} {"level":"info","ts":"2026-10-15T06:51:15.89968Z","caller":"traceutil/trace.go:171","msg":"trace[484519967] transaction","detail":"{read_only:false; response_revision:8967; number_of_response:1; }","duration":"202.627402ms","start":"2026-10-15T06:51:15.697006Z","end":"2026-10-15T06:51:15.899633Z","steps":["trace[484519967] 'process raft request' (duration: 201.966194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.899768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.685916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"warn","ts":"2026-10-15T06:51:15.899792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.338532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-10-15T06:51:15.899874Z","caller":"traceutil/trace.go:171","msg":"trace[472060524] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:8968; }","duration":"177.83172ms","start":"2026-10-15T06:51:15.722009Z","end":"2026-10-15T06:51:15.89984Z","steps":["trace[472060524] 'agreement among raft nodes before linearized reading' (duration: 177.537002ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:15.89981Z","caller":"traceutil/trace.go:171","msg":"trace[355219030] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bq2p7; range_end:; response_count:1; response_revision:8968; }","duration":"380.829003ms","start":"2026-10-15T06:51:15.518909Z","end":"2026-10-15T06:51:15.899738Z","steps":["trace[355219030] 'agreement among raft nodes before linearized reading' (duration: 380.311519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.900043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.548832ms","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-10-15T06:51:15.900069Z","caller":"traceutil/trace.go:171","msg":"trace[1948819328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8968; }","duration":"682.615473ms","start":"2026-10-15T06:51:15.217448Z","end":"2026-10-15T06:51:15.900064Z","steps":["trace[1948819328] 'agreement among raft nodes before linearized reading' (duration: 682.555892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.900096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.217427Z","time spent":"682.662625ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T06:51:15.90004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.518872Z","time spent":"381.151692ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4655,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bq2p7\" "} {"level":"warn","ts":"2026-10-15T06:51:15.899985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.150686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T06:51:15.899906Z","caller":"traceutil/trace.go:171","msg":"trace[1637668474] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8968; }","duration":"179.496096ms","start":"2026-10-15T06:51:15.720373Z","end":"2026-10-15T06:51:15.899869Z","steps":["trace[1637668474] 'agreement among raft nodes before linearized reading' (duration: 179.254989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.900204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.920653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T06:51:15.900229Z","caller":"traceutil/trace.go:171","msg":"trace[1675063560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8968; }","duration":"668.417883ms","start":"2026-10-15T06:51:15.231796Z","end":"2026-10-15T06:51:15.900214Z","steps":["trace[1675063560] 'agreement among raft nodes before linearized reading' (duration: 668.134946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.900262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.231779Z","time spent":"668.475044ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T06:51:15.90027Z","caller":"traceutil/trace.go:171","msg":"trace[208583611] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8968; }","duration":"742.981264ms","start":"2026-10-15T06:51:15.157267Z","end":"2026-10-15T06:51:15.900248Z","steps":["trace[208583611] 'agreement among raft nodes before linearized reading' (duration: 742.82802ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:15.89981Z","caller":"traceutil/trace.go:171","msg":"trace[1733555444] transaction","detail":"{read_only:false; response_revision:8968; number_of_response:1; }","duration":"155.331651ms","start":"2026-10-15T06:51:15.744431Z","end":"2026-10-15T06:51:15.899762Z","steps":["trace[1733555444] 'process raft request' (duration: 154.713734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.899988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.906207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"} {"level":"info","ts":"2026-10-15T06:51:15.900415Z","caller":"traceutil/trace.go:171","msg":"trace[1478890554] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8968; }","duration":"777.326288ms","start":"2026-10-15T06:51:15.123076Z","end":"2026-10-15T06:51:15.900403Z","steps":["trace[1478890554] 'agreement among raft nodes before linearized reading' (duration: 776.858596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.900442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.123058Z","time spent":"777.37843ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":3088,"request content":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-10-15T06:51:15.900312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.157212Z","time spent":"743.088127ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T06:51:15.899909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.197497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T06:51:15.900562Z","caller":"traceutil/trace.go:171","msg":"trace[2031393154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8968; }","duration":"644.910467ms","start":"2026-10-15T06:51:15.255645Z","end":"2026-10-15T06:51:15.900555Z","steps":["trace[2031393154] 'agreement among raft nodes before linearized reading' (duration: 644.171626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:15.900587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.255621Z","time spent":"644.954298ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T06:51:16.299228Z","caller":"traceutil/trace.go:171","msg":"trace[635179458] transaction","detail":"{read_only:false; response_revision:8970; number_of_response:1; }","duration":"393.123262ms","start":"2026-10-15T06:51:15.906077Z","end":"2026-10-15T06:51:16.2992Z","steps":["trace[635179458] 'process raft request' (duration: 392.786703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:16.299407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.906049Z","time spent":"393.257686ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:51:16.299617Z","caller":"traceutil/trace.go:171","msg":"trace[1924613691] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"392.996699ms","start":"2026-10-15T06:51:15.906572Z","end":"2026-10-15T06:51:16.299569Z","steps":["trace[1924613691] 'process raft request' (duration: 392.569537ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:16.299812Z","caller":"traceutil/trace.go:171","msg":"trace[1326784269] transaction","detail":"{read_only:false; response_revision:8973; number_of_response:1; }","duration":"391.510548ms","start":"2026-10-15T06:51:15.908279Z","end":"2026-10-15T06:51:16.29979Z","steps":["trace[1326784269] 'process raft request' (duration: 391.333513ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:16.299801Z","caller":"traceutil/trace.go:171","msg":"trace[1759479319] linearizableReadLoop","detail":"{readStateIndex:9290; appliedIndex:9288; }","duration":"393.202705ms","start":"2026-10-15T06:51:15.906567Z","end":"2026-10-15T06:51:16.29977Z","steps":["trace[1759479319] 'read index received' (duration: 11.099745ms)","trace[1759479319] 'applied index is now lower than readState.Index' (duration: 382.10164ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:51:16.299848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.906563Z","time spent":"393.171714ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:51:16.299943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.908273Z","time spent":"391.59147ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:51:16.300081Z","caller":"traceutil/trace.go:171","msg":"trace[543789913] transaction","detail":"{read_only:false; response_revision:8972; number_of_response:1; }","duration":"392.078654ms","start":"2026-10-15T06:51:15.907987Z","end":"2026-10-15T06:51:16.300065Z","steps":["trace[543789913] 'process raft request' (duration: 391.452507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:16.300119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.547634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bq2p7\" ","response":"range_response_count:1 size:4638"} {"level":"info","ts":"2026-10-15T06:51:16.300165Z","caller":"traceutil/trace.go:171","msg":"trace[1700478102] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bq2p7; range_end:; response_count:1; response_revision:8974; }","duration":"393.623566ms","start":"2026-10-15T06:51:15.906525Z","end":"2026-10-15T06:51:16.300148Z","steps":["trace[1700478102] 'agreement among raft nodes before linearized reading' (duration: 393.539624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:16.300187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.907952Z","time spent":"392.162626ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:51:16.300217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.906498Z","time spent":"393.708559ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4660,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bq2p7\" "} {"level":"info","ts":"2026-10-15T06:51:16.300326Z","caller":"traceutil/trace.go:171","msg":"trace[2064691747] transaction","detail":"{read_only:false; response_revision:8974; number_of_response:1; }","duration":"389.170814ms","start":"2026-10-15T06:51:15.911136Z","end":"2026-10-15T06:51:16.300307Z","steps":["trace[2064691747] 'process raft request' (duration: 388.536336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:16.300389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.341358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-10-15T06:51:16.300436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.911101Z","time spent":"389.280537ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4623,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:51:16.300475Z","caller":"traceutil/trace.go:171","msg":"trace[1879728638] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8974; }","duration":"389.508263ms","start":"2026-10-15T06:51:15.910946Z","end":"2026-10-15T06:51:16.300454Z","steps":["trace[1879728638] 'agreement among raft nodes before linearized reading' (duration: 389.238455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:16.300532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.910923Z","time spent":"389.594905ms","remote":"127.0.0.1:57742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":553,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-10-15T06:51:16.300648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.667137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:51:16.300698Z","caller":"traceutil/trace.go:171","msg":"trace[945113125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8974; }","duration":"265.753809ms","start":"2026-10-15T06:51:16.03493Z","end":"2026-10-15T06:51:16.300684Z","steps":["trace[945113125] 'agreement among raft nodes before linearized reading' (duration: 265.681607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:16.300821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.595145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-10-15T06:51:16.30086Z","caller":"traceutil/trace.go:171","msg":"trace[1332950069] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8974; }","duration":"389.689169ms","start":"2026-10-15T06:51:15.911161Z","end":"2026-10-15T06:51:16.30085Z","steps":["trace[1332950069] 'agreement among raft nodes before linearized reading' (duration: 389.585206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:16.300921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:15.911117Z","time spent":"389.796101ms","remote":"127.0.0.1:57742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":648,"request content":"key:\"/registry/configmaps/openstack/rook-config-override\" "} {"level":"info","ts":"2026-10-15T06:51:16.427281Z","caller":"traceutil/trace.go:171","msg":"trace[279264839] transaction","detail":"{read_only:false; response_revision:8976; number_of_response:1; }","duration":"122.534061ms","start":"2026-10-15T06:51:16.304697Z","end":"2026-10-15T06:51:16.427231Z","steps":["trace[279264839] 'process raft request' (duration: 96.114224ms)","trace[279264839] 'compare' (duration: 25.642005ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:51:28.912764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.099086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T06:51:28.912798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.317897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:51:28.912848Z","caller":"traceutil/trace.go:171","msg":"trace[912525720] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:9106; }","duration":"115.240469ms","start":"2026-10-15T06:51:28.79759Z","end":"2026-10-15T06:51:28.912831Z","steps":["trace[912525720] 'count revisions from in-memory index tree' (duration: 115.020433ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:28.912913Z","caller":"traceutil/trace.go:171","msg":"trace[6170835] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:9106; }","duration":"101.476501ms","start":"2026-10-15T06:51:28.811384Z","end":"2026-10-15T06:51:28.91286Z","steps":["trace[6170835] 'count revisions from in-memory index tree' (duration: 101.231144ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:39.382714Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000252f.wal"} {"level":"info","ts":"2026-10-15T06:51:49.011401Z","caller":"traceutil/trace.go:171","msg":"trace[1268552954] linearizableReadLoop","detail":"{readStateIndex:9656; appliedIndex:9653; }","duration":"104.987228ms","start":"2026-10-15T06:51:48.906383Z","end":"2026-10-15T06:51:49.01137Z","steps":["trace[1268552954] 'read index received' (duration: 10.261913ms)","trace[1268552954] 'applied index is now lower than readState.Index' (duration: 94.723865ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:51:49.01159Z","caller":"traceutil/trace.go:171","msg":"trace[1241357942] transaction","detail":"{read_only:false; response_revision:9324; number_of_response:1; }","duration":"154.390486ms","start":"2026-10-15T06:51:48.85716Z","end":"2026-10-15T06:51:49.01155Z","steps":["trace[1241357942] 'process raft request' (duration: 154.041476ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:49.011623Z","caller":"traceutil/trace.go:171","msg":"trace[297734506] transaction","detail":"{read_only:false; response_revision:9325; number_of_response:1; }","duration":"154.396906ms","start":"2026-10-15T06:51:48.8572Z","end":"2026-10-15T06:51:49.011597Z","steps":["trace[297734506] 'process raft request' (duration: 154.069527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:49.01169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.292466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-10-15T06:51:49.011749Z","caller":"traceutil/trace.go:171","msg":"trace[783327861] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:9325; }","duration":"105.41593ms","start":"2026-10-15T06:51:48.906311Z","end":"2026-10-15T06:51:49.011727Z","steps":["trace[783327861] 'agreement among raft nodes before linearized reading' (duration: 105.210674ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:49.011721Z","caller":"traceutil/trace.go:171","msg":"trace[1519674842] transaction","detail":"{read_only:false; response_revision:9323; number_of_response:1; }","duration":"154.54437ms","start":"2026-10-15T06:51:48.857119Z","end":"2026-10-15T06:51:49.011664Z","steps":["trace[1519674842] 'process raft request' (duration: 59.504206ms)","trace[1519674842] 'compare' (duration: 94.403517ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:52:20.784725Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7b14f43c75605232","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T06:52:20.791533Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T06:52:20.79168Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T06:52:44.604158Z","caller":"traceutil/trace.go:171","msg":"trace[1571279669] transaction","detail":"{read_only:false; response_revision:9816; number_of_response:1; }","duration":"199.023513ms","start":"2026-10-15T06:52:44.405104Z","end":"2026-10-15T06:52:44.604127Z","steps":["trace[1571279669] 'process raft request' (duration: 198.567991ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:44.752997Z","caller":"traceutil/trace.go:171","msg":"trace[611814789] transaction","detail":"{read_only:false; response_revision:9817; number_of_response:1; }","duration":"145.086021ms","start":"2026-10-15T06:52:44.607865Z","end":"2026-10-15T06:52:44.752951Z","steps":["trace[611814789] 'process raft request' (duration: 106.735856ms)","trace[611814789] 'compare' (duration: 38.133659ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:54:01.340641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7969} {"level":"info","ts":"2026-10-15T06:54:01.415454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7969,"took":"72.35134ms","hash":609653222,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":27906048,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T06:54:01.415548Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":609653222,"revision":7969,"compact-revision":5716} {"level":"info","ts":"2026-10-15T06:58:25.582094Z","caller":"traceutil/trace.go:171","msg":"trace[169507734] linearizableReadLoop","detail":"{readStateIndex:12681; appliedIndex:12680; }","duration":"399.604028ms","start":"2026-10-15T06:58:25.182457Z","end":"2026-10-15T06:58:25.582061Z","steps":["trace[169507734] 'read index received' (duration: 371.02237ms)","trace[169507734] 'applied index is now lower than readState.Index' (duration: 28.580118ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:58:25.582163Z","caller":"traceutil/trace.go:171","msg":"trace[1738997321] transaction","detail":"{read_only:false; response_revision:12258; number_of_response:1; }","duration":"400.272276ms","start":"2026-10-15T06:58:25.181848Z","end":"2026-10-15T06:58:25.582121Z","steps":["trace[1738997321] 'process raft request' (duration: 371.632246ms)","trace[1738997321] 'compare' (duration: 28.231489ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:58:25.582347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:58:25.181827Z","time spent":"400.42423ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:58:25.582449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.724553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T06:58:25.58251Z","caller":"traceutil/trace.go:171","msg":"trace[337120674] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12258; }","duration":"384.868797ms","start":"2026-10-15T06:58:25.197627Z","end":"2026-10-15T06:58:25.582496Z","steps":["trace[337120674] 'agreement among raft nodes before linearized reading' (duration: 384.668341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:58:25.582559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:58:25.197613Z","time spent":"384.930178ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T06:58:25.58267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.157143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T06:58:25.582778Z","caller":"traceutil/trace.go:171","msg":"trace[234523430] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:12258; }","duration":"400.319727ms","start":"2026-10-15T06:58:25.18244Z","end":"2026-10-15T06:58:25.58276Z","steps":["trace[234523430] 'agreement among raft nodes before linearized reading' (duration: 399.732851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:58:25.582829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:58:25.182429Z","time spent":"400.38416ms","remote":"127.0.0.1:57918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-10-15T06:58:25.583078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.090916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-10-15T06:58:25.58325Z","caller":"traceutil/trace.go:171","msg":"trace[387594280] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:12258; }","duration":"395.369213ms","start":"2026-10-15T06:58:25.187754Z","end":"2026-10-15T06:58:25.583123Z","steps":["trace[387594280] 'agreement among raft nodes before linearized reading' (duration: 394.506689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:58:25.583406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:58:25.187725Z","time spent":"395.573289ms","remote":"127.0.0.1:48832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5673,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-15T06:58:25.86234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.842552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:58:25.862435Z","caller":"traceutil/trace.go:171","msg":"trace[1304771825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12259; }","duration":"130.985446ms","start":"2026-10-15T06:58:25.731429Z","end":"2026-10-15T06:58:25.862415Z","steps":["trace[1304771825] 'range keys from in-memory index tree' (duration: 130.76758ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:59:01.347836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10334} {"level":"info","ts":"2026-10-15T06:59:01.440533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10334,"took":"90.254797ms","hash":2862364215,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":25485312,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T06:59:01.440592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2862364215,"revision":10334,"compact-revision":7969} {"level":"info","ts":"2026-10-15T07:04:01.357571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12498} {"level":"info","ts":"2026-10-15T07:04:01.442381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12498,"took":"82.31689ms","hash":3059323431,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":27561984,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T07:04:01.442446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3059323431,"revision":12498,"compact-revision":10334} {"level":"info","ts":"2026-10-15T07:04:15.360512Z","caller":"traceutil/trace.go:171","msg":"trace[2037680661] linearizableReadLoop","detail":"{readStateIndex:15155; appliedIndex:15154; }","duration":"130.800822ms","start":"2026-10-15T07:04:15.229685Z","end":"2026-10-15T07:04:15.360486Z","steps":["trace[2037680661] 'read index received' (duration: 127.736028ms)","trace[2037680661] 'applied index is now lower than readState.Index' (duration: 3.063544ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:04:15.360679Z","caller":"traceutil/trace.go:171","msg":"trace[1073129752] transaction","detail":"{read_only:false; response_revision:14647; number_of_response:1; }","duration":"171.075719ms","start":"2026-10-15T07:04:15.189583Z","end":"2026-10-15T07:04:15.360659Z","steps":["trace[1073129752] 'process raft request' (duration: 167.769888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:15.36102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.277175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T07:04:15.361075Z","caller":"traceutil/trace.go:171","msg":"trace[570354821] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:14648; }","duration":"131.42625ms","start":"2026-10-15T07:04:15.229635Z","end":"2026-10-15T07:04:15.361061Z","steps":["trace[570354821] 'agreement among raft nodes before linearized reading' (duration: 131.168803ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:19.738289Z","caller":"traceutil/trace.go:171","msg":"trace[977369395] transaction","detail":"{read_only:false; response_revision:14669; number_of_response:1; }","duration":"241.783849ms","start":"2026-10-15T07:04:19.496477Z","end":"2026-10-15T07:04:19.738261Z","steps":["trace[977369395] 'process raft request' (duration: 241.555693ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:19.739396Z","caller":"traceutil/trace.go:171","msg":"trace[1678409487] linearizableReadLoop","detail":"{readStateIndex:15178; appliedIndex:15176; }","duration":"103.497141ms","start":"2026-10-15T07:04:19.635853Z","end":"2026-10-15T07:04:19.73935Z","steps":["trace[1678409487] 'read index received' (duration: 102.159865ms)","trace[1678409487] 'applied index is now lower than readState.Index' (duration: 1.335716ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:04:19.739642Z","caller":"traceutil/trace.go:171","msg":"trace[406562994] transaction","detail":"{read_only:false; response_revision:14670; number_of_response:1; }","duration":"119.195632ms","start":"2026-10-15T07:04:19.62041Z","end":"2026-10-15T07:04:19.739606Z","steps":["trace[406562994] 'process raft request' (duration: 118.823902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:19.739798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.929443ms","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-10-15T07:04:19.739974Z","caller":"traceutil/trace.go:171","msg":"trace[1090760355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14670; }","duration":"104.117618ms","start":"2026-10-15T07:04:19.635774Z","end":"2026-10-15T07:04:19.739892Z","steps":["trace[1090760355] 'agreement among raft nodes before linearized reading' (duration: 103.737388ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:41.979536Z","caller":"traceutil/trace.go:171","msg":"trace[800079594] transaction","detail":"{read_only:false; response_revision:14789; number_of_response:1; }","duration":"146.793551ms","start":"2026-10-15T07:04:41.832709Z","end":"2026-10-15T07:04:41.979503Z","steps":["trace[800079594] 'process raft request' (duration: 146.564725ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:42.083168Z","caller":"traceutil/trace.go:171","msg":"trace[2096723205] linearizableReadLoop","detail":"{readStateIndex:15303; appliedIndex:15301; }","duration":"238.372916ms","start":"2026-10-15T07:04:41.844771Z","end":"2026-10-15T07:04:42.083144Z","steps":["trace[2096723205] 'read index received' (duration: 134.507304ms)","trace[2096723205] 'applied index is now lower than readState.Index' (duration: 103.864722ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:04:42.083265Z","caller":"traceutil/trace.go:171","msg":"trace[1057651847] transaction","detail":"{read_only:false; response_revision:14790; number_of_response:1; }","duration":"245.897404ms","start":"2026-10-15T07:04:41.83733Z","end":"2026-10-15T07:04:42.083227Z","steps":["trace[1057651847] 'process raft request' (duration: 242.426518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:42.08334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.541271ms","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":"warn","ts":"2026-10-15T07:04:42.08339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.982806ms","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-10-15T07:04:42.083416Z","caller":"traceutil/trace.go:171","msg":"trace[2146468762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14790; }","duration":"238.662914ms","start":"2026-10-15T07:04:41.844722Z","end":"2026-10-15T07:04:42.083385Z","steps":["trace[2146468762] 'agreement among raft nodes before linearized reading' (duration: 238.53399ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:42.083435Z","caller":"traceutil/trace.go:171","msg":"trace[1862417807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14790; }","duration":"214.061629ms","start":"2026-10-15T07:04:41.869357Z","end":"2026-10-15T07:04:42.083419Z","steps":["trace[1862417807] 'agreement among raft nodes before linearized reading' (duration: 213.923525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:42.083746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.097639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T07:04:42.083779Z","caller":"traceutil/trace.go:171","msg":"trace[1607701271] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14790; }","duration":"179.132389ms","start":"2026-10-15T07:04:41.904638Z","end":"2026-10-15T07:04:42.08377Z","steps":["trace[1607701271] 'agreement among raft nodes before linearized reading' (duration: 179.045107ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:52.261681Z","caller":"traceutil/trace.go:171","msg":"trace[383843845] transaction","detail":"{read_only:false; response_revision:14841; number_of_response:1; }","duration":"128.414737ms","start":"2026-10-15T07:04:52.133244Z","end":"2026-10-15T07:04:52.261659Z","steps":["trace[383843845] 'process raft request' (duration: 127.655286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:05:00.093357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.64133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:05:00.093481Z","caller":"traceutil/trace.go:171","msg":"trace[116906554] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14894; }","duration":"170.800315ms","start":"2026-10-15T07:04:59.922654Z","end":"2026-10-15T07:05:00.093454Z","steps":["trace[116906554] 'range keys from in-memory index tree' (duration: 170.529448ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:05:00.998699Z","caller":"traceutil/trace.go:171","msg":"trace[761591372] linearizableReadLoop","detail":"{readStateIndex:15426; appliedIndex:15425; }","duration":"250.858346ms","start":"2026-10-15T07:05:00.747817Z","end":"2026-10-15T07:05:00.998675Z","steps":["trace[761591372] 'read index received' (duration: 250.625309ms)","trace[761591372] 'applied index is now lower than readState.Index' (duration: 232.207µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:05:00.998836Z","caller":"traceutil/trace.go:171","msg":"trace[1780581112] transaction","detail":"{read_only:false; response_revision:14908; number_of_response:1; }","duration":"357.6978ms","start":"2026-10-15T07:05:00.64106Z","end":"2026-10-15T07:05:00.998758Z","steps":["trace[1780581112] 'process raft request' (duration: 357.453843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:05:00.998964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.088883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:05:00.999013Z","caller":"traceutil/trace.go:171","msg":"trace[1694566226] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:14908; }","duration":"251.231247ms","start":"2026-10-15T07:05:00.747768Z","end":"2026-10-15T07:05:00.998999Z","steps":["trace[1694566226] 'agreement among raft nodes before linearized reading' (duration: 251.039211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:05:00.999092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:05:00.641015Z","time spent":"357.958888ms","remote":"127.0.0.1:57822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:05:00.999107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.430948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T07:05:00.999225Z","caller":"traceutil/trace.go:171","msg":"trace[863064779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14908; }","duration":"165.613913ms","start":"2026-10-15T07:05:00.833581Z","end":"2026-10-15T07:05:00.999195Z","steps":["trace[863064779] 'agreement among raft nodes before linearized reading' (duration: 165.296634ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:05:09.288588Z","caller":"traceutil/trace.go:171","msg":"trace[2108001686] linearizableReadLoop","detail":"{readStateIndex:15500; appliedIndex:15499; }","duration":"112.432607ms","start":"2026-10-15T07:05:09.176132Z","end":"2026-10-15T07:05:09.288564Z","steps":["trace[2108001686] 'read index received' (duration: 112.202981ms)","trace[2108001686] 'applied index is now lower than readState.Index' (duration: 228.726µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:05:09.288668Z","caller":"traceutil/trace.go:171","msg":"trace[569886656] transaction","detail":"{read_only:false; response_revision:14980; number_of_response:1; }","duration":"157.033398ms","start":"2026-10-15T07:05:09.131613Z","end":"2026-10-15T07:05:09.288647Z","steps":["trace[569886656] 'process raft request' (duration: 156.665088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:05:09.288789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.638573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:05:09.288881Z","caller":"traceutil/trace.go:171","msg":"trace[978643249] range","detail":"{range_begin:/registry/serviceaccounts/openstack/ovn-controller; range_end:; response_count:0; response_revision:14980; }","duration":"112.746146ms","start":"2026-10-15T07:05:09.17612Z","end":"2026-10-15T07:05:09.288866Z","steps":["trace[978643249] 'agreement among raft nodes before linearized reading' (duration: 112.602932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:05:09.479368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.375501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T07:05:09.479433Z","caller":"traceutil/trace.go:171","msg":"trace[1546401157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14980; }","duration":"186.453553ms","start":"2026-10-15T07:05:09.292966Z","end":"2026-10-15T07:05:09.47942Z","steps":["trace[1546401157] 'range keys from in-memory index tree' (duration: 186.163196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:05:09.479464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.43461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/ovn-northd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:05:09.479583Z","caller":"traceutil/trace.go:171","msg":"trace[1651988692] range","detail":"{range_begin:/registry/serviceaccounts/openstack/ovn-northd; range_end:; response_count:0; response_revision:14980; }","duration":"187.567104ms","start":"2026-10-15T07:05:09.291991Z","end":"2026-10-15T07:05:09.479558Z","steps":["trace[1651988692] 'range keys from in-memory index tree' (duration: 187.342998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:42.325365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.802138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T07:06:42.325627Z","caller":"traceutil/trace.go:171","msg":"trace[52971762] linearizableReadLoop","detail":"{readStateIndex:16706; appliedIndex:16704; }","duration":"269.136107ms","start":"2026-10-15T07:06:42.05647Z","end":"2026-10-15T07:06:42.325606Z","steps":["trace[52971762] 'read index received' (duration: 117.960059ms)","trace[52971762] 'applied index is now lower than readState.Index' (duration: 151.174358ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:06:42.325742Z","caller":"traceutil/trace.go:171","msg":"trace[999041003] transaction","detail":"{read_only:false; response_revision:16151; number_of_response:1; }","duration":"278.951975ms","start":"2026-10-15T07:06:42.046778Z","end":"2026-10-15T07:06:42.32573Z","steps":["trace[999041003] 'process raft request' (duration: 127.636673ms)","trace[999041003] 'compare' (duration: 150.441398ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:06:42.325861Z","caller":"traceutil/trace.go:171","msg":"trace[436822552] transaction","detail":"{read_only:false; response_revision:16152; number_of_response:1; }","duration":"278.75703ms","start":"2026-10-15T07:06:42.047069Z","end":"2026-10-15T07:06:42.325826Z","steps":["trace[436822552] 'process raft request' (duration: 278.451561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:42.326045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.302265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T07:06:42.326092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.581827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:06:42.326126Z","caller":"traceutil/trace.go:171","msg":"trace[65816767] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16152; }","duration":"123.407957ms","start":"2026-10-15T07:06:42.202697Z","end":"2026-10-15T07:06:42.326105Z","steps":["trace[65816767] 'agreement among raft nodes before linearized reading' (duration: 123.236703ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:42.326174Z","caller":"traceutil/trace.go:171","msg":"trace[2011578478] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16152; }","duration":"125.711901ms","start":"2026-10-15T07:06:42.200441Z","end":"2026-10-15T07:06:42.326153Z","steps":["trace[2011578478] 'agreement among raft nodes before linearized reading' (duration: 125.541466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:42.32623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.575692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T07:06:42.3264Z","caller":"traceutil/trace.go:171","msg":"trace[1957942371] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16152; }","duration":"123.758068ms","start":"2026-10-15T07:06:42.202584Z","end":"2026-10-15T07:06:42.326343Z","steps":["trace[1957942371] 'agreement among raft nodes before linearized reading' (duration: 123.293865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:42.326416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.721136ms","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-10-15T07:06:42.326777Z","caller":"traceutil/trace.go:171","msg":"trace[1161788625] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16152; }","duration":"124.162459ms","start":"2026-10-15T07:06:42.202547Z","end":"2026-10-15T07:06:42.326709Z","steps":["trace[1161788625] 'agreement among raft nodes before linearized reading' (duration: 123.378667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:42.32679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.31756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:776850"} {"level":"info","ts":"2026-10-15T07:06:42.327302Z","caller":"traceutil/trace.go:171","msg":"trace[300560376] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:16152; }","duration":"270.890875ms","start":"2026-10-15T07:06:42.0564Z","end":"2026-10-15T07:06:42.32729Z","steps":["trace[300560376] 'agreement among raft nodes before linearized reading' (duration: 269.369793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:42.32679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.69384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:776850"} {"level":"info","ts":"2026-10-15T07:06:42.327508Z","caller":"traceutil/trace.go:171","msg":"trace[924239619] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:16152; }","duration":"267.45954ms","start":"2026-10-15T07:06:42.060026Z","end":"2026-10-15T07:06:42.327486Z","steps":["trace[924239619] 'agreement among raft nodes before linearized reading' (duration: 265.838806ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:43.102441Z","caller":"traceutil/trace.go:171","msg":"trace[2091369181] linearizableReadLoop","detail":"{readStateIndex:16710; appliedIndex:16709; }","duration":"106.732691ms","start":"2026-10-15T07:06:42.995685Z","end":"2026-10-15T07:06:43.102418Z","steps":["trace[2091369181] 'read index received' (duration: 106.609967ms)","trace[2091369181] 'applied index is now lower than readState.Index' (duration: 121.874µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:06:43.102741Z","caller":"traceutil/trace.go:171","msg":"trace[2065541668] transaction","detail":"{read_only:false; response_revision:16156; number_of_response:1; }","duration":"111.026979ms","start":"2026-10-15T07:06:42.991699Z","end":"2026-10-15T07:06:43.102726Z","steps":["trace[2065541668] 'process raft request' (duration: 110.505675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:43.103063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.348378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h9dvj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T07:06:43.103144Z","caller":"traceutil/trace.go:171","msg":"trace[1879719582] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h9dvj; range_end:; response_count:1; response_revision:16156; }","duration":"107.456511ms","start":"2026-10-15T07:06:42.995666Z","end":"2026-10-15T07:06:43.103123Z","steps":["trace[1879719582] 'agreement among raft nodes before linearized reading' (duration: 107.225225ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:43.27942Z","caller":"traceutil/trace.go:171","msg":"trace[606534032] transaction","detail":"{read_only:false; response_revision:16157; number_of_response:1; }","duration":"170.368803ms","start":"2026-10-15T07:06:43.109017Z","end":"2026-10-15T07:06:43.279386Z","steps":["trace[606534032] 'process raft request' (duration: 148.232217ms)","trace[606534032] 'compare' (duration: 21.989541ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:09:01.368104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14533} {"level":"info","ts":"2026-10-15T07:09:01.465329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14533,"took":"93.880109ms","hash":1351682305,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":32190464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T07:09:01.465414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1351682305,"revision":14533,"compact-revision":12498} {"level":"warn","ts":"2026-10-15T07:12:29.819076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.536914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-10-15T07:12:29.81916Z","caller":"traceutil/trace.go:171","msg":"trace[1977767272] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:18652; }","duration":"115.664027ms","start":"2026-10-15T07:12:29.703479Z","end":"2026-10-15T07:12:29.819143Z","steps":["trace[1977767272] 'range keys from in-memory index tree' (duration: 115.367839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:34.961606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.918253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:12:34.961733Z","caller":"traceutil/trace.go:171","msg":"trace[1336622900] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:18708; }","duration":"165.112678ms","start":"2026-10-15T07:12:34.796596Z","end":"2026-10-15T07:12:34.961708Z","steps":["trace[1336622900] 'count revisions from in-memory index tree' (duration: 164.83015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:35.389111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.013558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:12:35.389233Z","caller":"traceutil/trace.go:171","msg":"trace[137979863] linearizableReadLoop","detail":"{readStateIndex:19350; appliedIndex:19349; }","duration":"320.285285ms","start":"2026-10-15T07:12:35.068931Z","end":"2026-10-15T07:12:35.389216Z","steps":["trace[137979863] 'read index received' (duration: 7.946187ms)","trace[137979863] 'applied index is now lower than readState.Index' (duration: 312.338058ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:12:35.389339Z","caller":"traceutil/trace.go:171","msg":"trace[413877739] transaction","detail":"{read_only:false; response_revision:18709; number_of_response:1; }","duration":"352.949197ms","start":"2026-10-15T07:12:35.036374Z","end":"2026-10-15T07:12:35.389324Z","steps":["trace[413877739] 'process raft request' (duration: 40.63223ms)","trace[413877739] 'compare' (duration: 311.570016ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:12:35.389397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:35.036352Z","time spent":"353.023078ms","remote":"127.0.0.1:57682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:12:35.389817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.470439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-10-15T07:12:35.389808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.85559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-10-15T07:12:35.389913Z","caller":"traceutil/trace.go:171","msg":"trace[1115259428] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18709; }","duration":"261.620073ms","start":"2026-10-15T07:12:35.128269Z","end":"2026-10-15T07:12:35.389889Z","steps":["trace[1115259428] 'agreement among raft nodes before linearized reading' (duration: 261.242603ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:35.389933Z","caller":"traceutil/trace.go:171","msg":"trace[1447230172] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18709; }","duration":"321.040454ms","start":"2026-10-15T07:12:35.068865Z","end":"2026-10-15T07:12:35.389905Z","steps":["trace[1447230172] 'agreement among raft nodes before linearized reading' (duration: 320.655914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:35.390025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:35.068822Z","time spent":"321.189308ms","remote":"127.0.0.1:57798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-10-15T07:12:35.390394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.645765ms","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-10-15T07:12:35.390455Z","caller":"traceutil/trace.go:171","msg":"trace[632912540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18709; }","duration":"164.705687ms","start":"2026-10-15T07:12:35.225733Z","end":"2026-10-15T07:12:35.390439Z","steps":["trace[632912540] 'agreement among raft nodes before linearized reading' (duration: 164.545673ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:35.601201Z","caller":"traceutil/trace.go:171","msg":"trace[1828715788] transaction","detail":"{read_only:false; response_revision:18710; number_of_response:1; }","duration":"205.48325ms","start":"2026-10-15T07:12:35.39568Z","end":"2026-10-15T07:12:35.601163Z","steps":["trace[1828715788] 'process raft request' (duration: 152.643327ms)","trace[1828715788] 'compare' (duration: 52.36503ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:12:35.643888Z","caller":"traceutil/trace.go:171","msg":"trace[221497596] transaction","detail":"{read_only:false; response_revision:18711; number_of_response:1; }","duration":"247.23009ms","start":"2026-10-15T07:12:35.396621Z","end":"2026-10-15T07:12:35.643851Z","steps":["trace[221497596] 'process raft request' (duration: 246.922191ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:35.644219Z","caller":"traceutil/trace.go:171","msg":"trace[1717220273] transaction","detail":"{read_only:false; response_revision:18712; number_of_response:1; }","duration":"236.699812ms","start":"2026-10-15T07:12:35.407464Z","end":"2026-10-15T07:12:35.644164Z","steps":["trace[1717220273] 'process raft request' (duration: 236.300331ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:35.64448Z","caller":"traceutil/trace.go:171","msg":"trace[1117184180] linearizableReadLoop","detail":"{readStateIndex:19352; appliedIndex:19350; }","duration":"238.588504ms","start":"2026-10-15T07:12:35.405871Z","end":"2026-10-15T07:12:35.64446Z","steps":["trace[1117184180] 'read index received' (duration: 142.436629ms)","trace[1117184180] 'applied index is now lower than readState.Index' (duration: 96.150915ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:12:35.64475Z","caller":"traceutil/trace.go:171","msg":"trace[1754645445] transaction","detail":"{read_only:false; response_revision:18713; number_of_response:1; }","duration":"211.771442ms","start":"2026-10-15T07:12:35.432793Z","end":"2026-10-15T07:12:35.644565Z","steps":["trace[1754645445] 'process raft request' (duration: 211.266718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:35.644847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.939874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-10-15T07:12:35.644927Z","caller":"traceutil/trace.go:171","msg":"trace[1259232654] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:18713; }","duration":"239.090598ms","start":"2026-10-15T07:12:35.405817Z","end":"2026-10-15T07:12:35.644907Z","steps":["trace[1259232654] 'agreement among raft nodes before linearized reading' (duration: 238.778919ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:12:35.661498Z","caller":"traceutil/trace.go:171","msg":"trace[190950230] transaction","detail":"{read_only:false; response_revision:18714; number_of_response:1; }","duration":"106.23349ms","start":"2026-10-15T07:12:35.555225Z","end":"2026-10-15T07:12:35.661458Z","steps":["trace[190950230] 'process raft request' (duration: 105.606313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:35.661985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.06979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T07:12:35.662057Z","caller":"traceutil/trace.go:171","msg":"trace[722729052] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18714; }","duration":"160.213534ms","start":"2026-10-15T07:12:35.501817Z","end":"2026-10-15T07:12:35.662031Z","steps":["trace[722729052] 'agreement among raft nodes before linearized reading' (duration: 159.983538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:35.662231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.301157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:12:35.662275Z","caller":"traceutil/trace.go:171","msg":"trace[58336926] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:18714; }","duration":"208.390309ms","start":"2026-10-15T07:12:35.453873Z","end":"2026-10-15T07:12:35.662263Z","steps":["trace[58336926] 'agreement among raft nodes before linearized reading' (duration: 208.289987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:35.662619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.75463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gntrz\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-15T07:12:35.662698Z","caller":"traceutil/trace.go:171","msg":"trace[668467719] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gntrz; range_end:; response_count:1; response_revision:18714; }","duration":"245.888934ms","start":"2026-10-15T07:12:35.416791Z","end":"2026-10-15T07:12:35.66268Z","steps":["trace[668467719] 'agreement among raft nodes before linearized reading' (duration: 245.634157ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:13:26.818986Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7b14f43c75605232","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:13:26.825251Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T07:13:26.825422Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T07:14:01.382598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17073} {"level":"info","ts":"2026-10-15T07:14:01.498429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17073,"took":"110.828196ms","hash":3138128685,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":34811904,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T07:14:01.498515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3138128685,"revision":17073,"compact-revision":14533} {"level":"warn","ts":"2026-10-15T07:18:28.208149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.23611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:18:28.208229Z","caller":"traceutil/trace.go:171","msg":"trace[2077747501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21461; }","duration":"171.383164ms","start":"2026-10-15T07:18:28.036831Z","end":"2026-10-15T07:18:28.208214Z","steps":["trace[2077747501] 'range keys from in-memory index tree' (duration: 171.071036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:28.208395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.611264ms","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-10-15T07:18:28.208525Z","caller":"traceutil/trace.go:171","msg":"trace[1784034539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21461; }","duration":"162.792049ms","start":"2026-10-15T07:18:28.045709Z","end":"2026-10-15T07:18:28.208501Z","steps":["trace[1784034539] 'range keys from in-memory index tree' (duration: 162.382297ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:28.343965Z","caller":"traceutil/trace.go:171","msg":"trace[330200168] transaction","detail":"{read_only:false; response_revision:21462; number_of_response:1; }","duration":"130.204776ms","start":"2026-10-15T07:18:28.21373Z","end":"2026-10-15T07:18:28.343935Z","steps":["trace[330200168] 'process raft request' (duration: 129.98536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:49.681219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.500262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-15T07:18:49.681377Z","caller":"traceutil/trace.go:171","msg":"trace[127954057] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:21569; }","duration":"104.729938ms","start":"2026-10-15T07:18:49.576613Z","end":"2026-10-15T07:18:49.681343Z","steps":["trace[127954057] 'range keys from in-memory index tree' (duration: 104.266466ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:50.021185Z","caller":"traceutil/trace.go:171","msg":"trace[685372473] transaction","detail":"{read_only:false; response_revision:21570; number_of_response:1; }","duration":"280.597196ms","start":"2026-10-15T07:18:49.740547Z","end":"2026-10-15T07:18:50.021144Z","steps":["trace[685372473] 'process raft request' (duration: 280.337259ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:50.270053Z","caller":"traceutil/trace.go:171","msg":"trace[682639235] transaction","detail":"{read_only:false; response_revision:21571; number_of_response:1; }","duration":"438.251874ms","start":"2026-10-15T07:18:49.831753Z","end":"2026-10-15T07:18:50.270004Z","steps":["trace[682639235] 'process raft request' (duration: 437.584445ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:50.270254Z","caller":"traceutil/trace.go:171","msg":"trace[149019896] transaction","detail":"{read_only:false; response_revision:21572; number_of_response:1; }","duration":"436.473595ms","start":"2026-10-15T07:18:49.833753Z","end":"2026-10-15T07:18:50.270227Z","steps":["trace[149019896] 'process raft request' (duration: 436.124825ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:50.270419Z","caller":"traceutil/trace.go:171","msg":"trace[1036961467] transaction","detail":"{read_only:false; response_revision:21573; number_of_response:1; }","duration":"412.868159ms","start":"2026-10-15T07:18:49.857534Z","end":"2026-10-15T07:18:50.270402Z","steps":["trace[1036961467] 'process raft request' (duration: 412.475208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:50.270432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:49.833742Z","time spent":"436.586079ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:18:50.270499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:49.857516Z","time spent":"412.93563ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:18:50.270499Z","caller":"traceutil/trace.go:171","msg":"trace[1656469561] transaction","detail":"{read_only:false; response_revision:21574; number_of_response:1; }","duration":"397.503497ms","start":"2026-10-15T07:18:49.87296Z","end":"2026-10-15T07:18:50.270463Z","steps":["trace[1656469561] 'process raft request' (duration: 397.305872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:50.270606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:49.872921Z","time spent":"397.631191ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:18:50.270342Z","caller":"traceutil/trace.go:171","msg":"trace[21581814] linearizableReadLoop","detail":"{readStateIndex:22307; appliedIndex:22304; }","duration":"419.203671ms","start":"2026-10-15T07:18:49.851118Z","end":"2026-10-15T07:18:50.270322Z","steps":["trace[21581814] 'read index received' (duration: 169.804851ms)","trace[21581814] 'applied index is now lower than readState.Index' (duration: 249.39724ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:18:50.270757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.633634ms","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-10-15T07:18:50.270273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:49.831719Z","time spent":"438.430439ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:18:50.270828Z","caller":"traceutil/trace.go:171","msg":"trace[1524647207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21574; }","duration":"419.740607ms","start":"2026-10-15T07:18:49.85107Z","end":"2026-10-15T07:18:50.270811Z","steps":["trace[1524647207] 'agreement among raft nodes before linearized reading' (duration: 419.48268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:50.270876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:49.851054Z","time spent":"419.813478ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T07:18:50.434839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.854316ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T07:18:50.435132Z","caller":"traceutil/trace.go:171","msg":"trace[450949339] linearizableReadLoop","detail":"{readStateIndex:22310; appliedIndex:22309; }","duration":"164.457324ms","start":"2026-10-15T07:18:50.270653Z","end":"2026-10-15T07:18:50.43511Z","steps":["trace[450949339] 'read index received' (duration: 7.373442ms)","trace[450949339] 'applied index is now lower than readState.Index' (duration: 157.082662ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:18:50.435369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.673646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-15T07:18:50.435394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.85819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19221"} {"level":"info","ts":"2026-10-15T07:18:50.435443Z","caller":"traceutil/trace.go:171","msg":"trace[536622412] transaction","detail":"{read_only:false; response_revision:21575; number_of_response:1; }","duration":"159.060697ms","start":"2026-10-15T07:18:50.27637Z","end":"2026-10-15T07:18:50.43543Z","steps":["trace[536622412] 'process raft request' (duration: 158.593024ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:50.435512Z","caller":"traceutil/trace.go:171","msg":"trace[869998928] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21574; }","duration":"319.852491ms","start":"2026-10-15T07:18:50.115606Z","end":"2026-10-15T07:18:50.435458Z","steps":["trace[869998928] 'agreement among raft nodes before linearized reading' (duration: 319.516352ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:50.435521Z","caller":"traceutil/trace.go:171","msg":"trace[706262763] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:21574; }","duration":"405.038304ms","start":"2026-10-15T07:18:50.030443Z","end":"2026-10-15T07:18:50.435481Z","steps":["trace[706262763] 'agreement among raft nodes before linearized reading' (duration: 404.683945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:50.435587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:50.030418Z","time spent":"405.158717ms","remote":"127.0.0.1:57814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":19243,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-10-15T07:18:50.435594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:50.115579Z","time spent":"320.005295ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T07:18:50.436188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.261305ms","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-10-15T07:18:50.436263Z","caller":"traceutil/trace.go:171","msg":"trace[1382509032] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21574; }","duration":"545.395999ms","start":"2026-10-15T07:18:49.890856Z","end":"2026-10-15T07:18:50.436252Z","steps":["trace[1382509032] 'agreement among raft nodes before linearized reading' (duration: 544.309139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:50.436304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:18:49.890826Z","time spent":"545.466271ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-15T07:18:50.591724Z","caller":"traceutil/trace.go:171","msg":"trace[812060252] linearizableReadLoop","detail":"{readStateIndex:22312; appliedIndex:22311; }","duration":"149.588018ms","start":"2026-10-15T07:18:50.442104Z","end":"2026-10-15T07:18:50.591692Z","steps":["trace[812060252] 'read index received' (duration: 149.038823ms)","trace[812060252] 'applied index is now lower than readState.Index' (duration: 547.235µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:18:50.592074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.736043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T07:18:50.592107Z","caller":"traceutil/trace.go:171","msg":"trace[2019270779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21576; }","duration":"133.825716ms","start":"2026-10-15T07:18:50.458271Z","end":"2026-10-15T07:18:50.592097Z","steps":["trace[2019270779] 'agreement among raft nodes before linearized reading' (duration: 133.577709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:18:50.592207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.068771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19221"} {"level":"info","ts":"2026-10-15T07:18:50.592246Z","caller":"traceutil/trace.go:171","msg":"trace[1523764460] transaction","detail":"{read_only:false; response_revision:21576; number_of_response:1; }","duration":"151.15683ms","start":"2026-10-15T07:18:50.440906Z","end":"2026-10-15T07:18:50.592062Z","steps":["trace[1523764460] 'process raft request' (duration: 150.364328ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:18:50.5923Z","caller":"traceutil/trace.go:171","msg":"trace[801556067] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21576; }","duration":"150.203254ms","start":"2026-10-15T07:18:50.442074Z","end":"2026-10-15T07:18:50.592277Z","steps":["trace[801556067] 'agreement among raft nodes before linearized reading' (duration: 149.780262ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:01.39252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19646} {"level":"info","ts":"2026-10-15T07:19:01.538262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19646,"took":"141.148236ms","hash":3187881614,"current-db-size-bytes":56012800,"current-db-size":"56 MB","current-db-size-in-use-bytes":29700096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T07:19:01.538342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3187881614,"revision":19646,"compact-revision":17073} {"level":"info","ts":"2026-10-15T07:20:03.498037Z","caller":"traceutil/trace.go:171","msg":"trace[1946422059] transaction","detail":"{read_only:false; response_revision:22257; number_of_response:1; }","duration":"101.718116ms","start":"2026-10-15T07:20:03.396293Z","end":"2026-10-15T07:20:03.498012Z","steps":["trace[1946422059] 'process raft request' (duration: 100.917874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:22:54.853959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.683238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-10-15T07:22:54.854075Z","caller":"traceutil/trace.go:171","msg":"trace[1735552569] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:24083; }","duration":"119.857633ms","start":"2026-10-15T07:22:54.734197Z","end":"2026-10-15T07:22:54.854055Z","steps":["trace[1735552569] 'range keys from in-memory index tree' (duration: 119.568845ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:22:55.21121Z","caller":"traceutil/trace.go:171","msg":"trace[1317154985] linearizableReadLoop","detail":"{readStateIndex:24895; appliedIndex:24894; }","duration":"235.220703ms","start":"2026-10-15T07:22:54.975961Z","end":"2026-10-15T07:22:55.211182Z","steps":["trace[1317154985] 'read index received' (duration: 234.979206ms)","trace[1317154985] 'applied index is now lower than readState.Index' (duration: 239.887µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:22:55.2113Z","caller":"traceutil/trace.go:171","msg":"trace[424400873] transaction","detail":"{read_only:false; response_revision:24084; number_of_response:1; }","duration":"282.98344ms","start":"2026-10-15T07:22:54.928265Z","end":"2026-10-15T07:22:55.211248Z","steps":["trace[424400873] 'process raft request' (duration: 282.695473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:22:55.211386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.409537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:22:55.211433Z","caller":"traceutil/trace.go:171","msg":"trace[1676925157] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:24084; }","duration":"235.532381ms","start":"2026-10-15T07:22:54.975885Z","end":"2026-10-15T07:22:55.211418Z","steps":["trace[1676925157] 'agreement among raft nodes before linearized reading' (duration: 235.428858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:22:55.225761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.76425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:22:55.225764Z","caller":"traceutil/trace.go:171","msg":"trace[1532177375] transaction","detail":"{read_only:false; response_revision:24085; number_of_response:1; }","duration":"156.451686ms","start":"2026-10-15T07:22:55.069265Z","end":"2026-10-15T07:22:55.225716Z","steps":["trace[1532177375] 'process raft request' (duration: 156.101886ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:22:55.225829Z","caller":"traceutil/trace.go:171","msg":"trace[847557577] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24085; }","duration":"188.916944ms","start":"2026-10-15T07:22:55.036895Z","end":"2026-10-15T07:22:55.225812Z","steps":["trace[847557577] 'agreement among raft nodes before linearized reading' (duration: 188.666877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:22:55.22604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.895176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.113\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-15T07:22:55.226177Z","caller":"traceutil/trace.go:171","msg":"trace[441242494] range","detail":"{range_begin:/registry/masterleases/199.19.213.113; range_end:; response_count:1; response_revision:24085; }","duration":"184.084751ms","start":"2026-10-15T07:22:55.042062Z","end":"2026-10-15T07:22:55.226147Z","steps":["trace[441242494] 'agreement among raft nodes before linearized reading' (duration: 183.6752ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:24:01.399297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21633} {"level":"info","ts":"2026-10-15T07:24:01.540451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21633,"took":"135.314266ms","hash":1957720969,"current-db-size-bytes":56434688,"current-db-size":"56 MB","current-db-size-in-use-bytes":43253760,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-15T07:24:01.540582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1957720969,"revision":21633,"compact-revision":19646} {"level":"info","ts":"2026-10-15T07:24:16.794926Z","caller":"traceutil/trace.go:171","msg":"trace[1130070342] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"108.960504ms","start":"2026-10-15T07:24:16.685929Z","end":"2026-10-15T07:24:16.79489Z","steps":["trace[1130070342] 'process raft request' (duration: 108.729978ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:24:16.811196Z","caller":"traceutil/trace.go:171","msg":"trace[1014722717] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"111.095643ms","start":"2026-10-15T07:24:16.700067Z","end":"2026-10-15T07:24:16.811163Z","steps":["trace[1014722717] 'process raft request' (duration: 110.6254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:26:12.043595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.532789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:26:12.044343Z","caller":"traceutil/trace.go:171","msg":"trace[223846250] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"146.600636ms","start":"2026-10-15T07:26:11.897318Z","end":"2026-10-15T07:26:12.043918Z","steps":["trace[223846250] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 144.309243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:26:17.570503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.820998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:26:17.570808Z","caller":"traceutil/trace.go:171","msg":"trace[717087582] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"217.208959ms","start":"2026-10-15T07:26:17.35358Z","end":"2026-10-15T07:26:17.570789Z","steps":["trace[717087582] 'process raft request' (duration: 217.157448ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:17.570982Z","caller":"traceutil/trace.go:171","msg":"trace[301155510] transaction","detail":"{read_only:false; response_revision:26090; number_of_response:1; }","duration":"242.560714ms","start":"2026-10-15T07:26:17.328369Z","end":"2026-10-15T07:26:17.570929Z","steps":["trace[301155510] 'process raft request' (duration: 117.18639ms)","trace[301155510] 'compare' (duration: 124.613703ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:26:17.570983Z","caller":"traceutil/trace.go:171","msg":"trace[558987716] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"219.599435ms","start":"2026-10-15T07:26:17.351349Z","end":"2026-10-15T07:26:17.570949Z","steps":["trace[558987716] 'process raft request' (duration: 219.274676ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:17.769691Z","caller":"traceutil/trace.go:171","msg":"trace[602697941] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"156.565228ms","start":"2026-10-15T07:26:17.613073Z","end":"2026-10-15T07:26:17.769638Z","steps":["trace[602697941] 'process raft request' (duration: 138.418241ms)","trace[602697941] 'compare' (duration: 17.615292ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:26:18.062364Z","caller":"traceutil/trace.go:171","msg":"trace[927616388] linearizableReadLoop","detail":"{readStateIndex:26957; appliedIndex:26956; }","duration":"117.637322ms","start":"2026-10-15T07:26:17.944684Z","end":"2026-10-15T07:26:18.062322Z","steps":["trace[927616388] 'read index received' (duration: 117.261532ms)","trace[927616388] 'applied index is now lower than readState.Index' (duration: 374.13µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:26:18.062424Z","caller":"traceutil/trace.go:171","msg":"trace[1100720477] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"127.629625ms","start":"2026-10-15T07:26:17.93473Z","end":"2026-10-15T07:26:18.06236Z","steps":["trace[1100720477] 'process raft request' (duration: 127.224854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:26:18.062642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.660147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:26:18.062774Z","caller":"traceutil/trace.go:171","msg":"trace[1657185484] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26095; }","duration":"100.829821ms","start":"2026-10-15T07:26:17.961923Z","end":"2026-10-15T07:26:18.062753Z","steps":["trace[1657185484] 'agreement among raft nodes before linearized reading' (duration: 100.624386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:26:18.062651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.94261ms","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-10-15T07:26:18.062925Z","caller":"traceutil/trace.go:171","msg":"trace[1194741006] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26095; }","duration":"118.231968ms","start":"2026-10-15T07:26:17.944665Z","end":"2026-10-15T07:26:18.062897Z","steps":["trace[1194741006] 'agreement among raft nodes before linearized reading' (duration: 117.797656ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:34.041813Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069e3.wal"} {"level":"info","ts":"2026-10-15T07:27:14.780525Z","caller":"traceutil/trace.go:171","msg":"trace[1828251059] linearizableReadLoop","detail":"{readStateIndex:27490; appliedIndex:27489; }","duration":"143.482229ms","start":"2026-10-15T07:27:14.637017Z","end":"2026-10-15T07:27:14.780499Z","steps":["trace[1828251059] 'read index received' (duration: 143.190341ms)","trace[1828251059] 'applied index is now lower than readState.Index' (duration: 290.848µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:27:14.780745Z","caller":"traceutil/trace.go:171","msg":"trace[1338459809] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"148.716212ms","start":"2026-10-15T07:27:14.631927Z","end":"2026-10-15T07:27:14.780644Z","steps":["trace[1338459809] 'process raft request' (duration: 148.299421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:27:14.780859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.694075ms","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-10-15T07:27:14.780975Z","caller":"traceutil/trace.go:171","msg":"trace[1717022542] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26617; }","duration":"143.994833ms","start":"2026-10-15T07:27:14.636955Z","end":"2026-10-15T07:27:14.78095Z","steps":["trace[1717022542] 'agreement among raft nodes before linearized reading' (duration: 143.659063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:27:20.216675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.554418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:27:20.216789Z","caller":"traceutil/trace.go:171","msg":"trace[72329342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26654; }","duration":"182.747424ms","start":"2026-10-15T07:27:20.034014Z","end":"2026-10-15T07:27:20.216762Z","steps":["trace[72329342] 'range keys from in-memory index tree' (duration: 182.448215ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:27:20.379895Z","caller":"traceutil/trace.go:171","msg":"trace[1104158356] transaction","detail":"{read_only:false; response_revision:26655; number_of_response:1; }","duration":"157.315098ms","start":"2026-10-15T07:27:20.222548Z","end":"2026-10-15T07:27:20.379863Z","steps":["trace[1104158356] 'process raft request' (duration: 157.126422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:27:20.558953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.617442ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T07:27:20.55926Z","caller":"traceutil/trace.go:171","msg":"trace[2098636664] linearizableReadLoop","detail":"{readStateIndex:27531; appliedIndex:27529; }","duration":"179.656009ms","start":"2026-10-15T07:27:20.379581Z","end":"2026-10-15T07:27:20.559237Z","steps":["trace[2098636664] 'read index received' (duration: 81.602µs)","trace[2098636664] 'applied index is now lower than readState.Index' (duration: 179.572967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:27:20.559443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.853685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:27:20.5595Z","caller":"traceutil/trace.go:171","msg":"trace[415349277] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26655; }","duration":"179.981118ms","start":"2026-10-15T07:27:20.379501Z","end":"2026-10-15T07:27:20.559482Z","steps":["trace[415349277] 'agreement among raft nodes before linearized reading' (duration: 179.854394ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:27:20.559561Z","caller":"traceutil/trace.go:171","msg":"trace[20650689] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"102.26479ms","start":"2026-10-15T07:27:20.45726Z","end":"2026-10-15T07:27:20.559525Z","steps":["trace[20650689] 'process raft request' (duration: 101.847158ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:27:20.587275Z","caller":"traceutil/trace.go:171","msg":"trace[407087042] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"102.769524ms","start":"2026-10-15T07:27:20.48448Z","end":"2026-10-15T07:27:20.58725Z","steps":["trace[407087042] 'process raft request' (duration: 89.547532ms)","trace[407087042] 'compare' (duration: 13.078028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:27:20.587458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.964241ms","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-10-15T07:27:20.587562Z","caller":"traceutil/trace.go:171","msg":"trace[234037274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26657; }","duration":"175.123055ms","start":"2026-10-15T07:27:20.412409Z","end":"2026-10-15T07:27:20.587532Z","steps":["trace[234037274] 'agreement among raft nodes before linearized reading' (duration: 174.850847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:27:20.895528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.321695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:27:20.895781Z","caller":"traceutil/trace.go:171","msg":"trace[1075831220] transaction","detail":"{read_only:false; response_revision:26659; number_of_response:1; }","duration":"303.163301ms","start":"2026-10-15T07:27:20.5926Z","end":"2026-10-15T07:27:20.895764Z","steps":["trace[1075831220] 'process raft request' (duration: 303.059268ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:27:20.895835Z","caller":"traceutil/trace.go:171","msg":"trace[1712387945] transaction","detail":"{read_only:false; response_revision:26658; number_of_response:1; }","duration":"303.284404ms","start":"2026-10-15T07:27:20.592518Z","end":"2026-10-15T07:27:20.895802Z","steps":["trace[1712387945] 'process raft request' (duration: 148.568928ms)","trace[1712387945] 'compare' (duration: 154.003677ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:27:20.896129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:27:20.592495Z","time spent":"303.443909ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:27:20.89586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:27:20.592569Z","time spent":"303.255364ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:27:21.225642Z","caller":"traceutil/trace.go:171","msg":"trace[1522686431] linearizableReadLoop","detail":"{readStateIndex:27537; appliedIndex:27536; }","duration":"214.070861ms","start":"2026-10-15T07:27:21.011542Z","end":"2026-10-15T07:27:21.225613Z","steps":["trace[1522686431] 'read index received' (duration: 123.348897ms)","trace[1522686431] 'applied index is now lower than readState.Index' (duration: 90.720604ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:27:21.225674Z","caller":"traceutil/trace.go:171","msg":"trace[1333529955] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"303.905801ms","start":"2026-10-15T07:27:20.921734Z","end":"2026-10-15T07:27:21.22564Z","steps":["trace[1333529955] 'process raft request' (duration: 213.166777ms)","trace[1333529955] 'compare' (duration: 90.567249ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:27:21.225818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.263356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:27:21.22586Z","caller":"traceutil/trace.go:171","msg":"trace[1155391031] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26661; }","duration":"214.368399ms","start":"2026-10-15T07:27:21.011478Z","end":"2026-10-15T07:27:21.225846Z","steps":["trace[1155391031] 'agreement among raft nodes before linearized reading' (duration: 214.263846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:27:21.225854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:27:20.921706Z","time spent":"304.041304ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:29:01.407383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24849} {"level":"info","ts":"2026-10-15T07:29:01.580517Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24849,"took":"168.880183ms","hash":4278897464,"current-db-size-bytes":58544128,"current-db-size":"58 MB","current-db-size-in-use-bytes":36134912,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T07:29:01.580622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4278897464,"revision":24849,"compact-revision":21633} {"level":"info","ts":"2026-10-15T07:31:24.800814Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7b14f43c75605232","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:31:24.807476Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T07:31:24.807629Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T07:33:38.363402Z","caller":"traceutil/trace.go:171","msg":"trace[1008639970] transaction","detail":"{read_only:false; response_revision:30100; number_of_response:1; }","duration":"140.9826ms","start":"2026-10-15T07:33:38.222383Z","end":"2026-10-15T07:33:38.363366Z","steps":["trace[1008639970] 'process raft request' (duration: 122.674689ms)","trace[1008639970] 'compare' (duration: 17.923451ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:34:01.423473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27628} {"level":"info","ts":"2026-10-15T07:34:01.56656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27628,"took":"137.604308ms","hash":2890055659,"current-db-size-bytes":58544128,"current-db-size":"58 MB","current-db-size-in-use-bytes":34189312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T07:34:01.566657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2890055659,"revision":27628,"compact-revision":24849} {"level":"info","ts":"2026-10-15T07:39:01.432832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30265} {"level":"info","ts":"2026-10-15T07:39:01.589195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30265,"took":"150.329053ms","hash":1733005730,"current-db-size-bytes":58544128,"current-db-size":"58 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T07:39:01.589302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1733005730,"revision":30265,"compact-revision":27628} {"level":"info","ts":"2026-10-15T07:39:34.019238Z","caller":"traceutil/trace.go:171","msg":"trace[1959768719] transaction","detail":"{read_only:false; response_revision:32752; number_of_response:1; }","duration":"111.350612ms","start":"2026-10-15T07:39:33.907849Z","end":"2026-10-15T07:39:34.019199Z","steps":["trace[1959768719] 'process raft request' (duration: 105.564904ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:35.115102Z","caller":"traceutil/trace.go:171","msg":"trace[1531073230] transaction","detail":"{read_only:false; response_revision:32758; number_of_response:1; }","duration":"192.329836ms","start":"2026-10-15T07:39:34.922737Z","end":"2026-10-15T07:39:35.115067Z","steps":["trace[1531073230] 'process raft request' (duration: 192.10145ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:35.322433Z","caller":"traceutil/trace.go:171","msg":"trace[1331708853] transaction","detail":"{read_only:false; response_revision:32763; number_of_response:1; }","duration":"119.522495ms","start":"2026-10-15T07:39:35.202875Z","end":"2026-10-15T07:39:35.322398Z","steps":["trace[1331708853] 'process raft request' (duration: 119.33664ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:36.244401Z","caller":"traceutil/trace.go:171","msg":"trace[606814001] linearizableReadLoop","detail":"{readStateIndex:33810; appliedIndex:33809; }","duration":"201.497507ms","start":"2026-10-15T07:39:36.042863Z","end":"2026-10-15T07:39:36.244361Z","steps":["trace[606814001] 'read index received' (duration: 133.056464ms)","trace[606814001] 'applied index is now lower than readState.Index' (duration: 68.439383ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:36.244467Z","caller":"traceutil/trace.go:171","msg":"trace[1269638569] transaction","detail":"{read_only:false; response_revision:32767; number_of_response:1; }","duration":"218.339876ms","start":"2026-10-15T07:39:36.026087Z","end":"2026-10-15T07:39:36.244427Z","steps":["trace[1269638569] 'process raft request' (duration: 149.913223ms)","trace[1269638569] 'compare' (duration: 67.997691ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:39:36.244622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.733013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:39:36.24467Z","caller":"traceutil/trace.go:171","msg":"trace[179779812] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:32767; }","duration":"201.879077ms","start":"2026-10-15T07:39:36.042778Z","end":"2026-10-15T07:39:36.244657Z","steps":["trace[179779812] 'agreement among raft nodes before linearized reading' (duration: 201.761444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:36.244796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.388719ms","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-10-15T07:39:36.24484Z","caller":"traceutil/trace.go:171","msg":"trace[1990895782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:32767; }","duration":"181.452661ms","start":"2026-10-15T07:39:36.063377Z","end":"2026-10-15T07:39:36.24483Z","steps":["trace[1990895782] 'agreement among raft nodes before linearized reading' (duration: 181.292286ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:36.738859Z","caller":"traceutil/trace.go:171","msg":"trace[1197192165] transaction","detail":"{read_only:false; response_revision:32769; number_of_response:1; }","duration":"400.782924ms","start":"2026-10-15T07:39:36.338039Z","end":"2026-10-15T07:39:36.738822Z","steps":["trace[1197192165] 'process raft request' (duration: 400.562958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:36.739061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:39:36.338009Z","time spent":"400.945698ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:39:36.797624Z","caller":"traceutil/trace.go:171","msg":"trace[1825223246] linearizableReadLoop","detail":"{readStateIndex:33814; appliedIndex:33811; }","duration":"181.237136ms","start":"2026-10-15T07:39:36.616359Z","end":"2026-10-15T07:39:36.797596Z","steps":["trace[1825223246] 'read index received' (duration: 122.242179ms)","trace[1825223246] 'applied index is now lower than readState.Index' (duration: 58.994227ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:36.797672Z","caller":"traceutil/trace.go:171","msg":"trace[485626379] transaction","detail":"{read_only:false; response_revision:32770; number_of_response:1; }","duration":"428.006385ms","start":"2026-10-15T07:39:36.36962Z","end":"2026-10-15T07:39:36.797626Z","steps":["trace[485626379] 'process raft request' (duration: 427.771899ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:36.797698Z","caller":"traceutil/trace.go:171","msg":"trace[1668762749] transaction","detail":"{read_only:false; response_revision:32771; number_of_response:1; }","duration":"278.711079ms","start":"2026-10-15T07:39:36.518955Z","end":"2026-10-15T07:39:36.797666Z","steps":["trace[1668762749] 'process raft request' (duration: 278.597586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:36.797866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:39:36.369588Z","time spent":"428.16994ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:39:36.798002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.612915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T07:39:36.798101Z","caller":"traceutil/trace.go:171","msg":"trace[742468939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:32771; }","duration":"181.77124ms","start":"2026-10-15T07:39:36.616302Z","end":"2026-10-15T07:39:36.798073Z","steps":["trace[742468939] 'agreement among raft nodes before linearized reading' (duration: 181.441841ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:37.007606Z","caller":"traceutil/trace.go:171","msg":"trace[2005897194] transaction","detail":"{read_only:false; response_revision:32772; number_of_response:1; }","duration":"204.774256ms","start":"2026-10-15T07:39:36.802772Z","end":"2026-10-15T07:39:37.007547Z","steps":["trace[2005897194] 'process raft request' (duration: 134.245855ms)","trace[2005897194] 'compare' (duration: 70.405017ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:37.27287Z","caller":"traceutil/trace.go:171","msg":"trace[2099119209] transaction","detail":"{read_only:false; response_revision:32774; number_of_response:1; }","duration":"164.749256ms","start":"2026-10-15T07:39:37.108087Z","end":"2026-10-15T07:39:37.272837Z","steps":["trace[2099119209] 'process raft request' (duration: 164.54053ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:37.314538Z","caller":"traceutil/trace.go:171","msg":"trace[336032542] transaction","detail":"{read_only:false; response_revision:32775; number_of_response:1; }","duration":"173.945647ms","start":"2026-10-15T07:39:37.140565Z","end":"2026-10-15T07:39:37.31451Z","steps":["trace[336032542] 'process raft request' (duration: 173.737041ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:37.314774Z","caller":"traceutil/trace.go:171","msg":"trace[2120007681] linearizableReadLoop","detail":"{readStateIndex:33819; appliedIndex:33816; }","duration":"110.653763ms","start":"2026-10-15T07:39:37.204091Z","end":"2026-10-15T07:39:37.314744Z","steps":["trace[2120007681] 'read index received' (duration: 68.538846ms)","trace[2120007681] 'applied index is now lower than readState.Index' (duration: 42.112107ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:37.314823Z","caller":"traceutil/trace.go:171","msg":"trace[417366217] transaction","detail":"{read_only:false; response_revision:32776; number_of_response:1; }","duration":"138.143452ms","start":"2026-10-15T07:39:37.176627Z","end":"2026-10-15T07:39:37.31477Z","steps":["trace[417366217] 'process raft request' (duration: 137.838354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:37.315002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.895799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T07:39:37.315098Z","caller":"traceutil/trace.go:171","msg":"trace[1074981855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32776; }","duration":"111.045064ms","start":"2026-10-15T07:39:37.204028Z","end":"2026-10-15T07:39:37.315073Z","steps":["trace[1074981855] 'agreement among raft nodes before linearized reading' (duration: 110.819378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:37.432302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.561239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:39:37.432672Z","caller":"traceutil/trace.go:171","msg":"trace[2045907265] transaction","detail":"{read_only:false; response_revision:32777; number_of_response:1; }","duration":"112.206936ms","start":"2026-10-15T07:39:37.320432Z","end":"2026-10-15T07:39:37.432639Z","steps":["trace[2045907265] 'process raft request' (duration: 11.173884ms)","trace[2045907265] 'compare' (duration: 100.23544ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:37.432958Z","caller":"traceutil/trace.go:171","msg":"trace[1963191101] transaction","detail":"{read_only:false; response_revision:32778; number_of_response:1; }","duration":"105.110443ms","start":"2026-10-15T07:39:37.327818Z","end":"2026-10-15T07:39:37.432929Z","steps":["trace[1963191101] 'process raft request' (duration: 104.595458ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:38.323001Z","caller":"traceutil/trace.go:171","msg":"trace[1682922219] transaction","detail":"{read_only:false; response_revision:32783; number_of_response:1; }","duration":"103.150909ms","start":"2026-10-15T07:39:38.219624Z","end":"2026-10-15T07:39:38.322774Z","steps":["trace[1682922219] 'process raft request' (duration: 101.064112ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:38.356714Z","caller":"traceutil/trace.go:171","msg":"trace[556868125] transaction","detail":"{read_only:false; response_revision:32784; number_of_response:1; }","duration":"106.184951ms","start":"2026-10-15T07:39:38.250481Z","end":"2026-10-15T07:39:38.356666Z","steps":["trace[556868125] 'process raft request' (duration: 105.933044ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:38.900621Z","caller":"traceutil/trace.go:171","msg":"trace[745666981] linearizableReadLoop","detail":"{readStateIndex:33829; appliedIndex:33828; }","duration":"125.635992ms","start":"2026-10-15T07:39:38.774949Z","end":"2026-10-15T07:39:38.900585Z","steps":["trace[745666981] 'read index received' (duration: 125.475717ms)","trace[745666981] 'applied index is now lower than readState.Index' (duration: 158.955µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:38.900781Z","caller":"traceutil/trace.go:171","msg":"trace[749500032] transaction","detail":"{read_only:false; response_revision:32786; number_of_response:1; }","duration":"147.863307ms","start":"2026-10-15T07:39:38.752899Z","end":"2026-10-15T07:39:38.900762Z","steps":["trace[749500032] 'process raft request' (duration: 147.443645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:38.901086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.149695ms","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-10-15T07:39:38.901263Z","caller":"traceutil/trace.go:171","msg":"trace[1619019659] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:32786; }","duration":"126.375812ms","start":"2026-10-15T07:39:38.774866Z","end":"2026-10-15T07:39:38.901242Z","steps":["trace[1619019659] 'agreement among raft nodes before linearized reading' (duration: 126.048703ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:39.050033Z","caller":"traceutil/trace.go:171","msg":"trace[1261632612] linearizableReadLoop","detail":"{readStateIndex:33831; appliedIndex:33829; }","duration":"149.277905ms","start":"2026-10-15T07:39:38.900723Z","end":"2026-10-15T07:39:39.050001Z","steps":["trace[1261632612] 'read index received' (duration: 108.746252ms)","trace[1261632612] 'applied index is now lower than readState.Index' (duration: 40.530383ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:39.050187Z","caller":"traceutil/trace.go:171","msg":"trace[1668813173] transaction","detail":"{read_only:false; response_revision:32788; number_of_response:1; }","duration":"245.135475ms","start":"2026-10-15T07:39:38.804964Z","end":"2026-10-15T07:39:39.0501Z","steps":["trace[1668813173] 'process raft request' (duration: 244.886378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:39.050268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.958697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:39:39.050263Z","caller":"traceutil/trace.go:171","msg":"trace[278711864] transaction","detail":"{read_only:false; response_revision:32787; number_of_response:1; }","duration":"247.779067ms","start":"2026-10-15T07:39:38.802437Z","end":"2026-10-15T07:39:39.050216Z","steps":["trace[278711864] 'process raft request' (duration: 207.188752ms)","trace[278711864] 'compare' (duration: 39.783543ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:39.050318Z","caller":"traceutil/trace.go:171","msg":"trace[945839849] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:32788; }","duration":"268.07692ms","start":"2026-10-15T07:39:38.782225Z","end":"2026-10-15T07:39:39.050302Z","steps":["trace[945839849] 'agreement among raft nodes before linearized reading' (duration: 267.965457ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:39.443581Z","caller":"traceutil/trace.go:171","msg":"trace[352017429] transaction","detail":"{read_only:false; response_revision:32791; number_of_response:1; }","duration":"121.076127ms","start":"2026-10-15T07:39:39.322442Z","end":"2026-10-15T07:39:39.443519Z","steps":["trace[352017429] 'process raft request' (duration: 68.786154ms)","trace[352017429] 'compare' (duration: 52.023836ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:41.184366Z","caller":"traceutil/trace.go:171","msg":"trace[472213246] linearizableReadLoop","detail":"{readStateIndex:33846; appliedIndex:33844; }","duration":"118.467306ms","start":"2026-10-15T07:39:41.065867Z","end":"2026-10-15T07:39:41.184334Z","steps":["trace[472213246] 'read index received' (duration: 86.898416ms)","trace[472213246] 'applied index is now lower than readState.Index' (duration: 31.56767ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:41.184503Z","caller":"traceutil/trace.go:171","msg":"trace[1998429966] transaction","detail":"{read_only:false; response_revision:32802; number_of_response:1; }","duration":"128.402716ms","start":"2026-10-15T07:39:41.056037Z","end":"2026-10-15T07:39:41.184439Z","steps":["trace[1998429966] 'process raft request' (duration: 128.100478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:41.184604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.696402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:39:41.184655Z","caller":"traceutil/trace.go:171","msg":"trace[30632619] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:32802; }","duration":"118.820596ms","start":"2026-10-15T07:39:41.06582Z","end":"2026-10-15T07:39:41.184641Z","steps":["trace[30632619] 'agreement among raft nodes before linearized reading' (duration: 118.698302ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:41.665264Z","caller":"traceutil/trace.go:171","msg":"trace[1413818860] linearizableReadLoop","detail":"{readStateIndex:33848; appliedIndex:33847; }","duration":"270.29141ms","start":"2026-10-15T07:39:41.394948Z","end":"2026-10-15T07:39:41.665239Z","steps":["trace[1413818860] 'read index received' (duration: 270.108565ms)","trace[1413818860] 'applied index is now lower than readState.Index' (duration: 181.665µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:39:41.665446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.481936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:39:41.665394Z","caller":"traceutil/trace.go:171","msg":"trace[707717096] transaction","detail":"{read_only:false; response_revision:32804; number_of_response:1; }","duration":"301.238283ms","start":"2026-10-15T07:39:41.364097Z","end":"2026-10-15T07:39:41.665335Z","steps":["trace[707717096] 'process raft request' (duration: 300.950655ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:41.665478Z","caller":"traceutil/trace.go:171","msg":"trace[1157912122] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:32804; }","duration":"270.606479ms","start":"2026-10-15T07:39:41.394857Z","end":"2026-10-15T07:39:41.665464Z","steps":["trace[1157912122] 'agreement among raft nodes before linearized reading' (duration: 270.519597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:41.665645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:39:41.364057Z","time spent":"301.45436ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:39:41.776482Z","caller":"traceutil/trace.go:171","msg":"trace[1307182757] transaction","detail":"{read_only:false; response_revision:32806; number_of_response:1; }","duration":"307.768861ms","start":"2026-10-15T07:39:41.468678Z","end":"2026-10-15T07:39:41.776447Z","steps":["trace[1307182757] 'process raft request' (duration: 307.395791ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:41.776547Z","caller":"traceutil/trace.go:171","msg":"trace[1575421787] transaction","detail":"{read_only:false; response_revision:32805; number_of_response:1; }","duration":"325.999147ms","start":"2026-10-15T07:39:41.450509Z","end":"2026-10-15T07:39:41.776508Z","steps":["trace[1575421787] 'process raft request' (duration: 307.168854ms)","trace[1575421787] 'compare' (duration: 18.218716ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:39:41.776647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.234641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-15T07:39:41.776665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:39:41.468647Z","time spent":"307.916086ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:39:41.776712Z","caller":"traceutil/trace.go:171","msg":"trace[1736564691] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:32806; }","duration":"291.353684ms","start":"2026-10-15T07:39:41.485342Z","end":"2026-10-15T07:39:41.776696Z","steps":["trace[1736564691] 'agreement among raft nodes before linearized reading' (duration: 291.115618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:41.776735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:39:41.450463Z","time spent":"326.163022ms","remote":"127.0.0.1:57910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:39:41.776808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.305933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T07:39:41.776228Z","caller":"traceutil/trace.go:171","msg":"trace[1389843660] linearizableReadLoop","detail":"{readStateIndex:33850; appliedIndex:33848; }","duration":"110.856639ms","start":"2026-10-15T07:39:41.665343Z","end":"2026-10-15T07:39:41.7762Z","steps":["trace[1389843660] 'read index received' (duration: 92.230972ms)","trace[1389843660] 'applied index is now lower than readState.Index' (duration: 18.624357ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:39:41.776803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.824855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:39:41.776901Z","caller":"traceutil/trace.go:171","msg":"trace[869883785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32806; }","duration":"186.463258ms","start":"2026-10-15T07:39:41.59041Z","end":"2026-10-15T07:39:41.776873Z","steps":["trace[869883785] 'agreement among raft nodes before linearized reading' (duration: 186.027956ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:41.776976Z","caller":"traceutil/trace.go:171","msg":"trace[994957227] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:32806; }","duration":"325.012971ms","start":"2026-10-15T07:39:41.451893Z","end":"2026-10-15T07:39:41.776906Z","steps":["trace[994957227] 'agreement among raft nodes before linearized reading' (duration: 324.666741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:41.777031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.853141ms","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-10-15T07:39:41.77707Z","caller":"traceutil/trace.go:171","msg":"trace[1473778111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32806; }","duration":"109.922543ms","start":"2026-10-15T07:39:41.667138Z","end":"2026-10-15T07:39:41.77706Z","steps":["trace[1473778111] 'agreement among raft nodes before linearized reading' (duration: 109.82058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:41.77709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:39:41.451865Z","time spent":"325.200626ms","remote":"127.0.0.1:58170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":41,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-10-15T07:39:42.639734Z","caller":"traceutil/trace.go:171","msg":"trace[1881210207] transaction","detail":"{read_only:false; response_revision:32812; number_of_response:1; }","duration":"150.57994ms","start":"2026-10-15T07:39:42.489103Z","end":"2026-10-15T07:39:42.639683Z","steps":["trace[1881210207] 'process raft request' (duration: 69.890073ms)","trace[1881210207] 'compare' (duration: 80.504932ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:43.586782Z","caller":"traceutil/trace.go:171","msg":"trace[369654275] transaction","detail":"{read_only:false; response_revision:32817; number_of_response:1; }","duration":"257.186683ms","start":"2026-10-15T07:39:43.329563Z","end":"2026-10-15T07:39:43.58675Z","steps":["trace[369654275] 'process raft request' (duration: 256.975247ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:44.074404Z","caller":"traceutil/trace.go:171","msg":"trace[430518101] transaction","detail":"{read_only:false; response_revision:32822; number_of_response:1; }","duration":"189.178492ms","start":"2026-10-15T07:39:43.885196Z","end":"2026-10-15T07:39:44.074375Z","steps":["trace[430518101] 'process raft request' (duration: 155.992988ms)","trace[430518101] 'compare' (duration: 32.972668ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:44.074591Z","caller":"traceutil/trace.go:171","msg":"trace[435109713] linearizableReadLoop","detail":"{readStateIndex:33867; appliedIndex:33865; }","duration":"150.647582ms","start":"2026-10-15T07:39:43.923906Z","end":"2026-10-15T07:39:44.074554Z","steps":["trace[435109713] 'read index received' (duration: 117.299984ms)","trace[435109713] 'applied index is now lower than readState.Index' (duration: 33.344738ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:44.074686Z","caller":"traceutil/trace.go:171","msg":"trace[548261956] transaction","detail":"{read_only:false; response_revision:32823; number_of_response:1; }","duration":"188.841382ms","start":"2026-10-15T07:39:43.885795Z","end":"2026-10-15T07:39:44.074637Z","steps":["trace[548261956] 'process raft request' (duration: 188.530384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:39:44.07479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.845778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:39:44.074842Z","caller":"traceutil/trace.go:171","msg":"trace[1125837430] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:32823; }","duration":"150.96319ms","start":"2026-10-15T07:39:43.923869Z","end":"2026-10-15T07:39:44.074832Z","steps":["trace[1125837430] 'agreement among raft nodes before linearized reading' (duration: 150.816636ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:45.312796Z","caller":"traceutil/trace.go:171","msg":"trace[913634570] linearizableReadLoop","detail":"{readStateIndex:33875; appliedIndex:33873; }","duration":"102.564723ms","start":"2026-10-15T07:39:45.210194Z","end":"2026-10-15T07:39:45.312759Z","steps":["trace[913634570] 'read index received' (duration: 21.437704ms)","trace[913634570] 'applied index is now lower than readState.Index' (duration: 81.125349ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:39:45.312844Z","caller":"traceutil/trace.go:171","msg":"trace[1268325609] transaction","detail":"{read_only:false; response_revision:32830; number_of_response:1; }","duration":"110.126759ms","start":"2026-10-15T07:39:45.202662Z","end":"2026-10-15T07:39:45.312789Z","steps":["trace[1268325609] 'process raft request' (duration: 84.167882ms)","trace[1268325609] 'compare' (duration: 25.304159ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:39:45.313125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.82622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:39:45.313188Z","caller":"traceutil/trace.go:171","msg":"trace[1841004238] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:32831; }","duration":"103.010575ms","start":"2026-10-15T07:39:45.21016Z","end":"2026-10-15T07:39:45.313171Z","steps":["trace[1841004238] 'agreement among raft nodes before linearized reading' (duration: 102.792749ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:46.239576Z","caller":"traceutil/trace.go:171","msg":"trace[1424556956] transaction","detail":"{read_only:false; response_revision:32839; number_of_response:1; }","duration":"155.009571ms","start":"2026-10-15T07:39:46.084534Z","end":"2026-10-15T07:39:46.239543Z","steps":["trace[1424556956] 'process raft request' (duration: 154.810126ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:39:46.247928Z","caller":"traceutil/trace.go:171","msg":"trace[635383561] transaction","detail":"{read_only:false; response_revision:32840; number_of_response:1; }","duration":"130.969477ms","start":"2026-10-15T07:39:46.116927Z","end":"2026-10-15T07:39:46.247896Z","steps":["trace[635383561] 'process raft request' (duration: 130.741281ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:44:01.440955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32516} {"level":"info","ts":"2026-10-15T07:44:01.609775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32516,"took":"162.874742ms","hash":2177768974,"current-db-size-bytes":58544128,"current-db-size":"58 MB","current-db-size-in-use-bytes":30060544,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T07:44:01.609869Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2177768974,"revision":32516,"compact-revision":30265} {"level":"info","ts":"2026-10-15T07:49:01.452079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34733} {"level":"info","ts":"2026-10-15T07:49:01.588432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34733,"took":"129.849322ms","hash":1790810324,"current-db-size-bytes":58544128,"current-db-size":"58 MB","current-db-size-in-use-bytes":31055872,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T07:49:01.58851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1790810324,"revision":34733,"compact-revision":32516} {"level":"info","ts":"2026-10-15T07:52:52.0905Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7b14f43c75605232","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:52:52.096056Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T07:52:52.096183Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T07:54:01.4609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37027} {"level":"info","ts":"2026-10-15T07:54:01.598094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37027,"took":"131.178966ms","hash":2627057036,"current-db-size-bytes":58544128,"current-db-size":"58 MB","current-db-size-in-use-bytes":32464896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T07:54:01.598164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2627057036,"revision":37027,"compact-revision":34733} {"level":"info","ts":"2026-10-15T07:59:01.469988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39302} {"level":"info","ts":"2026-10-15T07:59:01.600716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39302,"took":"127.000242ms","hash":620288190,"current-db-size-bytes":59392000,"current-db-size":"59 MB","current-db-size-in-use-bytes":31944704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T07:59:01.600785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":620288190,"revision":39302,"compact-revision":37027}