{"level":"warn","ts":"2026-06-04T19:21:28.620179Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T19:21:28.620312Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.74: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.74:2380","--initial-cluster=instance=https://199.19.213.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.74: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-06-04T19:21:28.620413Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T19:21:28.620433Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-06-04T19:21:28.620459Z","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-06-04T19:21:28.621452Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"]} {"level":"info","ts":"2026-06-04T19:21:28.62156Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.74: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-06-04T19:21:28.633756Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.902745ms"} {"level":"info","ts":"2026-06-04T19:21:28.643895Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d4171f3ea72ed207","cluster-id":"d40b55e346dc63dc"} {"level":"info","ts":"2026-06-04T19:21:28.64399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=()"} {"level":"info","ts":"2026-06-04T19:21:28.644022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 0"} {"level":"info","ts":"2026-06-04T19:21:28.64406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d4171f3ea72ed207 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-04T19:21:28.644068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 1"} {"level":"info","ts":"2026-06-04T19:21:28.644121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"warn","ts":"2026-06-04T19:21:28.652821Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-04T19:21:28.654454Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-04T19:21:28.655797Z","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-06-04T19:21:28.659029Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d4171f3ea72ed207","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-04T19:21:28.659612Z","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-06-04T19:21:28.659743Z","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-06-04T19:21:28.65977Z","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-06-04T19:21:28.659253Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d4171f3ea72ed207","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-04T19:21:28.666649Z","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-06-04T19:21:28.666945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"info","ts":"2026-06-04T19:21:28.667077Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-06-04T19:21:28.667317Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-06-04T19:21:28.66733Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","added-peer-id":"d4171f3ea72ed207","added-peer-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-06-04T19:21:28.667591Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d4171f3ea72ed207","initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-04T19:21:28.668949Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-04T19:21:29.145359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 is starting a new election at term 1"} {"level":"info","ts":"2026-06-04T19:21:29.145421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-04T19:21:29.145441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgPreVoteResp from d4171f3ea72ed207 at term 1"} {"level":"info","ts":"2026-06-04T19:21:29.145455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became candidate at term 2"} {"level":"info","ts":"2026-06-04T19:21:29.145463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgVoteResp from d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-06-04T19:21:29.145473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became leader at term 2"} {"level":"info","ts":"2026-06-04T19:21:29.145482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d4171f3ea72ed207 elected leader d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-06-04T19:21:29.194916Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T19:21:29.196808Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d4171f3ea72ed207","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.74:2379]}","request-path":"/0/members/d4171f3ea72ed207/attributes","cluster-id":"d40b55e346dc63dc","publish-timeout":"7s"} {"level":"info","ts":"2026-06-04T19:21:29.196839Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T19:21:29.196917Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T19:21:29.197338Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-04T19:21:29.197361Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-04T19:21:29.197601Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T19:21:29.197845Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T19:21:29.197934Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T19:21:29.200069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-04T19:21:29.204253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.74:2379"} {"level":"warn","ts":"2026-06-04T19:21:53.416924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.083749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T19:21:53.417011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.675443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-04T19:21:53.417142Z","caller":"traceutil/trace.go:171","msg":"trace[100380304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:395; }","duration":"246.837004ms","start":"2026-06-04T19:21:53.170277Z","end":"2026-06-04T19:21:53.417114Z","steps":["trace[100380304] 'range keys from in-memory index tree' (duration: 246.575811ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:21:53.417041Z","caller":"traceutil/trace.go:171","msg":"trace[1933300324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"276.304701ms","start":"2026-06-04T19:21:53.140716Z","end":"2026-06-04T19:21:53.41702Z","steps":["trace[1933300324] 'range keys from in-memory index tree' (duration: 275.991678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:21:54.32498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.41937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:21:54.325086Z","caller":"traceutil/trace.go:171","msg":"trace[1657080831] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"775.840538ms","start":"2026-06-04T19:21:53.549229Z","end":"2026-06-04T19:21:54.32507Z","steps":["trace[1657080831] 'read index received' (duration: 62.431µs)","trace[1657080831] 'applied index is now lower than readState.Index' (duration: 775.776877ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:21:54.325167Z","caller":"traceutil/trace.go:171","msg":"trace[1664307151] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"903.313787ms","start":"2026-06-04T19:21:53.421805Z","end":"2026-06-04T19:21:54.325119Z","steps":["trace[1664307151] 'process raft request' (duration: 110.148379ms)","trace[1664307151] 'compare' (duration: 792.303319ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:21:54.325229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.007089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-06-04T19:21:54.325262Z","caller":"traceutil/trace.go:171","msg":"trace[359923178] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:397; }","duration":"776.084601ms","start":"2026-06-04T19:21:53.549169Z","end":"2026-06-04T19:21:54.325254Z","steps":["trace[359923178] 'agreement among raft nodes before linearized reading' (duration: 776.01913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:21:54.325302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:21:53.549137Z","time spent":"776.158241ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-04T19:21:54.325362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:21:53.421781Z","time spent":"903.474689ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:21:54.325451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.317869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:21:54.325673Z","caller":"traceutil/trace.go:171","msg":"trace[350375101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"183.501931ms","start":"2026-06-04T19:21:54.142101Z","end":"2026-06-04T19:21:54.325603Z","steps":["trace[350375101] 'agreement among raft nodes before linearized reading' (duration: 183.269399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:21:55.044079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.476471ms","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-06-04T19:21:55.044159Z","caller":"traceutil/trace.go:171","msg":"trace[714310792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"218.632242ms","start":"2026-06-04T19:21:54.82551Z","end":"2026-06-04T19:21:55.044142Z","steps":["trace[714310792] 'range keys from in-memory index tree' (duration: 218.314129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:21:55.044217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.703142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T19:21:55.044303Z","caller":"traceutil/trace.go:171","msg":"trace[950690872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"188.829853ms","start":"2026-06-04T19:21:54.855453Z","end":"2026-06-04T19:21:55.044283Z","steps":["trace[950690872] 'range keys from in-memory index tree' (duration: 188.622892ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:03.194968Z","caller":"traceutil/trace.go:171","msg":"trace[1922760063] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"100.190534ms","start":"2026-06-04T19:22:03.094757Z","end":"2026-06-04T19:22:03.194948Z","steps":["trace[1922760063] 'process raft request' (duration: 99.905631ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.071256Z","caller":"traceutil/trace.go:171","msg":"trace[1158565573] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"304.338183ms","start":"2026-06-04T19:22:09.766886Z","end":"2026-06-04T19:22:10.071224Z","steps":["trace[1158565573] 'process raft request' (duration: 304.179551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:10.071434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:09.766868Z","time spent":"304.488324ms","remote":"127.0.0.1:40522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-04T19:22:10.080161Z","caller":"traceutil/trace.go:171","msg":"trace[1387791518] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"287.756563ms","start":"2026-06-04T19:22:09.792381Z","end":"2026-06-04T19:22:10.080137Z","steps":["trace[1387791518] 'process raft request' (duration: 287.534351ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.080538Z","caller":"traceutil/trace.go:171","msg":"trace[101329975] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"303.16523ms","start":"2026-06-04T19:22:09.777285Z","end":"2026-06-04T19:22:10.08045Z","steps":["trace[101329975] 'process raft request' (duration: 300.644916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:10.080651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:09.777258Z","time spent":"303.301262ms","remote":"127.0.0.1:40522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-04T19:22:10.080776Z","caller":"traceutil/trace.go:171","msg":"trace[1945203475] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"303.391083ms","start":"2026-06-04T19:22:09.777366Z","end":"2026-06-04T19:22:10.080757Z","steps":["trace[1945203475] 'process raft request' (duration: 300.647987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:10.080832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:09.777359Z","time spent":"303.454654ms","remote":"127.0.0.1:40522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-04T19:22:10.081055Z","caller":"traceutil/trace.go:171","msg":"trace[1339237344] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"291.669931ms","start":"2026-06-04T19:22:09.789291Z","end":"2026-06-04T19:22:10.080961Z","steps":["trace[1339237344] 'process raft request' (duration: 288.767024ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.081217Z","caller":"traceutil/trace.go:171","msg":"trace[1571621890] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"291.846762ms","start":"2026-06-04T19:22:09.789339Z","end":"2026-06-04T19:22:10.081185Z","steps":["trace[1571621890] 'process raft request' (duration: 288.802423ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.081398Z","caller":"traceutil/trace.go:171","msg":"trace[1214447157] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"290.317548ms","start":"2026-06-04T19:22:09.791072Z","end":"2026-06-04T19:22:10.08139Z","steps":["trace[1214447157] 'process raft request' (duration: 287.136178ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.081621Z","caller":"traceutil/trace.go:171","msg":"trace[1620386725] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"289.953314ms","start":"2026-06-04T19:22:09.791639Z","end":"2026-06-04T19:22:10.081592Z","steps":["trace[1620386725] 'process raft request' (duration: 286.599382ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.088733Z","caller":"traceutil/trace.go:171","msg":"trace[1649225934] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"282.848446ms","start":"2026-06-04T19:22:09.805629Z","end":"2026-06-04T19:22:10.088478Z","steps":["trace[1649225934] 'process raft request' (duration: 280.656806ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.090317Z","caller":"traceutil/trace.go:171","msg":"trace[160184912] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"259.804756ms","start":"2026-06-04T19:22:09.829481Z","end":"2026-06-04T19:22:10.089285Z","steps":["trace[160184912] 'process raft request' (duration: 257.334933ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.093344Z","caller":"traceutil/trace.go:171","msg":"trace[1150694564] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"263.762344ms","start":"2026-06-04T19:22:09.829561Z","end":"2026-06-04T19:22:10.093323Z","steps":["trace[1150694564] 'process raft request' (duration: 258.906187ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:10.093471Z","caller":"traceutil/trace.go:171","msg":"trace[2062892859] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"138.889511ms","start":"2026-06-04T19:22:09.954572Z","end":"2026-06-04T19:22:10.093462Z","steps":["trace[2062892859] 'process raft request' (duration: 138.474117ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:16.936586Z","caller":"traceutil/trace.go:171","msg":"trace[2109048007] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"255.194183ms","start":"2026-06-04T19:22:16.681362Z","end":"2026-06-04T19:22:16.936556Z","steps":["trace[2109048007] 'read index received' (duration: 254.221495ms)","trace[2109048007] 'applied index is now lower than readState.Index' (duration: 971.728µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:16.936719Z","caller":"traceutil/trace.go:171","msg":"trace[1190374018] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"419.064883ms","start":"2026-06-04T19:22:16.517612Z","end":"2026-06-04T19:22:16.936676Z","steps":["trace[1190374018] 'process raft request' (duration: 418.231222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:16.936869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.488418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bs8fv\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-04T19:22:16.936932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:16.517587Z","time spent":"419.226551ms","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:22:16.937053Z","caller":"traceutil/trace.go:171","msg":"trace[1631246297] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bs8fv; range_end:; response_count:1; response_revision:605; }","duration":"255.661266ms","start":"2026-06-04T19:22:16.68131Z","end":"2026-06-04T19:22:16.936971Z","steps":["trace[1631246297] 'agreement among raft nodes before linearized reading' (duration: 255.354241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:18.51952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.655024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-06-04T19:22:18.519597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.305996ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:18.519721Z","caller":"traceutil/trace.go:171","msg":"trace[1332274255] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"575.874993ms","start":"2026-06-04T19:22:17.943824Z","end":"2026-06-04T19:22:18.519699Z","steps":["trace[1332274255] 'range keys from in-memory index tree' (duration: 575.288665ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:18.519885Z","caller":"traceutil/trace.go:171","msg":"trace[182076962] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"1.433150704s","start":"2026-06-04T19:22:17.08672Z","end":"2026-06-04T19:22:18.519871Z","steps":["trace[182076962] 'read index received' (duration: 456.06201ms)","trace[182076962] 'applied index is now lower than readState.Index' (duration: 977.087424ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:18.519884Z","caller":"traceutil/trace.go:171","msg":"trace[637217726] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.524605615s","start":"2026-06-04T19:22:16.995241Z","end":"2026-06-04T19:22:18.519846Z","steps":["trace[637217726] 'process raft request' (duration: 1.524471609s)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:18.519925Z","caller":"traceutil/trace.go:171","msg":"trace[922422435] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.553317645s","start":"2026-06-04T19:22:16.966568Z","end":"2026-06-04T19:22:18.519885Z","steps":["trace[922422435] 'process raft request' (duration: 576.193789ms)","trace[922422435] 'compare' (duration: 976.392091ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:22:18.52003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:16.966549Z","time spent":"1.553426251s","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:22:18.520026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:16.99521Z","time spent":"1.524751793s","remote":"127.0.0.1:40670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-04T19:22:18.569133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.291085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:18.569226Z","caller":"traceutil/trace.go:171","msg":"trace[1220077688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"427.449633ms","start":"2026-06-04T19:22:18.141754Z","end":"2026-06-04T19:22:18.569204Z","steps":["trace[1220077688] 'agreement among raft nodes before linearized reading' (duration: 427.279034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:18.569274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:18.14173Z","time spent":"427.534547ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T19:22:18.569221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.558067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-04T19:22:18.569483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24919503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T19:22:18.569546Z","caller":"traceutil/trace.go:171","msg":"trace[1530196886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.249264903s","start":"2026-06-04T19:22:17.320265Z","end":"2026-06-04T19:22:18.56953Z","steps":["trace[1530196886] 'agreement among raft nodes before linearized reading' (duration: 1.249088105s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:18.569574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:17.320216Z","time spent":"1.249351778s","remote":"127.0.0.1:40696","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-06-04T19:22:18.569658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482940319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:18.56971Z","caller":"traceutil/trace.go:171","msg":"trace[432648890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"1.483028383s","start":"2026-06-04T19:22:17.086665Z","end":"2026-06-04T19:22:18.569693Z","steps":["trace[432648890] 'agreement among raft nodes before linearized reading' (duration: 1.433298672s)","trace[432648890] 'range keys from in-memory index tree' (duration: 49.015717ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:22:18.569762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:17.086644Z","time spent":"1.483110617s","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T19:22:18.569508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291475586s","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-06-04T19:22:18.569882Z","caller":"traceutil/trace.go:171","msg":"trace[653725081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.291892346s","start":"2026-06-04T19:22:17.277966Z","end":"2026-06-04T19:22:18.569858Z","steps":["trace[653725081] 'agreement among raft nodes before linearized reading' (duration: 1.291363981s)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:18.569988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:17.277949Z","time spent":"1.292021913s","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-04T19:22:18.570186Z","caller":"traceutil/trace.go:171","msg":"trace[855853769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"927.732765ms","start":"2026-06-04T19:22:17.641583Z","end":"2026-06-04T19:22:18.569316Z","steps":["trace[855853769] 'agreement among raft nodes before linearized reading' (duration: 927.483873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:18.570277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:17.641557Z","time spent":"928.701232ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-04T19:22:20.055442Z","caller":"traceutil/trace.go:171","msg":"trace[1137825967] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"230.963877ms","start":"2026-06-04T19:22:19.824453Z","end":"2026-06-04T19:22:20.055417Z","steps":["trace[1137825967] 'process raft request' (duration: 214.558002ms)","trace[1137825967] 'compare' (duration: 16.31112ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:20.061467Z","caller":"traceutil/trace.go:171","msg":"trace[1263593330] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"233.689423ms","start":"2026-06-04T19:22:19.827757Z","end":"2026-06-04T19:22:20.061446Z","steps":["trace[1263593330] 'process raft request' (duration: 233.321736ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:28.754425Z","caller":"traceutil/trace.go:171","msg":"trace[746301292] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"139.896614ms","start":"2026-06-04T19:22:28.614501Z","end":"2026-06-04T19:22:28.754398Z","steps":["trace[746301292] 'read index received' (duration: 138.743795ms)","trace[746301292] 'applied index is now lower than readState.Index' (duration: 1.151599ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:28.754506Z","caller":"traceutil/trace.go:171","msg":"trace[523549162] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"204.26721ms","start":"2026-06-04T19:22:28.550217Z","end":"2026-06-04T19:22:28.754484Z","steps":["trace[523549162] 'process raft request' (duration: 202.958764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:28.754649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.125474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-04T19:22:28.754723Z","caller":"traceutil/trace.go:171","msg":"trace[1538394446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:732; }","duration":"140.229069ms","start":"2026-06-04T19:22:28.614475Z","end":"2026-06-04T19:22:28.754704Z","steps":["trace[1538394446] 'agreement among raft nodes before linearized reading' (duration: 140.02699ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:28.76579Z","caller":"traceutil/trace.go:171","msg":"trace[1774913988] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"146.277409ms","start":"2026-06-04T19:22:28.61949Z","end":"2026-06-04T19:22:28.765767Z","steps":["trace[1774913988] 'process raft request' (duration: 146.168014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:28.766123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.087972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-04T19:22:28.766131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.186665ms","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-06-04T19:22:28.766156Z","caller":"traceutil/trace.go:171","msg":"trace[529650576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"143.141484ms","start":"2026-06-04T19:22:28.623007Z","end":"2026-06-04T19:22:28.766148Z","steps":["trace[529650576] 'agreement among raft nodes before linearized reading' (duration: 143.015979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:28.766119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.548098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T19:22:28.766245Z","caller":"traceutil/trace.go:171","msg":"trace[1666754387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"142.688805ms","start":"2026-06-04T19:22:28.623538Z","end":"2026-06-04T19:22:28.766227Z","steps":["trace[1666754387] 'agreement among raft nodes before linearized reading' (duration: 142.454805ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:28.766188Z","caller":"traceutil/trace.go:171","msg":"trace[799596735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"143.272879ms","start":"2026-06-04T19:22:28.622901Z","end":"2026-06-04T19:22:28.766174Z","steps":["trace[799596735] 'agreement among raft nodes before linearized reading' (duration: 143.082441ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:28.916046Z","caller":"traceutil/trace.go:171","msg":"trace[23330205] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"157.091245ms","start":"2026-06-04T19:22:28.758928Z","end":"2026-06-04T19:22:28.916019Z","steps":["trace[23330205] 'process raft request' (duration: 141.266013ms)","trace[23330205] 'compare' (duration: 15.610833ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:28.91818Z","caller":"traceutil/trace.go:171","msg":"trace[1678523741] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"147.325944ms","start":"2026-06-04T19:22:28.770822Z","end":"2026-06-04T19:22:28.918148Z","steps":["trace[1678523741] 'process raft request' (duration: 147.246881ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:28.918211Z","caller":"traceutil/trace.go:171","msg":"trace[1130057647] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"147.70871ms","start":"2026-06-04T19:22:28.770471Z","end":"2026-06-04T19:22:28.91818Z","steps":["trace[1130057647] 'process raft request' (duration: 147.481311ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:28.918325Z","caller":"traceutil/trace.go:171","msg":"trace[1189180600] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:766; }","duration":"144.367747ms","start":"2026-06-04T19:22:28.773937Z","end":"2026-06-04T19:22:28.918305Z","steps":["trace[1189180600] 'read index received' (duration: 126.277887ms)","trace[1189180600] 'applied index is now lower than readState.Index' (duration: 18.08845ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:28.918489Z","caller":"traceutil/trace.go:171","msg":"trace[1860913016] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"146.927487ms","start":"2026-06-04T19:22:28.771553Z","end":"2026-06-04T19:22:28.918481Z","steps":["trace[1860913016] 'process raft request' (duration: 146.556771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:28.918865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.565045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4542"} {"level":"info","ts":"2026-06-04T19:22:28.91893Z","caller":"traceutil/trace.go:171","msg":"trace[1344222808] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:737; }","duration":"144.999064ms","start":"2026-06-04T19:22:28.773913Z","end":"2026-06-04T19:22:28.918912Z","steps":["trace[1344222808] 'agreement among raft nodes before linearized reading' (duration: 144.467951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:34.28874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.298992ms","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":"warn","ts":"2026-06-04T19:22:34.288872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.412443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T19:22:34.288959Z","caller":"traceutil/trace.go:171","msg":"trace[390125510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"430.524157ms","start":"2026-06-04T19:22:33.858416Z","end":"2026-06-04T19:22:34.28894Z","steps":["trace[390125510] 'range keys from in-memory index tree' (duration: 430.062418ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:34.288976Z","caller":"traceutil/trace.go:171","msg":"trace[803363263] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:769; }","duration":"351.617585ms","start":"2026-06-04T19:22:33.937327Z","end":"2026-06-04T19:22:34.288944Z","steps":["trace[803363263] 'count revisions from in-memory index tree' (duration: 351.072513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:34.289005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:33.858394Z","time spent":"430.60197ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-04T19:22:34.288765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.738912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rq7wz\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-06-04T19:22:34.289061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:33.937307Z","time spent":"351.737519ms","remote":"127.0.0.1:40792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-04T19:22:34.289122Z","caller":"traceutil/trace.go:171","msg":"trace[1735452486] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rq7wz; range_end:; response_count:1; response_revision:769; }","duration":"498.131548ms","start":"2026-06-04T19:22:33.790927Z","end":"2026-06-04T19:22:34.289059Z","steps":["trace[1735452486] 'range keys from in-memory index tree' (duration: 497.488823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:34.289222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:22:33.790895Z","time spent":"498.297155ms","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rq7wz\" "} {"level":"info","ts":"2026-06-04T19:22:38.493266Z","caller":"traceutil/trace.go:171","msg":"trace[1653439182] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"256.864757ms","start":"2026-06-04T19:22:38.236356Z","end":"2026-06-04T19:22:38.493221Z","steps":["trace[1653439182] 'read index received' (duration: 256.583176ms)","trace[1653439182] 'applied index is now lower than readState.Index' (duration: 280.081µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:38.493319Z","caller":"traceutil/trace.go:171","msg":"trace[474445885] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"261.805248ms","start":"2026-06-04T19:22:38.231461Z","end":"2026-06-04T19:22:38.493266Z","steps":["trace[474445885] 'process raft request' (duration: 261.545268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.493711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.318194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T19:22:38.493781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.948857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.493871Z","caller":"traceutil/trace.go:171","msg":"trace[1756480257] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:798; }","duration":"134.067841ms","start":"2026-06-04T19:22:38.359783Z","end":"2026-06-04T19:22:38.493851Z","steps":["trace[1756480257] 'agreement among raft nodes before linearized reading' (duration: 133.907495ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:38.493771Z","caller":"traceutil/trace.go:171","msg":"trace[1067068514] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:798; }","duration":"257.425888ms","start":"2026-06-04T19:22:38.236331Z","end":"2026-06-04T19:22:38.493757Z","steps":["trace[1067068514] 'agreement among raft nodes before linearized reading' (duration: 257.173278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.49385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.400647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T19:22:38.49395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.171066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.493989Z","caller":"traceutil/trace.go:171","msg":"trace[1841460003] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:798; }","duration":"134.220077ms","start":"2026-06-04T19:22:38.359754Z","end":"2026-06-04T19:22:38.493974Z","steps":["trace[1841460003] 'agreement among raft nodes before linearized reading' (duration: 134.162835ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:38.494025Z","caller":"traceutil/trace.go:171","msg":"trace[423783477] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:798; }","duration":"257.631906ms","start":"2026-06-04T19:22:38.236337Z","end":"2026-06-04T19:22:38.493969Z","steps":["trace[423783477] 'agreement among raft nodes before linearized reading' (duration: 257.343335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.494135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.720156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.494176Z","caller":"traceutil/trace.go:171","msg":"trace[1304682912] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:798; }","duration":"150.788708ms","start":"2026-06-04T19:22:38.343377Z","end":"2026-06-04T19:22:38.494165Z","steps":["trace[1304682912] 'agreement among raft nodes before linearized reading' (duration: 150.726776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.493882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.213602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T19:22:38.49436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.393407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.494329Z","caller":"traceutil/trace.go:171","msg":"trace[1835508678] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:798; }","duration":"169.68341ms","start":"2026-06-04T19:22:38.324605Z","end":"2026-06-04T19:22:38.494289Z","steps":["trace[1835508678] 'agreement among raft nodes before linearized reading' (duration: 169.174101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.494419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.784337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.49444Z","caller":"traceutil/trace.go:171","msg":"trace[1117142015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:798; }","duration":"170.481402ms","start":"2026-06-04T19:22:38.323922Z","end":"2026-06-04T19:22:38.494403Z","steps":["trace[1117142015] 'agreement among raft nodes before linearized reading' (duration: 170.346766ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:38.494463Z","caller":"traceutil/trace.go:171","msg":"trace[521482573] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:798; }","duration":"151.84782ms","start":"2026-06-04T19:22:38.342604Z","end":"2026-06-04T19:22:38.494452Z","steps":["trace[521482573] 'agreement among raft nodes before linearized reading' (duration: 151.791277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.494647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.694569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T19:22:38.494695Z","caller":"traceutil/trace.go:171","msg":"trace[1248142070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"116.764251ms","start":"2026-06-04T19:22:38.377919Z","end":"2026-06-04T19:22:38.494683Z","steps":["trace[1248142070] 'agreement among raft nodes before linearized reading' (duration: 116.608585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.494726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.164605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.494841Z","caller":"traceutil/trace.go:171","msg":"trace[588595552] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:798; }","duration":"134.28148ms","start":"2026-06-04T19:22:38.36052Z","end":"2026-06-04T19:22:38.494802Z","steps":["trace[588595552] 'agreement among raft nodes before linearized reading' (duration: 134.130334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.494849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.98004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.494928Z","caller":"traceutil/trace.go:171","msg":"trace[629685104] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:798; }","duration":"171.079664ms","start":"2026-06-04T19:22:38.323832Z","end":"2026-06-04T19:22:38.494912Z","steps":["trace[629685104] 'agreement among raft nodes before linearized reading' (duration: 170.97592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.494374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.697304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.495287Z","caller":"traceutil/trace.go:171","msg":"trace[779091629] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:798; }","duration":"152.64128ms","start":"2026-06-04T19:22:38.342623Z","end":"2026-06-04T19:22:38.495264Z","steps":["trace[779091629] 'agreement among raft nodes before linearized reading' (duration: 151.719784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.495118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.214826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.495507Z","caller":"traceutil/trace.go:171","msg":"trace[274705705] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:798; }","duration":"229.621562ms","start":"2026-06-04T19:22:38.265869Z","end":"2026-06-04T19:22:38.495491Z","steps":["trace[274705705] 'agreement among raft nodes before linearized reading' (duration: 229.202576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.495172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.04828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.49568Z","caller":"traceutil/trace.go:171","msg":"trace[640299861] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:798; }","duration":"228.549829ms","start":"2026-06-04T19:22:38.267114Z","end":"2026-06-04T19:22:38.495664Z","steps":["trace[640299861] 'agreement among raft nodes before linearized reading' (duration: 228.033879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.495211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.29203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.495846Z","caller":"traceutil/trace.go:171","msg":"trace[1783444192] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:798; }","duration":"228.955996ms","start":"2026-06-04T19:22:38.266867Z","end":"2026-06-04T19:22:38.495823Z","steps":["trace[1783444192] 'agreement among raft nodes before linearized reading' (duration: 228.325941ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:22:38.62978Z","caller":"traceutil/trace.go:171","msg":"trace[671980509] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"101.176988ms","start":"2026-06-04T19:22:38.528582Z","end":"2026-06-04T19:22:38.629759Z","steps":["trace[671980509] 'read index received' (duration: 55.484329ms)","trace[671980509] 'applied index is now lower than readState.Index' (duration: 45.691909ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:22:38.629887Z","caller":"traceutil/trace.go:171","msg":"trace[1169370148] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"128.33598ms","start":"2026-06-04T19:22:38.501523Z","end":"2026-06-04T19:22:38.629859Z","steps":["trace[1169370148] 'process raft request' (duration: 82.452513ms)","trace[1169370148] 'compare' (duration: 45.695329ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:22:38.629918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.300142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.629952Z","caller":"traceutil/trace.go:171","msg":"trace[1181021570] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:800; }","duration":"101.423847ms","start":"2026-06-04T19:22:38.528519Z","end":"2026-06-04T19:22:38.629943Z","steps":["trace[1181021570] 'agreement among raft nodes before linearized reading' (duration: 101.331573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.630314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.622056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.630366Z","caller":"traceutil/trace.go:171","msg":"trace[140636202] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:803; }","duration":"101.707598ms","start":"2026-06-04T19:22:38.528647Z","end":"2026-06-04T19:22:38.630355Z","steps":["trace[140636202] 'agreement among raft nodes before linearized reading' (duration: 101.620745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:22:38.630758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.779562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:22:38.630786Z","caller":"traceutil/trace.go:171","msg":"trace[95140179] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:803; }","duration":"100.852725ms","start":"2026-06-04T19:22:38.529926Z","end":"2026-06-04T19:22:38.630779Z","steps":["trace[95140179] 'agreement among raft nodes before linearized reading' (duration: 100.810204ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:23:05.089667Z","caller":"traceutil/trace.go:171","msg":"trace[2116394616] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"280.259295ms","start":"2026-06-04T19:23:04.80938Z","end":"2026-06-04T19:23:05.08964Z","steps":["trace[2116394616] 'process raft request' (duration: 280.037029ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:23:23.577768Z","caller":"traceutil/trace.go:171","msg":"trace[1909914845] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"172.044224ms","start":"2026-06-04T19:23:23.405684Z","end":"2026-06-04T19:23:23.577728Z","steps":["trace[1909914845] 'process raft request' (duration: 168.115663ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:23:31.260276Z","caller":"traceutil/trace.go:171","msg":"trace[2119139915] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1494; }","duration":"134.862179ms","start":"2026-06-04T19:23:31.125387Z","end":"2026-06-04T19:23:31.260249Z","steps":["trace[2119139915] 'read index received' (duration: 77.026696ms)","trace[2119139915] 'applied index is now lower than readState.Index' (duration: 57.834413ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:23:31.260424Z","caller":"traceutil/trace.go:171","msg":"trace[1878161719] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"150.395003ms","start":"2026-06-04T19:23:31.110005Z","end":"2026-06-04T19:23:31.2604Z","steps":["trace[1878161719] 'process raft request' (duration: 148.672561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:31.260448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.040363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T19:23:31.260876Z","caller":"traceutil/trace.go:171","msg":"trace[1691128154] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1444; }","duration":"135.387692ms","start":"2026-06-04T19:23:31.125341Z","end":"2026-06-04T19:23:31.260729Z","steps":["trace[1691128154] 'agreement among raft nodes before linearized reading' (duration: 135.015273ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:23:32.435937Z","caller":"traceutil/trace.go:171","msg":"trace[2098698364] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"226.783093ms","start":"2026-06-04T19:23:32.209128Z","end":"2026-06-04T19:23:32.435911Z","steps":["trace[2098698364] 'process raft request' (duration: 226.634529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.007912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15134239431652627122,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-04T19:23:58.044927Z","caller":"traceutil/trace.go:171","msg":"trace[557970347] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"539.63364ms","start":"2026-06-04T19:23:57.505239Z","end":"2026-06-04T19:23:58.044872Z","steps":["trace[557970347] 'process raft request' (duration: 539.480627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.045138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.505213Z","time spent":"539.795364ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:23:58.158334Z","caller":"traceutil/trace.go:171","msg":"trace[373054635] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"113.88068ms","start":"2026-06-04T19:23:58.044427Z","end":"2026-06-04T19:23:58.158307Z","steps":["trace[373054635] 'process raft request' (duration: 113.460612ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:23:58.158487Z","caller":"traceutil/trace.go:171","msg":"trace[204880396] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1941; }","duration":"650.681113ms","start":"2026-06-04T19:23:57.507794Z","end":"2026-06-04T19:23:58.158475Z","steps":["trace[204880396] 'read index received' (duration: 537.030727ms)","trace[204880396] 'applied index is now lower than readState.Index' (duration: 113.649206ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:23:58.158916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.098922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-04T19:23:58.158918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.154901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e152a8a-7de0-496b-8175-1a30502ac98d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-04T19:23:58.158965Z","caller":"traceutil/trace.go:171","msg":"trace[168709362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1865; }","duration":"651.243434ms","start":"2026-06-04T19:23:57.507708Z","end":"2026-06-04T19:23:58.158951Z","steps":["trace[168709362] 'agreement among raft nodes before linearized reading' (duration: 650.855297ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:23:58.158993Z","caller":"traceutil/trace.go:171","msg":"trace[1279690] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e152a8a-7de0-496b-8175-1a30502ac98d; range_end:; response_count:1; response_revision:1865; }","duration":"646.275214ms","start":"2026-06-04T19:23:57.512697Z","end":"2026-06-04T19:23:58.158972Z","steps":["trace[1279690] 'agreement among raft nodes before linearized reading' (duration: 646.058619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.159163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.373537ms","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-06-04T19:23:58.159238Z","caller":"traceutil/trace.go:171","msg":"trace[1143592236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1865; }","duration":"585.480098ms","start":"2026-06-04T19:23:57.573748Z","end":"2026-06-04T19:23:58.159228Z","steps":["trace[1143592236] 'agreement among raft nodes before linearized reading' (duration: 585.356006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.159296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.411325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T19:23:58.15932Z","caller":"traceutil/trace.go:171","msg":"trace[2137502221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"644.448917ms","start":"2026-06-04T19:23:57.514864Z","end":"2026-06-04T19:23:58.159313Z","steps":["trace[2137502221] 'agreement among raft nodes before linearized reading' (duration: 644.356135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.159316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.573729Z","time spent":"585.56264ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T19:23:58.159171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.512683Z","time spent":"646.477328ms","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e152a8a-7de0-496b-8175-1a30502ac98d\" "} {"level":"warn","ts":"2026-06-04T19:23:58.159366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.514856Z","time spent":"644.498328ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-04T19:23:58.159005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.573307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T19:23:58.16072Z","caller":"traceutil/trace.go:171","msg":"trace[1001297769] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1865; }","duration":"577.326973ms","start":"2026-06-04T19:23:57.583373Z","end":"2026-06-04T19:23:58.1607Z","steps":["trace[1001297769] 'agreement among raft nodes before linearized reading' (duration: 575.541677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.160781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.583362Z","time spent":"577.400644ms","remote":"127.0.0.1:34042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-06-04T19:23:58.159084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.082479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T19:23:58.160974Z","caller":"traceutil/trace.go:171","msg":"trace[608050173] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1865; }","duration":"645.986568ms","start":"2026-06-04T19:23:57.514966Z","end":"2026-06-04T19:23:58.160953Z","steps":["trace[608050173] 'agreement among raft nodes before linearized reading' (duration: 644.077009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.159003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.507693Z","time spent":"651.301286ms","remote":"127.0.0.1:40696","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-06-04T19:23:58.161072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.514952Z","time spent":"646.08834ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-06-04T19:23:58.159441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.073691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T19:23:58.161341Z","caller":"traceutil/trace.go:171","msg":"trace[1343748672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"434.00484ms","start":"2026-06-04T19:23:57.727317Z","end":"2026-06-04T19:23:58.161322Z","steps":["trace[1343748672] 'agreement among raft nodes before linearized reading' (duration: 432.00558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:23:58.16142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:23:57.727296Z","time spent":"434.101862ms","remote":"127.0.0.1:40696","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-06-04T19:24:10.780986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.325975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:24:10.78142Z","caller":"traceutil/trace.go:171","msg":"trace[1087428998] linearizableReadLoop","detail":"{readStateIndex:2047; appliedIndex:2046; }","duration":"391.434483ms","start":"2026-06-04T19:24:10.389951Z","end":"2026-06-04T19:24:10.781385Z","steps":["trace[1087428998] 'read index received' (duration: 69.257932ms)","trace[1087428998] 'applied index is now lower than readState.Index' (duration: 322.174681ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:24:10.781511Z","caller":"traceutil/trace.go:171","msg":"trace[1439171930] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"482.151142ms","start":"2026-06-04T19:24:10.2993Z","end":"2026-06-04T19:24:10.781451Z","steps":["trace[1439171930] 'process raft request' (duration: 159.97816ms)","trace[1439171930] 'compare' (duration: 320.736995ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:24:10.781646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.309443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T19:24:10.781686Z","caller":"traceutil/trace.go:171","msg":"trace[435865675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1964; }","duration":"389.385235ms","start":"2026-06-04T19:24:10.392288Z","end":"2026-06-04T19:24:10.781673Z","steps":["trace[435865675] 'agreement among raft nodes before linearized reading' (duration: 389.225522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:10.78172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:10.299286Z","time spent":"482.321066ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:24:10.781714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.739239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T19:24:10.781783Z","caller":"traceutil/trace.go:171","msg":"trace[479338953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1964; }","duration":"391.854642ms","start":"2026-06-04T19:24:10.389907Z","end":"2026-06-04T19:24:10.781762Z","steps":["trace[479338953] 'agreement among raft nodes before linearized reading' (duration: 391.585206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:10.781754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.086002ms","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":"warn","ts":"2026-06-04T19:24:10.781815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.296936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-04T19:24:10.781824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:10.38989Z","time spent":"391.925633ms","remote":"127.0.0.1:40696","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":"info","ts":"2026-06-04T19:24:10.781864Z","caller":"traceutil/trace.go:171","msg":"trace[783402438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1964; }","duration":"387.349296ms","start":"2026-06-04T19:24:10.394502Z","end":"2026-06-04T19:24:10.781851Z","steps":["trace[783402438] 'agreement among raft nodes before linearized reading' (duration: 387.251424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:10.781893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:10.394496Z","time spent":"387.391357ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-04T19:24:10.781878Z","caller":"traceutil/trace.go:171","msg":"trace[270794264] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1964; }","duration":"114.287505ms","start":"2026-06-04T19:24:10.667564Z","end":"2026-06-04T19:24:10.781851Z","steps":["trace[270794264] 'agreement among raft nodes before linearized reading' (duration: 114.047231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:10.781724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:10.392242Z","time spent":"389.473447ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-04T19:24:27.516364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.933048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T19:24:27.516539Z","caller":"traceutil/trace.go:171","msg":"trace[1784465377] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2074; }","duration":"129.093761ms","start":"2026-06-04T19:24:27.387349Z","end":"2026-06-04T19:24:27.516443Z","steps":["trace[1784465377] 'range keys from in-memory index tree' (duration: 128.679104ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:24:33.32767Z","caller":"traceutil/trace.go:171","msg":"trace[1616437437] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"278.997478ms","start":"2026-06-04T19:24:33.048635Z","end":"2026-06-04T19:24:33.327633Z","steps":["trace[1616437437] 'read index received' (duration: 278.780854ms)","trace[1616437437] 'applied index is now lower than readState.Index' (duration: 215.384µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:24:33.328029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.768728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T19:24:33.328028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.368835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T19:24:33.328062Z","caller":"traceutil/trace.go:171","msg":"trace[9810282] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"321.538016ms","start":"2026-06-04T19:24:33.006488Z","end":"2026-06-04T19:24:33.328026Z","steps":["trace[9810282] 'process raft request' (duration: 320.956936ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:24:33.328108Z","caller":"traceutil/trace.go:171","msg":"trace[725301430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2104; }","duration":"184.89557ms","start":"2026-06-04T19:24:33.143193Z","end":"2026-06-04T19:24:33.328088Z","steps":["trace[725301430] 'agreement among raft nodes before linearized reading' (duration: 184.766678ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:24:33.328115Z","caller":"traceutil/trace.go:171","msg":"trace[1694504723] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2104; }","duration":"279.532597ms","start":"2026-06-04T19:24:33.048565Z","end":"2026-06-04T19:24:33.328097Z","steps":["trace[1694504723] 'agreement among raft nodes before linearized reading' (duration: 279.318033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:33.328222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:33.00647Z","time spent":"321.647667ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:24:33.465653Z","caller":"traceutil/trace.go:171","msg":"trace[1479055307] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"116.945559ms","start":"2026-06-04T19:24:33.34867Z","end":"2026-06-04T19:24:33.465615Z","steps":["trace[1479055307] 'process raft request' (duration: 66.729571ms)","trace[1479055307] 'compare' (duration: 49.804311ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:24:33.73901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.990058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-04T19:24:33.739106Z","caller":"traceutil/trace.go:171","msg":"trace[1555443682] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2105; }","duration":"135.14605ms","start":"2026-06-04T19:24:33.603939Z","end":"2026-06-04T19:24:33.739085Z","steps":["trace[1555443682] 'range keys from in-memory index tree' (duration: 134.745484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:33.73902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.857832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T19:24:33.739207Z","caller":"traceutil/trace.go:171","msg":"trace[1764218327] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2105; }","duration":"174.105887ms","start":"2026-06-04T19:24:33.565082Z","end":"2026-06-04T19:24:33.739188Z","steps":["trace[1764218327] 'range keys from in-memory index tree' (duration: 173.627818ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:24:34.411983Z","caller":"traceutil/trace.go:171","msg":"trace[1962880167] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"182.597119ms","start":"2026-06-04T19:24:34.229354Z","end":"2026-06-04T19:24:34.411951Z","steps":["trace[1962880167] 'process raft request' (duration: 182.403776ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:24:34.495384Z","caller":"traceutil/trace.go:171","msg":"trace[563590208] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"186.1351ms","start":"2026-06-04T19:24:34.309207Z","end":"2026-06-04T19:24:34.495342Z","steps":["trace[563590208] 'process raft request' (duration: 131.170934ms)","trace[563590208] 'compare' (duration: 54.744922ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:24:35.136025Z","caller":"traceutil/trace.go:171","msg":"trace[517106615] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"157.481924ms","start":"2026-06-04T19:24:34.978509Z","end":"2026-06-04T19:24:35.135991Z","steps":["trace[517106615] 'read index received' (duration: 157.189579ms)","trace[517106615] 'applied index is now lower than readState.Index' (duration: 290.825µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:24:35.136178Z","caller":"traceutil/trace.go:171","msg":"trace[407793885] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"197.956682ms","start":"2026-06-04T19:24:34.938183Z","end":"2026-06-04T19:24:35.13614Z","steps":["trace[407793885] 'process raft request' (duration: 197.578056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:35.136439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.89359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-04T19:24:35.136552Z","caller":"traceutil/trace.go:171","msg":"trace[2140441595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2109; }","duration":"157.984562ms","start":"2026-06-04T19:24:34.9785Z","end":"2026-06-04T19:24:35.136485Z","steps":["trace[2140441595] 'agreement among raft nodes before linearized reading' (duration: 157.657656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:35.721547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.969804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:24:35.721657Z","caller":"traceutil/trace.go:171","msg":"trace[1935448630] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2197; }","duration":"585.522283ms","start":"2026-06-04T19:24:35.136119Z","end":"2026-06-04T19:24:35.721641Z","steps":["trace[1935448630] 'read index received' (duration: 402.326145ms)","trace[1935448630] 'applied index is now lower than readState.Index' (duration: 183.193647ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:24:35.721722Z","caller":"traceutil/trace.go:171","msg":"trace[1706654148] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"729.433795ms","start":"2026-06-04T19:24:34.992276Z","end":"2026-06-04T19:24:35.721709Z","steps":["trace[1706654148] 'process raft request' (duration: 546.172976ms)","trace[1706654148] 'compare' (duration: 182.701749ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:24:35.721774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:34.992249Z","time spent":"729.495625ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:24:35.72194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.155424ms","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-06-04T19:24:35.721997Z","caller":"traceutil/trace.go:171","msg":"trace[520826737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2110; }","duration":"721.228535ms","start":"2026-06-04T19:24:35.00075Z","end":"2026-06-04T19:24:35.721979Z","steps":["trace[520826737] 'agreement among raft nodes before linearized reading' (duration: 721.062942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:35.722009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.778295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-04T19:24:35.722037Z","caller":"traceutil/trace.go:171","msg":"trace[683403443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2110; }","duration":"721.847526ms","start":"2026-06-04T19:24:35.000181Z","end":"2026-06-04T19:24:35.722029Z","steps":["trace[683403443] 'agreement among raft nodes before linearized reading' (duration: 721.750064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:35.722043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.000739Z","time spent":"721.290736ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T19:24:35.722065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.000157Z","time spent":"721.901557ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-04T19:24:35.722811Z","caller":"traceutil/trace.go:171","msg":"trace[1298402096] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"251.815672ms","start":"2026-06-04T19:24:35.470981Z","end":"2026-06-04T19:24:35.722797Z","steps":["trace[1298402096] 'process raft request' (duration: 251.777811ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:24:35.723057Z","caller":"traceutil/trace.go:171","msg":"trace[1375279063] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"578.080826ms","start":"2026-06-04T19:24:35.144965Z","end":"2026-06-04T19:24:35.723046Z","steps":["trace[1375279063] 'process raft request' (duration: 577.68956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:35.723119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.144948Z","time spent":"578.132437ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:24:35.723259Z","caller":"traceutil/trace.go:171","msg":"trace[1565983695] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"389.071121ms","start":"2026-06-04T19:24:35.334174Z","end":"2026-06-04T19:24:35.723245Z","steps":["trace[1565983695] 'process raft request' (duration: 388.555162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:35.723335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.334153Z","time spent":"389.143192ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:24:36.090005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.262083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:24:36.090131Z","caller":"traceutil/trace.go:171","msg":"trace[272366868] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2198; }","duration":"368.429014ms","start":"2026-06-04T19:24:35.721682Z","end":"2026-06-04T19:24:36.090111Z","steps":["trace[272366868] 'read index received' (duration: 923.566µs)","trace[272366868] 'applied index is now lower than readState.Index' (duration: 367.503728ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:24:36.090193Z","caller":"traceutil/trace.go:171","msg":"trace[11103791] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"390.976237ms","start":"2026-06-04T19:24:35.699167Z","end":"2026-06-04T19:24:36.090144Z","steps":["trace[11103791] 'process raft request' (duration: 171.476389ms)","trace[11103791] 'compare' (duration: 218.140935ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:24:36.090242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.521527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T19:24:36.090314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.363893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-06-04T19:24:36.090385Z","caller":"traceutil/trace.go:171","msg":"trace[393399371] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2114; }","duration":"546.510035ms","start":"2026-06-04T19:24:35.543861Z","end":"2026-06-04T19:24:36.090371Z","steps":["trace[393399371] 'agreement among raft nodes before linearized reading' (duration: 546.275341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:36.090422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.543833Z","time spent":"546.582336ms","remote":"127.0.0.1:40638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-06-04T19:24:36.090428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.699139Z","time spent":"391.17858ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:24:36.090398Z","caller":"traceutil/trace.go:171","msg":"trace[166095700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2114; }","duration":"947.6873ms","start":"2026-06-04T19:24:35.142669Z","end":"2026-06-04T19:24:36.090356Z","steps":["trace[166095700] 'agreement among raft nodes before linearized reading' (duration: 947.513477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:36.090557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.142646Z","time spent":"947.896673ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-04T19:24:36.119869Z","caller":"traceutil/trace.go:171","msg":"trace[1542479734] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"391.820019ms","start":"2026-06-04T19:24:35.728024Z","end":"2026-06-04T19:24:36.119844Z","steps":["trace[1542479734] 'process raft request' (duration: 391.529154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:36.120025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.728002Z","time spent":"391.964962ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:24:36.120381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.748626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-06-04T19:24:36.120462Z","caller":"traceutil/trace.go:171","msg":"trace[406607798] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2116; }","duration":"151.892109ms","start":"2026-06-04T19:24:35.968552Z","end":"2026-06-04T19:24:36.120444Z","steps":["trace[406607798] 'agreement among raft nodes before linearized reading' (duration: 151.671745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:36.120601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.258359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b5f73bb3329027\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-04T19:24:36.120679Z","caller":"traceutil/trace.go:171","msg":"trace[1282903797] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b5f73bb3329027; range_end:; response_count:1; response_revision:2116; }","duration":"358.353551ms","start":"2026-06-04T19:24:35.762311Z","end":"2026-06-04T19:24:36.120665Z","steps":["trace[1282903797] 'agreement among raft nodes before linearized reading' (duration: 358.088947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:36.120739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.762303Z","time spent":"358.425132ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b5f73bb3329027\" "} {"level":"warn","ts":"2026-06-04T19:24:36.120827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.461718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-06-04T19:24:36.120866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.871528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T19:24:36.120957Z","caller":"traceutil/trace.go:171","msg":"trace[844252663] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"392.50036ms","start":"2026-06-04T19:24:35.728446Z","end":"2026-06-04T19:24:36.120946Z","steps":["trace[844252663] 'process raft request' (duration: 391.235849ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:24:36.121005Z","caller":"traceutil/trace.go:171","msg":"trace[1200428847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"366.045182ms","start":"2026-06-04T19:24:35.754943Z","end":"2026-06-04T19:24:36.120988Z","steps":["trace[1200428847] 'agreement among raft nodes before linearized reading' (duration: 365.659685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:36.12106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.728435Z","time spent":"392.559821ms","remote":"127.0.0.1:40696","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-06-04T19:24:36.120901Z","caller":"traceutil/trace.go:171","msg":"trace[872026430] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2116; }","duration":"307.521469ms","start":"2026-06-04T19:24:35.813348Z","end":"2026-06-04T19:24:36.120869Z","steps":["trace[872026430] 'agreement among raft nodes before linearized reading' (duration: 307.353776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:24:36.121112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.754925Z","time spent":"366.171844ms","remote":"127.0.0.1:41004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-04T19:24:36.1212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:24:35.813281Z","time spent":"307.902925ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-04T19:25:35.496812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.295734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-04T19:25:35.498553Z","caller":"traceutil/trace.go:171","msg":"trace[1377737698] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2478; }","duration":"123.10164ms","start":"2026-06-04T19:25:35.375425Z","end":"2026-06-04T19:25:35.498527Z","steps":["trace[1377737698] 'range keys from in-memory index tree' (duration: 121.079621ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:25:45.121114Z","caller":"traceutil/trace.go:171","msg":"trace[478764649] linearizableReadLoop","detail":"{readStateIndex:2691; appliedIndex:2690; }","duration":"344.176651ms","start":"2026-06-04T19:25:44.776906Z","end":"2026-06-04T19:25:45.121083Z","steps":["trace[478764649] 'read index received' (duration: 344.019188ms)","trace[478764649] 'applied index is now lower than readState.Index' (duration: 155.993µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:25:45.121168Z","caller":"traceutil/trace.go:171","msg":"trace[782507359] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"356.590688ms","start":"2026-06-04T19:25:44.764537Z","end":"2026-06-04T19:25:45.121128Z","steps":["trace[782507359] 'process raft request' (duration: 356.331685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:25:45.121345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.151522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T19:25:45.121347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:25:44.76451Z","time spent":"356.73676ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:25:45.12139Z","caller":"traceutil/trace.go:171","msg":"trace[2019108398] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:2587; }","duration":"330.246013ms","start":"2026-06-04T19:25:44.791131Z","end":"2026-06-04T19:25:45.121377Z","steps":["trace[2019108398] 'agreement among raft nodes before linearized reading' (duration: 330.126341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:25:45.121434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:25:44.791126Z","time spent":"330.295993ms","remote":"127.0.0.1:41006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-06-04T19:25:45.122205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.305797ms","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-06-04T19:25:45.122257Z","caller":"traceutil/trace.go:171","msg":"trace[1368715040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2587; }","duration":"345.383188ms","start":"2026-06-04T19:25:44.776859Z","end":"2026-06-04T19:25:45.122242Z","steps":["trace[1368715040] 'agreement among raft nodes before linearized reading' (duration: 344.363294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:25:45.122288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:25:44.776843Z","time spent":"345.438669ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-04T19:26:16.963432Z","caller":"traceutil/trace.go:171","msg":"trace[216621461] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"262.614708ms","start":"2026-06-04T19:26:16.700791Z","end":"2026-06-04T19:26:16.963406Z","steps":["trace[216621461] 'process raft request' (duration: 262.438766ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:26:17.174822Z","caller":"traceutil/trace.go:171","msg":"trace[1820354366] linearizableReadLoop","detail":"{readStateIndex:2872; appliedIndex:2870; }","duration":"470.778588ms","start":"2026-06-04T19:26:16.704004Z","end":"2026-06-04T19:26:17.174783Z","steps":["trace[1820354366] 'read index received' (duration: 259.238962ms)","trace[1820354366] 'applied index is now lower than readState.Index' (duration: 211.537796ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:26:17.175224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.096983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T19:26:17.175262Z","caller":"traceutil/trace.go:171","msg":"trace[1529874557] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2761; }","duration":"204.170244ms","start":"2026-06-04T19:26:16.971083Z","end":"2026-06-04T19:26:17.175253Z","steps":["trace[1529874557] 'agreement among raft nodes before linearized reading' (duration: 204.050543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:26:17.175365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.348505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-04T19:26:17.175431Z","caller":"traceutil/trace.go:171","msg":"trace[1514801327] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2761; }","duration":"471.450736ms","start":"2026-06-04T19:26:16.70396Z","end":"2026-06-04T19:26:17.175411Z","steps":["trace[1514801327] 'agreement among raft nodes before linearized reading' (duration: 471.250464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:26:17.175491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:26:16.703945Z","time spent":"471.530988ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-04T19:26:17.175762Z","caller":"traceutil/trace.go:171","msg":"trace[124575460] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"474.909084ms","start":"2026-06-04T19:26:16.700833Z","end":"2026-06-04T19:26:17.175742Z","steps":["trace[124575460] 'process raft request' (duration: 436.175144ms)","trace[124575460] 'compare' (duration: 37.25343ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:26:17.175903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:26:16.70081Z","time spent":"475.002996ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:26:17.507758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.094167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:26:17.507883Z","caller":"traceutil/trace.go:171","msg":"trace[1447766203] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2873; }","duration":"197.171969ms","start":"2026-06-04T19:26:17.310693Z","end":"2026-06-04T19:26:17.507865Z","steps":["trace[1447766203] 'read index received' (duration: 110.832µs)","trace[1447766203] 'applied index is now lower than readState.Index' (duration: 197.060187ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:26:17.508321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.627365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T19:26:17.508316Z","caller":"traceutil/trace.go:171","msg":"trace[1284836354] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"268.356633ms","start":"2026-06-04T19:26:17.239867Z","end":"2026-06-04T19:26:17.508224Z","steps":["trace[1284836354] 'process raft request' (duration: 43.700328ms)","trace[1284836354] 'compare' (duration: 223.773053ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:26:17.508358Z","caller":"traceutil/trace.go:171","msg":"trace[1343073120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2763; }","duration":"197.700416ms","start":"2026-06-04T19:26:17.310648Z","end":"2026-06-04T19:26:17.508348Z","steps":["trace[1343073120] 'agreement among raft nodes before linearized reading' (duration: 197.26031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:26:17.890563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.294091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:26:17.891058Z","caller":"traceutil/trace.go:171","msg":"trace[213741192] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"376.506854ms","start":"2026-06-04T19:26:17.514511Z","end":"2026-06-04T19:26:17.891018Z","steps":["trace[213741192] 'process raft request' (duration: 178.600945ms)","trace[213741192] 'compare' (duration: 196.877665ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:26:17.891155Z","caller":"traceutil/trace.go:171","msg":"trace[558213248] linearizableReadLoop","detail":"{readStateIndex:2875; appliedIndex:2874; }","duration":"334.068322ms","start":"2026-06-04T19:26:17.557041Z","end":"2026-06-04T19:26:17.89111Z","steps":["trace[558213248] 'read index received' (duration: 136.152564ms)","trace[558213248] 'applied index is now lower than readState.Index' (duration: 197.913028ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:26:17.89136Z","caller":"traceutil/trace.go:171","msg":"trace[270085317] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"231.869404ms","start":"2026-06-04T19:26:17.659463Z","end":"2026-06-04T19:26:17.891332Z","steps":["trace[270085317] 'process raft request' (duration: 231.57705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:26:17.891385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:26:17.514483Z","time spent":"376.664206ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:26:17.89145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.337908ms","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-06-04T19:26:17.891498Z","caller":"traceutil/trace.go:171","msg":"trace[183038106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2765; }","duration":"150.45417ms","start":"2026-06-04T19:26:17.741032Z","end":"2026-06-04T19:26:17.891486Z","steps":["trace[183038106] 'agreement among raft nodes before linearized reading' (duration: 150.261817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:26:17.891439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.313046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:26:17.891699Z","caller":"traceutil/trace.go:171","msg":"trace[1608451699] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2765; }","duration":"334.683571ms","start":"2026-06-04T19:26:17.556982Z","end":"2026-06-04T19:26:17.891666Z","steps":["trace[1608451699] 'agreement among raft nodes before linearized reading' (duration: 334.254895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:26:17.891809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:26:17.556867Z","time spent":"334.924955ms","remote":"127.0.0.1:57156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-06-04T19:30:14.093915Z","caller":"traceutil/trace.go:171","msg":"trace[932664288] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"106.390286ms","start":"2026-06-04T19:30:13.987456Z","end":"2026-06-04T19:30:14.093846Z","steps":["trace[932664288] 'process raft request' (duration: 106.08199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.521567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.406042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:30:19.521709Z","caller":"traceutil/trace.go:171","msg":"trace[2058901163] linearizableReadLoop","detail":"{readStateIndex:4861; appliedIndex:4860; }","duration":"242.138617ms","start":"2026-06-04T19:30:19.279546Z","end":"2026-06-04T19:30:19.521684Z","steps":["trace[2058901163] 'read index received' (duration: 61.44543ms)","trace[2058901163] 'applied index is now lower than readState.Index' (duration: 180.691727ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:19.521955Z","caller":"traceutil/trace.go:171","msg":"trace[251264369] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"253.859473ms","start":"2026-06-04T19:30:19.268081Z","end":"2026-06-04T19:30:19.521941Z","steps":["trace[251264369] 'process raft request' (duration: 72.958171ms)","trace[251264369] 'compare' (duration: 179.971644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:19.522277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.819456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-06-04T19:30:19.522323Z","caller":"traceutil/trace.go:171","msg":"trace[1482386715] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76; range_end:; response_count:1; response_revision:4672; }","duration":"247.898238ms","start":"2026-06-04T19:30:19.274411Z","end":"2026-06-04T19:30:19.522309Z","steps":["trace[1482386715] 'agreement among raft nodes before linearized reading' (duration: 247.736805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.523155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.004965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-04T19:30:19.523203Z","caller":"traceutil/trace.go:171","msg":"trace[1108515795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4672; }","duration":"240.078157ms","start":"2026-06-04T19:30:19.283114Z","end":"2026-06-04T19:30:19.523192Z","steps":["trace[1108515795] 'agreement among raft nodes before linearized reading' (duration: 239.907564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.524033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.281716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:19.52408Z","caller":"traceutil/trace.go:171","msg":"trace[254934553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4672; }","duration":"170.386898ms","start":"2026-06-04T19:30:19.353681Z","end":"2026-06-04T19:30:19.524068Z","steps":["trace[254934553] 'agreement among raft nodes before linearized reading' (duration: 170.312797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.524339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.365633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T19:30:19.524381Z","caller":"traceutil/trace.go:171","msg":"trace[746764753] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:4672; }","duration":"192.432805ms","start":"2026-06-04T19:30:19.331937Z","end":"2026-06-04T19:30:19.52437Z","steps":["trace[746764753] 'agreement among raft nodes before linearized reading' (duration: 192.307442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.525188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.902782ms","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-06-04T19:30:19.525285Z","caller":"traceutil/trace.go:171","msg":"trace[498546742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4672; }","duration":"242.055895ms","start":"2026-06-04T19:30:19.283209Z","end":"2026-06-04T19:30:19.525265Z","steps":["trace[498546742] 'agreement among raft nodes before linearized reading' (duration: 241.748379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.52544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.689711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-06-04T19:30:19.525662Z","caller":"traceutil/trace.go:171","msg":"trace[1729855028] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4672; }","duration":"205.827654ms","start":"2026-06-04T19:30:19.319645Z","end":"2026-06-04T19:30:19.525473Z","steps":["trace[1729855028] 'agreement among raft nodes before linearized reading' (duration: 205.006958ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:19.707754Z","caller":"traceutil/trace.go:171","msg":"trace[83694113] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"177.832271ms","start":"2026-06-04T19:30:19.529897Z","end":"2026-06-04T19:30:19.707729Z","steps":["trace[83694113] 'process raft request' (duration: 170.890397ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:19.762557Z","caller":"traceutil/trace.go:171","msg":"trace[1550939878] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"232.248835ms","start":"2026-06-04T19:30:19.530278Z","end":"2026-06-04T19:30:19.762527Z","steps":["trace[1550939878] 'process raft request' (duration: 232.042801ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:19.762686Z","caller":"traceutil/trace.go:171","msg":"trace[1117863466] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4861; }","duration":"232.093072ms","start":"2026-06-04T19:30:19.53057Z","end":"2026-06-04T19:30:19.762663Z","steps":["trace[1117863466] 'read index received' (duration: 170.378158ms)","trace[1117863466] 'applied index is now lower than readState.Index' (duration: 61.712534ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:19.762765Z","caller":"traceutil/trace.go:171","msg":"trace[39272708] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"229.663645ms","start":"2026-06-04T19:30:19.533091Z","end":"2026-06-04T19:30:19.762754Z","steps":["trace[39272708] 'process raft request' (duration: 229.35994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.762898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.299936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-06-04T19:30:19.762934Z","caller":"traceutil/trace.go:171","msg":"trace[447101486] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4675; }","duration":"232.368528ms","start":"2026-06-04T19:30:19.530552Z","end":"2026-06-04T19:30:19.762921Z","steps":["trace[447101486] 'agreement among raft nodes before linearized reading' (duration: 232.210205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:19.763057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.481626ms","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-06-04T19:30:19.763149Z","caller":"traceutil/trace.go:171","msg":"trace[1344074840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4675; }","duration":"175.65485ms","start":"2026-06-04T19:30:19.587471Z","end":"2026-06-04T19:30:19.763126Z","steps":["trace[1344074840] 'agreement among raft nodes before linearized reading' (duration: 175.405295ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:19.967107Z","caller":"traceutil/trace.go:171","msg":"trace[1172708165] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"103.806239ms","start":"2026-06-04T19:30:19.863266Z","end":"2026-06-04T19:30:19.967072Z","steps":["trace[1172708165] 'process raft request' (duration: 103.585445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:20.669014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.622835ms","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-06-04T19:30:20.669083Z","caller":"traceutil/trace.go:171","msg":"trace[1663902139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4680; }","duration":"276.772347ms","start":"2026-06-04T19:30:20.392296Z","end":"2026-06-04T19:30:20.669069Z","steps":["trace[1663902139] 'range keys from in-memory index tree' (duration: 276.479832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:20.669004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.686742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76\" ","response":"range_response_count:1 size:3890"} {"level":"info","ts":"2026-06-04T19:30:20.669237Z","caller":"traceutil/trace.go:171","msg":"trace[980650530] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76; range_end:; response_count:1; response_revision:4680; }","duration":"386.001058ms","start":"2026-06-04T19:30:20.283225Z","end":"2026-06-04T19:30:20.669226Z","steps":["trace[980650530] 'range keys from in-memory index tree' (duration: 385.465938ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:20.669234Z","caller":"traceutil/trace.go:171","msg":"trace[1438524711] linearizableReadLoop","detail":"{readStateIndex:4870; appliedIndex:4869; }","duration":"135.378066ms","start":"2026-06-04T19:30:20.533837Z","end":"2026-06-04T19:30:20.669215Z","steps":["trace[1438524711] 'read index received' (duration: 68.977133ms)","trace[1438524711] 'applied index is now lower than readState.Index' (duration: 66.399473ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:20.669257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.283203Z","time spent":"386.0469ms","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3914,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76\" "} {"level":"info","ts":"2026-06-04T19:30:20.669667Z","caller":"traceutil/trace.go:171","msg":"trace[1579560980] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"182.787082ms","start":"2026-06-04T19:30:20.486852Z","end":"2026-06-04T19:30:20.669639Z","steps":["trace[1579560980] 'process raft request' (duration: 116.005752ms)","trace[1579560980] 'compare' (duration: 66.014106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:20.669746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.921426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab57ef57-811e-4f56-b16b-de3a4761136d\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-06-04T19:30:20.669768Z","caller":"traceutil/trace.go:171","msg":"trace[289033327] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ab57ef57-811e-4f56-b16b-de3a4761136d; range_end:; response_count:1; response_revision:4681; }","duration":"135.996608ms","start":"2026-06-04T19:30:20.533764Z","end":"2026-06-04T19:30:20.669761Z","steps":["trace[289033327] 'agreement among raft nodes before linearized reading' (duration: 135.918406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.344881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.945008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:30:21.344994Z","caller":"traceutil/trace.go:171","msg":"trace[828031008] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"671.14078ms","start":"2026-06-04T19:30:20.673837Z","end":"2026-06-04T19:30:21.344978Z","steps":["trace[828031008] 'process raft request' (duration: 195.018958ms)","trace[828031008] 'compare' (duration: 475.600312ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:21.345041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.673794Z","time spent":"671.223542ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:30:21.3521Z","caller":"traceutil/trace.go:171","msg":"trace[693736549] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4870; }","duration":"647.31537ms","start":"2026-06-04T19:30:20.704775Z","end":"2026-06-04T19:30:21.352091Z","steps":["trace[693736549] 'read index received' (duration: 164.176513ms)","trace[693736549] 'applied index is now lower than readState.Index' (duration: 483.138087ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:21.352242Z","caller":"traceutil/trace.go:171","msg":"trace[74895873] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"675.881011ms","start":"2026-06-04T19:30:20.676335Z","end":"2026-06-04T19:30:21.352216Z","steps":["trace[74895873] 'process raft request' (duration: 675.723798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.352284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.476923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:21.352331Z","caller":"traceutil/trace.go:171","msg":"trace[995688780] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4685; }","duration":"647.558264ms","start":"2026-06-04T19:30:20.70474Z","end":"2026-06-04T19:30:21.352299Z","steps":["trace[995688780] 'agreement among raft nodes before linearized reading' (duration: 647.489892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.352381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.676324Z","time spent":"675.978003ms","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4061,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:30:21.352372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.70472Z","time spent":"647.640326ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-06-04T19:30:21.3525Z","caller":"traceutil/trace.go:171","msg":"trace[2027405019] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"678.305188ms","start":"2026-06-04T19:30:20.674159Z","end":"2026-06-04T19:30:21.352464Z","steps":["trace[2027405019] 'process raft request' (duration: 677.780738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.352709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.674138Z","time spent":"678.465452ms","remote":"127.0.0.1:40696","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-06-04T19:30:21.352615Z","caller":"traceutil/trace.go:171","msg":"trace[673074050] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"676.774298ms","start":"2026-06-04T19:30:20.675801Z","end":"2026-06-04T19:30:21.352576Z","steps":["trace[673074050] 'process raft request' (duration: 676.225968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.353039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.675792Z","time spent":"677.018583ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:30:21.370355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.90111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T19:30:21.370415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.843847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:21.370448Z","caller":"traceutil/trace.go:171","msg":"trace[2030807344] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4686; }","duration":"396.016273ms","start":"2026-06-04T19:30:20.974407Z","end":"2026-06-04T19:30:21.370423Z","steps":["trace[2030807344] 'agreement among raft nodes before linearized reading' (duration: 395.842999ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:21.370484Z","caller":"traceutil/trace.go:171","msg":"trace[1272220017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4686; }","duration":"227.9696ms","start":"2026-06-04T19:30:21.142499Z","end":"2026-06-04T19:30:21.370469Z","steps":["trace[1272220017] 'agreement among raft nodes before linearized reading' (duration: 227.845297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.370496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.974397Z","time spent":"396.086204ms","remote":"127.0.0.1:34012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-06-04T19:30:21.37059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.752628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-04T19:30:21.370687Z","caller":"traceutil/trace.go:171","msg":"trace[959154286] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4686; }","duration":"307.928012ms","start":"2026-06-04T19:30:21.062744Z","end":"2026-06-04T19:30:21.370672Z","steps":["trace[959154286] 'agreement among raft nodes before linearized reading' (duration: 307.501274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.370759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:21.062719Z","time spent":"308.030754ms","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-04T19:30:21.370608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.571593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76\" ","response":"range_response_count:1 size:4076"} {"level":"info","ts":"2026-06-04T19:30:21.370903Z","caller":"traceutil/trace.go:171","msg":"trace[2145207911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76; range_end:; response_count:1; response_revision:4686; }","duration":"397.910849ms","start":"2026-06-04T19:30:20.972964Z","end":"2026-06-04T19:30:21.370875Z","steps":["trace[2145207911] 'agreement among raft nodes before linearized reading' (duration: 397.546932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:21.370974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:20.972942Z","time spent":"398.011161ms","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4100,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d59e6c53-fe57-49a9-801d-a2a886d9da76\" "} {"level":"info","ts":"2026-06-04T19:30:21.484037Z","caller":"traceutil/trace.go:171","msg":"trace[12353755] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"105.037906ms","start":"2026-06-04T19:30:21.378965Z","end":"2026-06-04T19:30:21.484003Z","steps":["trace[12353755] 'process raft request' (duration: 104.829202ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:23.42885Z","caller":"traceutil/trace.go:171","msg":"trace[1621633019] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"100.620396ms","start":"2026-06-04T19:30:23.328199Z","end":"2026-06-04T19:30:23.428819Z","steps":["trace[1621633019] 'process raft request' (duration: 86.048166ms)","trace[1621633019] 'compare' (duration: 14.372906ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:28.938661Z","caller":"traceutil/trace.go:171","msg":"trace[1010096682] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5060; }","duration":"311.766665ms","start":"2026-06-04T19:30:28.626861Z","end":"2026-06-04T19:30:28.938628Z","steps":["trace[1010096682] 'read index received' (duration: 248.819903ms)","trace[1010096682] 'applied index is now lower than readState.Index' (duration: 62.944362ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:28.938678Z","caller":"traceutil/trace.go:171","msg":"trace[1863718170] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"313.133292ms","start":"2026-06-04T19:30:28.625507Z","end":"2026-06-04T19:30:28.93864Z","steps":["trace[1863718170] 'process raft request' (duration: 250.20052ms)","trace[1863718170] 'compare' (duration: 62.589565ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:28.938858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:28.625487Z","time spent":"313.292914ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-04T19:30:28.938906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.01674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:28.939165Z","caller":"traceutil/trace.go:171","msg":"trace[533962378] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4866; }","duration":"312.472519ms","start":"2026-06-04T19:30:28.626644Z","end":"2026-06-04T19:30:28.939117Z","steps":["trace[533962378] 'agreement among raft nodes before linearized reading' (duration: 312.060531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:28.939243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:28.626633Z","time spent":"312.57357ms","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" "} {"level":"info","ts":"2026-06-04T19:30:29.286777Z","caller":"traceutil/trace.go:171","msg":"trace[301706311] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5061; }","duration":"348.01352ms","start":"2026-06-04T19:30:28.938734Z","end":"2026-06-04T19:30:29.286747Z","steps":["trace[301706311] 'read index received' (duration: 300.372102ms)","trace[301706311] 'applied index is now lower than readState.Index' (duration: 47.634078ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:29.287045Z","caller":"traceutil/trace.go:171","msg":"trace[992845672] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"588.382432ms","start":"2026-06-04T19:30:28.698601Z","end":"2026-06-04T19:30:29.286983Z","steps":["trace[992845672] 'process raft request' (duration: 540.59255ms)","trace[992845672] 'compare' (duration: 46.885315ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:29.28709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.849857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T19:30:29.287594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:28.698563Z","time spent":"588.924191ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:30:29.287653Z","caller":"traceutil/trace.go:171","msg":"trace[759266012] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4867; }","duration":"392.467509ms","start":"2026-06-04T19:30:28.895137Z","end":"2026-06-04T19:30:29.287604Z","steps":["trace[759266012] 'agreement among raft nodes before linearized reading' (duration: 391.825276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:29.287145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.920497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-877120d1-d4a2-4f11-b0a6-3e0a5588984b\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-06-04T19:30:29.287854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:28.895107Z","time spent":"392.619392ms","remote":"127.0.0.1:45194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-04T19:30:29.288232Z","caller":"traceutil/trace.go:171","msg":"trace[281354135] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-877120d1-d4a2-4f11-b0a6-3e0a5588984b; range_end:; response_count:1; response_revision:4867; }","duration":"361.036019ms","start":"2026-06-04T19:30:28.92716Z","end":"2026-06-04T19:30:29.288196Z","steps":["trace[281354135] 'agreement among raft nodes before linearized reading' (duration: 359.817516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:29.28844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:28.927141Z","time spent":"361.251993ms","remote":"127.0.0.1:40630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-877120d1-d4a2-4f11-b0a6-3e0a5588984b\" "} {"level":"info","ts":"2026-06-04T19:30:29.537416Z","caller":"traceutil/trace.go:171","msg":"trace[585746951] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"542.171482ms","start":"2026-06-04T19:30:28.995213Z","end":"2026-06-04T19:30:29.537385Z","steps":["trace[585746951] 'process raft request' (duration: 519.730274ms)","trace[585746951] 'compare' (duration: 21.734375ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:29.537584Z","caller":"traceutil/trace.go:171","msg":"trace[1798750962] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5062; }","duration":"250.300984ms","start":"2026-06-04T19:30:29.287239Z","end":"2026-06-04T19:30:29.53754Z","steps":["trace[1798750962] 'read index received' (duration: 227.723784ms)","trace[1798750962] 'applied index is now lower than readState.Index' (duration: 22.57489ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:29.537635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.28823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:29.537677Z","caller":"traceutil/trace.go:171","msg":"trace[1192016272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4869; }","duration":"394.396493ms","start":"2026-06-04T19:30:29.14327Z","end":"2026-06-04T19:30:29.537666Z","steps":["trace[1192016272] 'agreement among raft nodes before linearized reading' (duration: 394.302741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:29.537654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.472296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T19:30:29.537701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:29.143247Z","time spent":"394.447214ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-04T19:30:29.537718Z","caller":"traceutil/trace.go:171","msg":"trace[456495448] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4869; }","duration":"457.611748ms","start":"2026-06-04T19:30:29.080092Z","end":"2026-06-04T19:30:29.537704Z","steps":["trace[456495448] 'agreement among raft nodes before linearized reading' (duration: 457.477856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:29.537757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:29.080065Z","time spent":"457.68436ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-04T19:30:29.53778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.475945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-04T19:30:29.537868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.129966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-06-04T19:30:29.537876Z","caller":"traceutil/trace.go:171","msg":"trace[1767600285] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4869; }","duration":"469.657018ms","start":"2026-06-04T19:30:29.068194Z","end":"2026-06-04T19:30:29.537851Z","steps":["trace[1767600285] 'agreement among raft nodes before linearized reading' (duration: 469.452154ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:29.537908Z","caller":"traceutil/trace.go:171","msg":"trace[1869998693] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4869; }","duration":"148.234578ms","start":"2026-06-04T19:30:29.389661Z","end":"2026-06-04T19:30:29.537896Z","steps":["trace[1869998693] 'agreement among raft nodes before linearized reading' (duration: 148.052044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:29.537941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:29.068162Z","time spent":"469.768381ms","remote":"127.0.0.1:40942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-04T19:30:29.537979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.529483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-06-04T19:30:29.538097Z","caller":"traceutil/trace.go:171","msg":"trace[910077118] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4869; }","duration":"593.738167ms","start":"2026-06-04T19:30:28.944328Z","end":"2026-06-04T19:30:29.538066Z","steps":["trace[910077118] 'agreement among raft nodes before linearized reading' (duration: 593.37575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:29.538166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:28.944298Z","time spent":"593.857999ms","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":720,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"warn","ts":"2026-06-04T19:30:29.53842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:28.995178Z","time spent":"542.316344ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:30:29.538468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.406694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-595d5c657d-2v8qr.18b5f790a883b723\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-06-04T19:30:29.538554Z","caller":"traceutil/trace.go:171","msg":"trace[448689218] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-595d5c657d-2v8qr.18b5f790a883b723; range_end:; response_count:1; response_revision:4869; }","duration":"145.508136ms","start":"2026-06-04T19:30:29.393032Z","end":"2026-06-04T19:30:29.53854Z","steps":["trace[448689218] 'agreement among raft nodes before linearized reading' (duration: 145.122369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:30.311101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.952076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T19:30:30.311713Z","caller":"traceutil/trace.go:171","msg":"trace[2084269686] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"480.68536ms","start":"2026-06-04T19:30:29.830995Z","end":"2026-06-04T19:30:30.31168Z","steps":["trace[2084269686] 'process raft request' (duration: 298.943848ms)","trace[2084269686] 'compare' (duration: 180.731472ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:30.311896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:29.830973Z","time spent":"480.830813ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:30:30.312084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.757034ms","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-06-04T19:30:30.312154Z","caller":"traceutil/trace.go:171","msg":"trace[192848885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4889; }","duration":"466.872326ms","start":"2026-06-04T19:30:29.845265Z","end":"2026-06-04T19:30:30.312137Z","steps":["trace[192848885] 'agreement among raft nodes before linearized reading' (duration: 466.53897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:30.312215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:29.845245Z","time spent":"466.955337ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-04T19:30:30.311759Z","caller":"traceutil/trace.go:171","msg":"trace[1269521853] linearizableReadLoop","detail":"{readStateIndex:5084; appliedIndex:5082; }","duration":"466.415968ms","start":"2026-06-04T19:30:29.845315Z","end":"2026-06-04T19:30:30.311731Z","steps":["trace[1269521853] 'read index received' (duration: 36.083608ms)","trace[1269521853] 'applied index is now lower than readState.Index' (duration: 430.329509ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:30.312614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.045415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T19:30:30.31267Z","caller":"traceutil/trace.go:171","msg":"trace[1271635293] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4889; }","duration":"364.110886ms","start":"2026-06-04T19:30:29.948544Z","end":"2026-06-04T19:30:30.312655Z","steps":["trace[1271635293] 'agreement among raft nodes before linearized reading' (duration: 364.011304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:30.312715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:29.948469Z","time spent":"364.232109ms","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-06-04T19:30:30.542758Z","caller":"traceutil/trace.go:171","msg":"trace[1266227641] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"132.533884ms","start":"2026-06-04T19:30:30.410178Z","end":"2026-06-04T19:30:30.542712Z","steps":["trace[1266227641] 'process raft request' (duration: 131.678558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.005946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.946407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-06-04T19:30:43.00606Z","caller":"traceutil/trace.go:171","msg":"trace[2088400952] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5050; }","duration":"129.198962ms","start":"2026-06-04T19:30:42.876836Z","end":"2026-06-04T19:30:43.006035Z","steps":["trace[2088400952] 'range keys from in-memory index tree' (duration: 128.650381ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:43.439489Z","caller":"traceutil/trace.go:171","msg":"trace[1598010438] linearizableReadLoop","detail":"{readStateIndex:5253; appliedIndex:5252; }","duration":"414.428947ms","start":"2026-06-04T19:30:43.025032Z","end":"2026-06-04T19:30:43.439461Z","steps":["trace[1598010438] 'read index received' (duration: 414.354116ms)","trace[1598010438] 'applied index is now lower than readState.Index' (duration: 73.221µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:43.439654Z","caller":"traceutil/trace.go:171","msg":"trace[1865295939] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"415.768232ms","start":"2026-06-04T19:30:43.023836Z","end":"2026-06-04T19:30:43.439604Z","steps":["trace[1865295939] 'process raft request' (duration: 415.460596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.439726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.676912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-06-04T19:30:43.439773Z","caller":"traceutil/trace.go:171","msg":"trace[1965717782] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5051; }","duration":"414.786264ms","start":"2026-06-04T19:30:43.024976Z","end":"2026-06-04T19:30:43.439762Z","steps":["trace[1965717782] 'agreement among raft nodes before linearized reading' (duration: 414.617491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.439819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:43.024955Z","time spent":"414.853065ms","remote":"127.0.0.1:40638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1477,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-06-04T19:30:43.439964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:43.023821Z","time spent":"415.954196ms","remote":"127.0.0.1:40534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T19:30:43.606276Z","caller":"traceutil/trace.go:171","msg":"trace[441121168] linearizableReadLoop","detail":"{readStateIndex:5255; appliedIndex:5253; }","duration":"166.69338ms","start":"2026-06-04T19:30:43.439563Z","end":"2026-06-04T19:30:43.606256Z","steps":["trace[441121168] 'read index received' (duration: 165.097281ms)","trace[441121168] 'applied index is now lower than readState.Index' (duration: 1.595419ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T19:30:43.606346Z","caller":"traceutil/trace.go:171","msg":"trace[1994923939] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"266.729113ms","start":"2026-06-04T19:30:43.339602Z","end":"2026-06-04T19:30:43.606331Z","steps":["trace[1994923939] 'process raft request' (duration: 266.624101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.606462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.409656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:43.606497Z","caller":"traceutil/trace.go:171","msg":"trace[2110014006] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:5053; }","duration":"384.528898ms","start":"2026-06-04T19:30:43.221959Z","end":"2026-06-04T19:30:43.606488Z","steps":["trace[2110014006] 'agreement among raft nodes before linearized reading' (duration: 384.400345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.606521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:43.221928Z","time spent":"384.586228ms","remote":"127.0.0.1:46934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-06-04T19:30:43.606549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.109897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:43.606675Z","caller":"traceutil/trace.go:171","msg":"trace[1301154346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5053; }","duration":"464.298401ms","start":"2026-06-04T19:30:43.14235Z","end":"2026-06-04T19:30:43.606648Z","steps":["trace[1301154346] 'agreement among raft nodes before linearized reading' (duration: 464.087337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.60672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:43.142327Z","time spent":"464.385852ms","remote":"127.0.0.1:40468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-04T19:30:43.606905Z","caller":"traceutil/trace.go:171","msg":"trace[539720496] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"302.619594ms","start":"2026-06-04T19:30:43.303685Z","end":"2026-06-04T19:30:43.606305Z","steps":["trace[539720496] 'process raft request' (duration: 300.960573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.607131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T19:30:43.303649Z","time spent":"303.377569ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T19:30:43.618321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.583813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-06-04T19:30:43.618416Z","caller":"traceutil/trace.go:171","msg":"trace[988897823] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5054; }","duration":"100.723346ms","start":"2026-06-04T19:30:43.51767Z","end":"2026-06-04T19:30:43.618394Z","steps":["trace[988897823] 'agreement among raft nodes before linearized reading' (duration: 100.498881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.61864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.329504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b5f7908ff19022\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-06-04T19:30:43.618723Z","caller":"traceutil/trace.go:171","msg":"trace[1465034127] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b5f7908ff19022; range_end:; response_count:1; response_revision:5054; }","duration":"173.500687ms","start":"2026-06-04T19:30:43.445203Z","end":"2026-06-04T19:30:43.618704Z","steps":["trace[1465034127] 'agreement among raft nodes before linearized reading' (duration: 173.242583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.619066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.607071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-06-04T19:30:43.619108Z","caller":"traceutil/trace.go:171","msg":"trace[1096739430] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5054; }","duration":"172.661902ms","start":"2026-06-04T19:30:43.446437Z","end":"2026-06-04T19:30:43.619098Z","steps":["trace[1096739430] 'agreement among raft nodes before linearized reading' (duration: 171.906608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:43.731489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.426991ms","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-06-04T19:30:43.731585Z","caller":"traceutil/trace.go:171","msg":"trace[449224559] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5057; }","duration":"107.575044ms","start":"2026-06-04T19:30:43.623986Z","end":"2026-06-04T19:30:43.731561Z","steps":["trace[449224559] 'agreement among raft nodes before linearized reading' (duration: 107.305229ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:30:43.731891Z","caller":"traceutil/trace.go:171","msg":"trace[1347849190] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"102.162472ms","start":"2026-06-04T19:30:43.62968Z","end":"2026-06-04T19:30:43.731843Z","steps":["trace[1347849190] 'process raft request' (duration: 84.488571ms)","trace[1347849190] 'compare' (duration: 16.877456ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T19:30:43.732035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.048747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T19:30:43.732155Z","caller":"traceutil/trace.go:171","msg":"trace[1016474768] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5057; }","duration":"104.245921ms","start":"2026-06-04T19:30:43.627882Z","end":"2026-06-04T19:30:43.732128Z","steps":["trace[1016474768] 'agreement among raft nodes before linearized reading' (duration: 103.984186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T19:30:44.155459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.365354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-04T19:30:44.155569Z","caller":"traceutil/trace.go:171","msg":"trace[762572603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5070; }","duration":"163.543937ms","start":"2026-06-04T19:30:43.991998Z","end":"2026-06-04T19:30:44.155542Z","steps":["trace[762572603] 'range keys from in-memory index tree' (duration: 163.088539ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T19:31:29.230421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2826} {"level":"info","ts":"2026-06-04T19:31:29.310344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2826,"took":"78.296812ms","hash":3661251045,"current-db-size-bytes":52350976,"current-db-size":"52 MB","current-db-size-in-use-bytes":28348416,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-04T19:31:29.310432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3661251045,"revision":2826,"compact-revision":-1} {"level":"info","ts":"2026-06-04T19:36:29.238461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5455} {"level":"info","ts":"2026-06-04T19:36:29.355366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5455,"took":"114.846524ms","hash":1685078280,"current-db-size-bytes":52350976,"current-db-size":"52 MB","current-db-size-in-use-bytes":18325504,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-04T19:36:29.355428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1685078280,"revision":5455,"compact-revision":2826}