{"level":"warn","ts":"2026-09-22T01:51:27.588385Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:51:27.588581Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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://162.253.55.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-09-22T01:51:27.588728Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:51:27.588785Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-22T01:51:27.588829Z","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-09-22T01:51:27.590233Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-09-22T01:51:27.590394Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-09-22T01:51:27.597178Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.783816ms"} {"level":"info","ts":"2026-09-22T01:51:27.607815Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-09-22T01:51:27.607936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:51:27.607961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-09-22T01:51:27.607975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:51:27.607986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-09-22T01:51:27.608029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-09-22T01:51:27.616886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:51:27.620025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:51:27.622176Z","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-09-22T01:51:27.625144Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:51:27.625365Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:51:27.625599Z","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-09-22T01:51:27.625748Z","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-09-22T01:51:27.625831Z","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-09-22T01:51:27.627704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-09-22T01:51:27.628466Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-22T01:51:27.636326Z","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-09-22T01:51:27.636441Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-22T01:51:27.636495Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-22T01:51:27.636682Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:51:27.636721Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:51:28.10906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:51:28.109158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:51:28.109209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-09-22T01:51:28.109233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:51:28.109246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-22T01:51:28.109264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-09-22T01:51:28.109297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-22T01:51:28.11325Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:51:28.11373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:51:28.113859Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:51:28.113936Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:28.114095Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:51:28.114165Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:51:28.115267Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:28.11541Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:28.115464Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:28.115669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-09-22T01:51:28.120461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-22T01:51:49.818269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.965876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:51:49.818387Z","caller":"traceutil/trace.go:171","msg":"trace[1829491223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:384; }","duration":"140.16315ms","start":"2026-09-22T01:51:49.678203Z","end":"2026-09-22T01:51:49.818366Z","steps":["trace[1829491223] 'range keys from in-memory index tree' (duration: 139.877064ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:12.087937Z","caller":"traceutil/trace.go:171","msg":"trace[183919075] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"245.86499ms","start":"2026-09-22T01:52:11.842035Z","end":"2026-09-22T01:52:12.0879Z","steps":["trace[183919075] 'process raft request' (duration: 162.390783ms)","trace[183919075] 'compare' (duration: 83.086029ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:52:12.0886Z","caller":"traceutil/trace.go:171","msg":"trace[77847366] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"111.045673ms","start":"2026-09-22T01:52:11.977526Z","end":"2026-09-22T01:52:12.088572Z","steps":["trace[77847366] 'read index received' (duration: 26.899181ms)","trace[77847366] 'applied index is now lower than readState.Index' (duration: 84.145632ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:52:12.088746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.207226ms","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-09-22T01:52:12.088841Z","caller":"traceutil/trace.go:171","msg":"trace[713880778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"111.332899ms","start":"2026-09-22T01:52:11.977473Z","end":"2026-09-22T01:52:12.088806Z","steps":["trace[713880778] 'agreement among raft nodes before linearized reading' (duration: 111.203216ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:12.088998Z","caller":"traceutil/trace.go:171","msg":"trace[1056008037] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"231.31697ms","start":"2026-09-22T01:52:11.857371Z","end":"2026-09-22T01:52:12.088688Z","steps":["trace[1056008037] 'process raft request' (duration: 230.554673ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:13.949445Z","caller":"traceutil/trace.go:171","msg":"trace[1525713245] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"122.948065ms","start":"2026-09-22T01:52:13.826466Z","end":"2026-09-22T01:52:13.949414Z","steps":["trace[1525713245] 'process raft request' (duration: 120.89894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:29.0479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.654408ms","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-09-22T01:52:29.047997Z","caller":"traceutil/trace.go:171","msg":"trace[1620617743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"102.803411ms","start":"2026-09-22T01:52:28.945175Z","end":"2026-09-22T01:52:29.047978Z","steps":["trace[1620617743] 'range keys from in-memory index tree' (duration: 102.417073ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:46.066232Z","caller":"traceutil/trace.go:171","msg":"trace[523626700] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"185.893361ms","start":"2026-09-22T01:53:45.88031Z","end":"2026-09-22T01:53:46.066204Z","steps":["trace[523626700] 'process raft request' (duration: 185.715487ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:46.409211Z","caller":"traceutil/trace.go:171","msg":"trace[1796475779] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"137.419173ms","start":"2026-09-22T01:53:46.271721Z","end":"2026-09-22T01:53:46.40914Z","steps":["trace[1796475779] 'read index received' (duration: 83.176219ms)","trace[1796475779] 'applied index is now lower than readState.Index' (duration: 54.241784ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:53:46.409323Z","caller":"traceutil/trace.go:171","msg":"trace[441598700] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"159.96906ms","start":"2026-09-22T01:53:46.249287Z","end":"2026-09-22T01:53:46.409256Z","steps":["trace[441598700] 'process raft request' (duration: 105.699085ms)","trace[441598700] 'compare' (duration: 53.951998ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:53:46.409586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.786832ms","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-09-22T01:53:46.409695Z","caller":"traceutil/trace.go:171","msg":"trace[1520441175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1826; }","duration":"137.998886ms","start":"2026-09-22T01:53:46.271678Z","end":"2026-09-22T01:53:46.409677Z","steps":["trace[1520441175] 'agreement among raft nodes before linearized reading' (duration: 137.637268ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:54:31.770149Z","caller":"traceutil/trace.go:171","msg":"trace[417739614] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"122.808522ms","start":"2026-09-22T01:54:31.647313Z","end":"2026-09-22T01:54:31.770121Z","steps":["trace[417739614] 'process raft request' (duration: 122.623468ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:54:31.997365Z","caller":"traceutil/trace.go:171","msg":"trace[1734488751] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"227.250184ms","start":"2026-09-22T01:54:31.770082Z","end":"2026-09-22T01:54:31.997332Z","steps":["trace[1734488751] 'read index received' (duration: 214.682824ms)","trace[1734488751] 'applied index is now lower than readState.Index' (duration: 12.56589ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:54:31.997508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.083761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-22T01:54:31.997571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.256132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:54:31.997624Z","caller":"traceutil/trace.go:171","msg":"trace[581359876] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2128; }","duration":"244.264014ms","start":"2026-09-22T01:54:31.753335Z","end":"2026-09-22T01:54:31.997599Z","steps":["trace[581359876] 'agreement among raft nodes before linearized reading' (duration: 244.06971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:54:31.997715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.34633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-22T01:54:31.9978Z","caller":"traceutil/trace.go:171","msg":"trace[52474641] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:2128; }","duration":"306.493885ms","start":"2026-09-22T01:54:31.691288Z","end":"2026-09-22T01:54:31.997782Z","steps":["trace[52474641] 'agreement among raft nodes before linearized reading' (duration: 306.213167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:54:31.99785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:54:31.691267Z","time spent":"306.571116ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.93\" "} {"level":"warn","ts":"2026-09-22T01:54:31.997534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.161321ms","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-09-22T01:54:31.997973Z","caller":"traceutil/trace.go:171","msg":"trace[1224680560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2128; }","duration":"240.633413ms","start":"2026-09-22T01:54:31.75732Z","end":"2026-09-22T01:54:31.997954Z","steps":["trace[1224680560] 'agreement among raft nodes before linearized reading' (duration: 240.07808ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:54:31.997627Z","caller":"traceutil/trace.go:171","msg":"trace[1230236277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2128; }","duration":"226.310203ms","start":"2026-09-22T01:54:31.7713Z","end":"2026-09-22T01:54:31.99761Z","steps":["trace[1230236277] 'agreement among raft nodes before linearized reading' (duration: 226.229311ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:54:31.997628Z","caller":"traceutil/trace.go:171","msg":"trace[1957122145] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"311.285301ms","start":"2026-09-22T01:54:31.686314Z","end":"2026-09-22T01:54:31.9976Z","steps":["trace[1957122145] 'process raft request' (duration: 298.556817ms)","trace[1957122145] 'compare' (duration: 12.168401ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:54:31.998583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:54:31.6863Z","time spent":"311.816933ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:56:04.858681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.080981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:56:04.858811Z","caller":"traceutil/trace.go:171","msg":"trace[345707889] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"245.656956ms","start":"2026-09-22T01:56:04.613136Z","end":"2026-09-22T01:56:04.858793Z","steps":["trace[345707889] 'process raft request' (duration: 104.194297ms)","trace[345707889] 'compare' (duration: 140.798955ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:12.355662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.709859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-22T01:59:12.355697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.173574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:59:12.355824Z","caller":"traceutil/trace.go:171","msg":"trace[1411290069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4015; }","duration":"103.936614ms","start":"2026-09-22T01:59:12.251862Z","end":"2026-09-22T01:59:12.355799Z","steps":["trace[1411290069] 'range keys from in-memory index tree' (duration: 103.516265ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:12.35585Z","caller":"traceutil/trace.go:171","msg":"trace[383373390] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4015; }","duration":"266.331278ms","start":"2026-09-22T01:59:12.089474Z","end":"2026-09-22T01:59:12.355805Z","steps":["trace[383373390] 'range keys from in-memory index tree' (duration: 266.103643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:12.35585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.84941ms","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-09-22T01:59:12.355976Z","caller":"traceutil/trace.go:171","msg":"trace[215472271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4015; }","duration":"112.009203ms","start":"2026-09-22T01:59:12.243949Z","end":"2026-09-22T01:59:12.355958Z","steps":["trace[215472271] 'range keys from in-memory index tree' (duration: 111.715877ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:41.090004Z","caller":"traceutil/trace.go:171","msg":"trace[998626280] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"117.546297ms","start":"2026-09-22T02:00:40.972437Z","end":"2026-09-22T02:00:41.089983Z","steps":["trace[998626280] 'process raft request' (duration: 117.418504ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:41.116322Z","caller":"traceutil/trace.go:171","msg":"trace[1968554548] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"137.056841ms","start":"2026-09-22T02:00:40.979241Z","end":"2026-09-22T02:00:41.116298Z","steps":["trace[1968554548] 'process raft request' (duration: 136.918748ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:42.519931Z","caller":"traceutil/trace.go:171","msg":"trace[1996571101] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"155.802719ms","start":"2026-09-22T02:00:42.364088Z","end":"2026-09-22T02:00:42.51989Z","steps":["trace[1996571101] 'process raft request' (duration: 148.724722ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:42.519896Z","caller":"traceutil/trace.go:171","msg":"trace[1034350727] linearizableReadLoop","detail":"{readStateIndex:5415; appliedIndex:5414; }","duration":"152.847283ms","start":"2026-09-22T02:00:42.366998Z","end":"2026-09-22T02:00:42.519845Z","steps":["trace[1034350727] 'read index received' (duration: 145.793656ms)","trace[1034350727] 'applied index is now lower than readState.Index' (duration: 7.052087ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:00:42.520253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.205791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-22T02:00:42.520336Z","caller":"traceutil/trace.go:171","msg":"trace[633665508] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5222; }","duration":"153.323843ms","start":"2026-09-22T02:00:42.366993Z","end":"2026-09-22T02:00:42.520316Z","steps":["trace[633665508] 'agreement among raft nodes before linearized reading' (duration: 153.014086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:42.520646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.254851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786889"} {"level":"info","ts":"2026-09-22T02:00:42.520752Z","caller":"traceutil/trace.go:171","msg":"trace[1858020051] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5222; }","duration":"122.393064ms","start":"2026-09-22T02:00:42.398314Z","end":"2026-09-22T02:00:42.520707Z","steps":["trace[1858020051] 'agreement among raft nodes before linearized reading' (duration: 121.839582ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:46.193193Z","caller":"traceutil/trace.go:171","msg":"trace[846103498] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"107.096274ms","start":"2026-09-22T02:00:46.086055Z","end":"2026-09-22T02:00:46.193152Z","steps":["trace[846103498] 'process raft request' (duration: 21.039589ms)","trace[846103498] 'compare' (duration: 85.896701ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:00:56.848977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.303257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:00:56.849153Z","caller":"traceutil/trace.go:171","msg":"trace[989223767] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"214.649898ms","start":"2026-09-22T02:00:56.63448Z","end":"2026-09-22T02:00:56.84913Z","steps":["trace[989223767] 'process raft request' (duration: 37.106416ms)","trace[989223767] 'compare' (duration: 177.117853ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:56.849393Z","caller":"traceutil/trace.go:171","msg":"trace[2071050366] linearizableReadLoop","detail":"{readStateIndex:5671; appliedIndex:5670; }","duration":"170.905524ms","start":"2026-09-22T02:00:56.678479Z","end":"2026-09-22T02:00:56.849384Z","steps":["trace[2071050366] 'read index received' (duration: 35.481µs)","trace[2071050366] 'applied index is now lower than readState.Index' (duration: 170.869073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:00:56.881264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.764544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:00:56.881343Z","caller":"traceutil/trace.go:171","msg":"trace[363174926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5470; }","duration":"202.873357ms","start":"2026-09-22T02:00:56.678444Z","end":"2026-09-22T02:00:56.881317Z","steps":["trace[363174926] 'agreement among raft nodes before linearized reading' (duration: 170.962066ms)","trace[363174926] 'range keys from in-memory index tree' (duration: 31.800459ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:08.1614Z","caller":"traceutil/trace.go:171","msg":"trace[148998542] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"108.033934ms","start":"2026-09-22T02:01:08.053339Z","end":"2026-09-22T02:01:08.161373Z","steps":["trace[148998542] 'process raft request' (duration: 37.971015ms)","trace[148998542] 'compare' (duration: 69.823524ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:08.362218Z","caller":"traceutil/trace.go:171","msg":"trace[1173925894] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"127.378966ms","start":"2026-09-22T02:01:08.234777Z","end":"2026-09-22T02:01:08.362156Z","steps":["trace[1173925894] 'process raft request' (duration: 108.963885ms)","trace[1173925894] 'compare' (duration: 18.156255ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:28.273757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-09-22T02:01:28.353294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"77.71375ms","hash":2084274876,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":32272384,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T02:01:28.353367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2084274876,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-09-22T02:06:28.284735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5878} {"level":"info","ts":"2026-09-22T02:06:28.373723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5878,"took":"85.318228ms","hash":37703525,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":25591808,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-22T02:06:28.373773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":37703525,"revision":5878,"compact-revision":2957} {"level":"info","ts":"2026-09-22T02:07:18.857544Z","caller":"traceutil/trace.go:171","msg":"trace[1568386970] linearizableReadLoop","detail":"{readStateIndex:8806; appliedIndex:8805; }","duration":"209.135465ms","start":"2026-09-22T02:07:18.648372Z","end":"2026-09-22T02:07:18.857507Z","steps":["trace[1568386970] 'read index received' (duration: 208.854549ms)","trace[1568386970] 'applied index is now lower than readState.Index' (duration: 278.806µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:07:18.857638Z","caller":"traceutil/trace.go:171","msg":"trace[264001457] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"258.677718ms","start":"2026-09-22T02:07:18.598924Z","end":"2026-09-22T02:07:18.857602Z","steps":["trace[264001457] 'process raft request' (duration: 258.35042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:18.857886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.492724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"warn","ts":"2026-09-22T02:07:18.857936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.62549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-22T02:07:18.857948Z","caller":"traceutil/trace.go:171","msg":"trace[33789650] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8508; }","duration":"209.634637ms","start":"2026-09-22T02:07:18.648294Z","end":"2026-09-22T02:07:18.857928Z","steps":["trace[33789650] 'agreement among raft nodes before linearized reading' (duration: 209.395331ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:18.857984Z","caller":"traceutil/trace.go:171","msg":"trace[2053842141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8508; }","duration":"206.751313ms","start":"2026-09-22T02:07:18.651217Z","end":"2026-09-22T02:07:18.857969Z","steps":["trace[2053842141] 'agreement among raft nodes before linearized reading' (duration: 206.508387ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:19.001738Z","caller":"traceutil/trace.go:171","msg":"trace[876325421] linearizableReadLoop","detail":"{readStateIndex:8807; appliedIndex:8806; }","duration":"138.912311ms","start":"2026-09-22T02:07:18.862797Z","end":"2026-09-22T02:07:19.001709Z","steps":["trace[876325421] 'read index received' (duration: 126.287961ms)","trace[876325421] 'applied index is now lower than readState.Index' (duration: 12.62304ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:07:19.001847Z","caller":"traceutil/trace.go:171","msg":"trace[2057098475] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"139.133426ms","start":"2026-09-22T02:07:18.862671Z","end":"2026-09-22T02:07:19.001804Z","steps":["trace[2057098475] 'process raft request' (duration: 126.488545ms)","trace[2057098475] 'compare' (duration: 12.303474ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:07:19.002018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.923187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-22T02:07:19.002003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.204558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bc8137a3-909f-4f8f-94a2-d3b37bef7b74\" ","response":"range_response_count:1 size:1293"} {"level":"info","ts":"2026-09-22T02:07:19.002065Z","caller":"traceutil/trace.go:171","msg":"trace[163805950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8509; }","duration":"110.007618ms","start":"2026-09-22T02:07:18.892044Z","end":"2026-09-22T02:07:19.002052Z","steps":["trace[163805950] 'agreement among raft nodes before linearized reading' (duration: 109.830614ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:19.002098Z","caller":"traceutil/trace.go:171","msg":"trace[461345510] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bc8137a3-909f-4f8f-94a2-d3b37bef7b74; range_end:; response_count:1; response_revision:8509; }","duration":"139.30459ms","start":"2026-09-22T02:07:18.862765Z","end":"2026-09-22T02:07:19.002069Z","steps":["trace[461345510] 'agreement among raft nodes before linearized reading' (duration: 139.104336ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:31.085078Z","caller":"traceutil/trace.go:171","msg":"trace[161320904] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"181.672013ms","start":"2026-09-22T02:07:30.903371Z","end":"2026-09-22T02:07:31.085043Z","steps":["trace[161320904] 'process raft request' (duration: 181.431478ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:31.559366Z","caller":"traceutil/trace.go:171","msg":"trace[1775997565] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"147.740629ms","start":"2026-09-22T02:07:31.411604Z","end":"2026-09-22T02:07:31.559345Z","steps":["trace[1775997565] 'process raft request' (duration: 147.599026ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:00.907042Z","caller":"traceutil/trace.go:171","msg":"trace[1632535272] transaction","detail":"{read_only:false; response_revision:8787; number_of_response:1; }","duration":"151.682426ms","start":"2026-09-22T02:08:00.755337Z","end":"2026-09-22T02:08:00.907019Z","steps":["trace[1632535272] 'process raft request' (duration: 151.493332ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:03.055211Z","caller":"traceutil/trace.go:171","msg":"trace[359089725] linearizableReadLoop","detail":"{readStateIndex:9108; appliedIndex:9107; }","duration":"133.818748ms","start":"2026-09-22T02:08:02.921361Z","end":"2026-09-22T02:08:03.05518Z","steps":["trace[359089725] 'read index received' (duration: 61.988349ms)","trace[359089725] 'applied index is now lower than readState.Index' (duration: 71.829009ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:08:03.055346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.957791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:08:03.055382Z","caller":"traceutil/trace.go:171","msg":"trace[183119204] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8798; }","duration":"134.038413ms","start":"2026-09-22T02:08:02.921329Z","end":"2026-09-22T02:08:03.055367Z","steps":["trace[183119204] 'agreement among raft nodes before linearized reading' (duration: 133.954482ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:03.055348Z","caller":"traceutil/trace.go:171","msg":"trace[693376955] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"139.829822ms","start":"2026-09-22T02:08:02.915473Z","end":"2026-09-22T02:08:03.055303Z","steps":["trace[693376955] 'process raft request' (duration: 67.936611ms)","trace[693376955] 'compare' (duration: 71.628845ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:08:09.448158Z","caller":"traceutil/trace.go:171","msg":"trace[1552678806] linearizableReadLoop","detail":"{readStateIndex:9144; appliedIndex:9143; }","duration":"123.711144ms","start":"2026-09-22T02:08:09.324407Z","end":"2026-09-22T02:08:09.448118Z","steps":["trace[1552678806] 'read index received' (duration: 86.584858ms)","trace[1552678806] 'applied index is now lower than readState.Index' (duration: 37.123776ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:08:09.448353Z","caller":"traceutil/trace.go:171","msg":"trace[2115941806] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"146.556142ms","start":"2026-09-22T02:08:09.301777Z","end":"2026-09-22T02:08:09.448333Z","steps":["trace[2115941806] 'process raft request' (duration: 109.272342ms)","trace[2115941806] 'compare' (duration: 36.879341ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:08:09.448616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.192584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-22T02:08:09.448628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.124848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-22T02:08:09.448613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.968971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-22T02:08:09.448701Z","caller":"traceutil/trace.go:171","msg":"trace[740522998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8833; }","duration":"124.323167ms","start":"2026-09-22T02:08:09.324362Z","end":"2026-09-22T02:08:09.448686Z","steps":["trace[740522998] 'agreement among raft nodes before linearized reading' (duration: 124.050942ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:09.448717Z","caller":"traceutil/trace.go:171","msg":"trace[1976453832] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8833; }","duration":"104.24749ms","start":"2026-09-22T02:08:09.344447Z","end":"2026-09-22T02:08:09.448695Z","steps":["trace[1976453832] 'agreement among raft nodes before linearized reading' (duration: 104.003144ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:09.44873Z","caller":"traceutil/trace.go:171","msg":"trace[1610499599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8833; }","duration":"116.161545ms","start":"2026-09-22T02:08:09.332546Z","end":"2026-09-22T02:08:09.448707Z","steps":["trace[1610499599] 'agreement among raft nodes before linearized reading' (duration: 115.884389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:08:09.448631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.475842ms","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-09-22T02:08:09.44894Z","caller":"traceutil/trace.go:171","msg":"trace[1779145397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8833; }","duration":"107.740618ms","start":"2026-09-22T02:08:09.341134Z","end":"2026-09-22T02:08:09.448875Z","steps":["trace[1779145397] 'agreement among raft nodes before linearized reading' (duration: 107.37292ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:09.586541Z","caller":"traceutil/trace.go:171","msg":"trace[2120046733] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"132.737335ms","start":"2026-09-22T02:08:09.453768Z","end":"2026-09-22T02:08:09.586505Z","steps":["trace[2120046733] 'process raft request' (duration: 106.97107ms)","trace[2120046733] 'compare' (duration: 25.61654ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:08:09.620332Z","caller":"traceutil/trace.go:171","msg":"trace[96854672] linearizableReadLoop","detail":"{readStateIndex:9147; appliedIndex:9144; }","duration":"165.858713ms","start":"2026-09-22T02:08:09.454445Z","end":"2026-09-22T02:08:09.620304Z","steps":["trace[96854672] 'read index received' (duration: 106.286846ms)","trace[96854672] 'applied index is now lower than readState.Index' (duration: 59.570437ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:08:09.620359Z","caller":"traceutil/trace.go:171","msg":"trace[583619134] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"166.000315ms","start":"2026-09-22T02:08:09.454322Z","end":"2026-09-22T02:08:09.620323Z","steps":["trace[583619134] 'process raft request' (duration: 165.897323ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:09.620459Z","caller":"traceutil/trace.go:171","msg":"trace[1970312365] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"166.547027ms","start":"2026-09-22T02:08:09.453873Z","end":"2026-09-22T02:08:09.62042Z","steps":["trace[1970312365] 'process raft request' (duration: 166.192768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:08:09.62055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.084497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:20205"} {"level":"info","ts":"2026-09-22T02:08:09.620592Z","caller":"traceutil/trace.go:171","msg":"trace[1826017941] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8837; }","duration":"166.162359ms","start":"2026-09-22T02:08:09.454416Z","end":"2026-09-22T02:08:09.620578Z","steps":["trace[1826017941] 'agreement among raft nodes before linearized reading' (duration: 165.989245ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:09.620708Z","caller":"traceutil/trace.go:171","msg":"trace[1548285530] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"164.500662ms","start":"2026-09-22T02:08:09.455899Z","end":"2026-09-22T02:08:09.6204Z","steps":["trace[1548285530] 'process raft request' (duration: 164.359019ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:15.817666Z","caller":"traceutil/trace.go:171","msg":"trace[149176477] linearizableReadLoop","detail":"{readStateIndex:9208; appliedIndex:9207; }","duration":"110.752755ms","start":"2026-09-22T02:08:15.706887Z","end":"2026-09-22T02:08:15.81764Z","steps":["trace[149176477] 'read index received' (duration: 110.637533ms)","trace[149176477] 'applied index is now lower than readState.Index' (duration: 114.312µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:08:15.817906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.99742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-22T02:08:15.817949Z","caller":"traceutil/trace.go:171","msg":"trace[1452941055] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:8895; }","duration":"111.084573ms","start":"2026-09-22T02:08:15.706851Z","end":"2026-09-22T02:08:15.817936Z","steps":["trace[1452941055] 'agreement among raft nodes before linearized reading' (duration: 110.884018ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:15.818278Z","caller":"traceutil/trace.go:171","msg":"trace[689231292] transaction","detail":"{read_only:false; response_revision:8895; number_of_response:1; }","duration":"113.868345ms","start":"2026-09-22T02:08:15.704398Z","end":"2026-09-22T02:08:15.818266Z","steps":["trace[689231292] 'process raft request' (duration: 113.113048ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:16.166796Z","caller":"traceutil/trace.go:171","msg":"trace[1096932893] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"253.532783ms","start":"2026-09-22T02:08:15.9132Z","end":"2026-09-22T02:08:16.166733Z","steps":["trace[1096932893] 'process raft request' (duration: 190.825107ms)","trace[1096932893] 'compare' (duration: 62.324737ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:08:16.166829Z","caller":"traceutil/trace.go:171","msg":"trace[1064129193] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"133.510471ms","start":"2026-09-22T02:08:16.033286Z","end":"2026-09-22T02:08:16.166796Z","steps":["trace[1064129193] 'process raft request' (duration: 133.44908ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:16.166848Z","caller":"traceutil/trace.go:171","msg":"trace[1022374899] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"245.424672ms","start":"2026-09-22T02:08:15.921396Z","end":"2026-09-22T02:08:16.166821Z","steps":["trace[1022374899] 'process raft request' (duration: 245.240898ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:16.166896Z","caller":"traceutil/trace.go:171","msg":"trace[1737385844] linearizableReadLoop","detail":"{readStateIndex:9219; appliedIndex:9217; }","duration":"181.321196ms","start":"2026-09-22T02:08:15.985562Z","end":"2026-09-22T02:08:16.166883Z","steps":["trace[1737385844] 'read index received' (duration: 118.614ms)","trace[1737385844] 'applied index is now lower than readState.Index' (duration: 62.705946ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:08:16.16792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.271948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-22T02:08:16.168014Z","caller":"traceutil/trace.go:171","msg":"trace[2115685300] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8907; }","duration":"165.401022ms","start":"2026-09-22T02:08:16.002569Z","end":"2026-09-22T02:08:16.16797Z","steps":["trace[2115685300] 'agreement among raft nodes before linearized reading' (duration: 165.181927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:08:16.169663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.538757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-09-22T02:08:16.16972Z","caller":"traceutil/trace.go:171","msg":"trace[608327048] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8907; }","duration":"197.64313ms","start":"2026-09-22T02:08:15.972062Z","end":"2026-09-22T02:08:16.169705Z","steps":["trace[608327048] 'agreement among raft nodes before linearized reading' (duration: 194.857008ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:36.107739Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c0.wal"} {"level":"info","ts":"2026-09-22T02:09:33.482631Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:09:33.490926Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-22T02:09:33.491169Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-22T02:09:39.70745Z","caller":"traceutil/trace.go:171","msg":"trace[112862197] transaction","detail":"{read_only:false; response_revision:9699; number_of_response:1; }","duration":"137.619026ms","start":"2026-09-22T02:09:39.5698Z","end":"2026-09-22T02:09:39.707419Z","steps":["trace[112862197] 'process raft request' (duration: 137.431222ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:09:39.740803Z","caller":"traceutil/trace.go:171","msg":"trace[181373882] transaction","detail":"{read_only:false; response_revision:9700; number_of_response:1; }","duration":"147.048237ms","start":"2026-09-22T02:09:39.59371Z","end":"2026-09-22T02:09:39.740758Z","steps":["trace[181373882] 'process raft request' (duration: 146.794972ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:11:28.293834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8042} {"level":"info","ts":"2026-09-22T02:11:28.360558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8042,"took":"64.572149ms","hash":2358431546,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-22T02:11:28.360661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2358431546,"revision":8042,"compact-revision":5878} {"level":"info","ts":"2026-09-22T02:13:25.128248Z","caller":"traceutil/trace.go:171","msg":"trace[882101461] transaction","detail":"{read_only:false; response_revision:11557; number_of_response:1; }","duration":"156.878431ms","start":"2026-09-22T02:13:24.971345Z","end":"2026-09-22T02:13:25.128223Z","steps":["trace[882101461] 'process raft request' (duration: 140.259358ms)","trace[882101461] 'compare' (duration: 16.072901ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:16:28.300703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10491} {"level":"info","ts":"2026-09-22T02:16:28.391364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10491,"took":"88.076897ms","hash":311304391,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":27574272,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T02:16:28.391427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":311304391,"revision":10491,"compact-revision":8042} {"level":"warn","ts":"2026-09-22T02:19:36.797123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.895784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:19:36.797288Z","caller":"traceutil/trace.go:171","msg":"trace[334521698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14063; }","duration":"119.14632ms","start":"2026-09-22T02:19:36.678112Z","end":"2026-09-22T02:19:36.797259Z","steps":["trace[334521698] 'range keys from in-memory index tree' (duration: 118.72784ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:19:55.314084Z","caller":"traceutil/trace.go:171","msg":"trace[1803078156] transaction","detail":"{read_only:false; response_revision:14173; number_of_response:1; }","duration":"346.370331ms","start":"2026-09-22T02:19:54.967685Z","end":"2026-09-22T02:19:55.314056Z","steps":["trace[1803078156] 'process raft request' (duration: 346.121026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:19:55.314257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:19:54.967646Z","time spent":"346.524875ms","remote":"127.0.0.1:52588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:19:55.360858Z","caller":"traceutil/trace.go:171","msg":"trace[1915939516] linearizableReadLoop","detail":"{readStateIndex:14658; appliedIndex:14656; }","duration":"265.678399ms","start":"2026-09-22T02:19:55.095154Z","end":"2026-09-22T02:19:55.360833Z","steps":["trace[1915939516] 'read index received' (duration: 218.6535ms)","trace[1915939516] 'applied index is now lower than readState.Index' (duration: 47.024029ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:19:55.360938Z","caller":"traceutil/trace.go:171","msg":"trace[2089125523] transaction","detail":"{read_only:false; response_revision:14174; number_of_response:1; }","duration":"380.952862ms","start":"2026-09-22T02:19:54.979939Z","end":"2026-09-22T02:19:55.360892Z","steps":["trace[2089125523] 'process raft request' (duration: 380.708407ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:19:55.360962Z","caller":"traceutil/trace.go:171","msg":"trace[117672821] transaction","detail":"{read_only:false; response_revision:14175; number_of_response:1; }","duration":"179.19542ms","start":"2026-09-22T02:19:55.181738Z","end":"2026-09-22T02:19:55.360933Z","steps":["trace[117672821] 'process raft request' (duration: 179.054907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:19:55.361129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:19:54.979909Z","time spent":"381.114246ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:19:55.361229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.998375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-22T02:19:55.361241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.088072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:19:55.361295Z","caller":"traceutil/trace.go:171","msg":"trace[11813659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14175; }","duration":"261.107168ms","start":"2026-09-22T02:19:55.10017Z","end":"2026-09-22T02:19:55.361277Z","steps":["trace[11813659] 'agreement among raft nodes before linearized reading' (duration: 260.883622ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:19:55.361344Z","caller":"traceutil/trace.go:171","msg":"trace[2028413558] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:14175; }","duration":"125.266226ms","start":"2026-09-22T02:19:55.236055Z","end":"2026-09-22T02:19:55.361321Z","steps":["trace[2028413558] 'agreement among raft nodes before linearized reading' (duration: 125.060011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:19:55.361276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.099619ms","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-09-22T02:19:55.361573Z","caller":"traceutil/trace.go:171","msg":"trace[2103662858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14175; }","duration":"266.470648ms","start":"2026-09-22T02:19:55.095088Z","end":"2026-09-22T02:19:55.361559Z","steps":["trace[2103662858] 'agreement among raft nodes before linearized reading' (duration: 265.883035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:19:55.36162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.552574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:19:55.361887Z","caller":"traceutil/trace.go:171","msg":"trace[1809400047] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:14175; }","duration":"131.138578ms","start":"2026-09-22T02:19:55.2307Z","end":"2026-09-22T02:19:55.361839Z","steps":["trace[1809400047] 'agreement among raft nodes before linearized reading' (duration: 130.413941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:06.147869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.991092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:20:06.148076Z","caller":"traceutil/trace.go:171","msg":"trace[1307167779] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14353; }","duration":"152.262418ms","start":"2026-09-22T02:20:05.995788Z","end":"2026-09-22T02:20:06.148051Z","steps":["trace[1307167779] 'count revisions from in-memory index tree' (duration: 151.880949ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:20:06.322594Z","caller":"traceutil/trace.go:171","msg":"trace[433637798] linearizableReadLoop","detail":"{readStateIndex:14841; appliedIndex:14840; }","duration":"150.824486ms","start":"2026-09-22T02:20:06.171698Z","end":"2026-09-22T02:20:06.322523Z","steps":["trace[433637798] 'read index received' (duration: 150.503459ms)","trace[433637798] 'applied index is now lower than readState.Index' (duration: 319.017µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:20:06.322704Z","caller":"traceutil/trace.go:171","msg":"trace[364070728] transaction","detail":"{read_only:false; response_revision:14354; number_of_response:1; }","duration":"166.833133ms","start":"2026-09-22T02:20:06.155819Z","end":"2026-09-22T02:20:06.322652Z","steps":["trace[364070728] 'process raft request' (duration: 166.428464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:06.322811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.074502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7rmgq\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-09-22T02:20:06.322869Z","caller":"traceutil/trace.go:171","msg":"trace[663588066] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7rmgq; range_end:; response_count:1; response_revision:14354; }","duration":"151.208825ms","start":"2026-09-22T02:20:06.171642Z","end":"2026-09-22T02:20:06.32285Z","steps":["trace[663588066] 'agreement among raft nodes before linearized reading' (duration: 151.058481ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:20:06.401247Z","caller":"traceutil/trace.go:171","msg":"trace[1912026720] transaction","detail":"{read_only:false; response_revision:14355; number_of_response:1; }","duration":"202.55874ms","start":"2026-09-22T02:20:06.198656Z","end":"2026-09-22T02:20:06.401215Z","steps":["trace[1912026720] 'process raft request' (duration: 161.645408ms)","trace[1912026720] 'compare' (duration: 40.739859ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:21:28.311495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12764} {"level":"info","ts":"2026-09-22T02:21:28.402972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12764,"took":"86.563221ms","hash":3996639680,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":30736384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:21:28.403047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3996639680,"revision":12764,"compact-revision":10491} {"level":"info","ts":"2026-09-22T02:21:48.092708Z","caller":"traceutil/trace.go:171","msg":"trace[1462357325] linearizableReadLoop","detail":"{readStateIndex:15973; appliedIndex:15972; }","duration":"254.631563ms","start":"2026-09-22T02:21:47.838029Z","end":"2026-09-22T02:21:48.092661Z","steps":["trace[1462357325] 'read index received' (duration: 254.404988ms)","trace[1462357325] 'applied index is now lower than readState.Index' (duration: 225.515µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:21:48.09296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.913869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-22T02:21:48.093008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.046286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-cl4t7\" ","response":"range_response_count:1 size:20649"} {"level":"info","ts":"2026-09-22T02:21:48.093039Z","caller":"traceutil/trace.go:171","msg":"trace[281766310] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15451; }","duration":"255.056563ms","start":"2026-09-22T02:21:47.837964Z","end":"2026-09-22T02:21:48.09302Z","steps":["trace[281766310] 'agreement among raft nodes before linearized reading' (duration: 254.858118ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:48.09305Z","caller":"traceutil/trace.go:171","msg":"trace[1918529662] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-cl4t7; range_end:; response_count:1; response_revision:15451; }","duration":"136.108197ms","start":"2026-09-22T02:21:47.956928Z","end":"2026-09-22T02:21:48.093037Z","steps":["trace[1918529662] 'agreement among raft nodes before linearized reading' (duration: 135.907113ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:48.092894Z","caller":"traceutil/trace.go:171","msg":"trace[125806034] transaction","detail":"{read_only:false; response_revision:15451; number_of_response:1; }","duration":"257.990318ms","start":"2026-09-22T02:21:47.834758Z","end":"2026-09-22T02:21:48.092749Z","steps":["trace[125806034] 'process raft request' (duration: 257.736632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:21:48.093562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.655961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-22T02:21:48.093698Z","caller":"traceutil/trace.go:171","msg":"trace[1169472107] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15451; }","duration":"128.906977ms","start":"2026-09-22T02:21:47.964733Z","end":"2026-09-22T02:21:48.09364Z","steps":["trace[1169472107] 'agreement among raft nodes before linearized reading' (duration: 128.512429ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:48.256493Z","caller":"traceutil/trace.go:171","msg":"trace[717731771] transaction","detail":"{read_only:false; response_revision:15453; number_of_response:1; }","duration":"154.426716ms","start":"2026-09-22T02:21:48.102042Z","end":"2026-09-22T02:21:48.256468Z","steps":["trace[717731771] 'process raft request' (duration: 148.533955ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:48.256576Z","caller":"traceutil/trace.go:171","msg":"trace[346682484] transaction","detail":"{read_only:false; response_revision:15454; number_of_response:1; }","duration":"154.494159ms","start":"2026-09-22T02:21:48.102069Z","end":"2026-09-22T02:21:48.256563Z","steps":["trace[346682484] 'process raft request' (duration: 154.083839ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:48.256488Z","caller":"traceutil/trace.go:171","msg":"trace[1492471758] transaction","detail":"{read_only:false; response_revision:15457; number_of_response:1; }","duration":"153.328531ms","start":"2026-09-22T02:21:48.103123Z","end":"2026-09-22T02:21:48.256451Z","steps":["trace[1492471758] 'process raft request' (duration: 153.257949ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:48.256839Z","caller":"traceutil/trace.go:171","msg":"trace[1337971129] transaction","detail":"{read_only:false; response_revision:15456; number_of_response:1; }","duration":"154.614942ms","start":"2026-09-22T02:21:48.102209Z","end":"2026-09-22T02:21:48.256824Z","steps":["trace[1337971129] 'process raft request' (duration: 154.11733ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:48.256849Z","caller":"traceutil/trace.go:171","msg":"trace[209425835] transaction","detail":"{read_only:false; response_revision:15455; number_of_response:1; }","duration":"154.622712ms","start":"2026-09-22T02:21:48.102162Z","end":"2026-09-22T02:21:48.256784Z","steps":["trace[209425835] 'process raft request' (duration: 154.11325ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:26:28.32905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15354} {"level":"info","ts":"2026-09-22T02:26:28.43281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15354,"took":"100.422073ms","hash":3912254858,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":31883264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T02:26:28.432938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3912254858,"revision":15354,"compact-revision":12764} {"level":"info","ts":"2026-09-22T02:27:28.732022Z","caller":"traceutil/trace.go:171","msg":"trace[701130492] linearizableReadLoop","detail":"{readStateIndex:18637; appliedIndex:18636; }","duration":"105.722456ms","start":"2026-09-22T02:27:28.626278Z","end":"2026-09-22T02:27:28.732Z","steps":["trace[701130492] 'read index received' (duration: 105.603303ms)","trace[701130492] 'applied index is now lower than readState.Index' (duration: 118.053µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:27:28.732354Z","caller":"traceutil/trace.go:171","msg":"trace[1035355718] transaction","detail":"{read_only:false; response_revision:18032; number_of_response:1; }","duration":"135.721787ms","start":"2026-09-22T02:27:28.596619Z","end":"2026-09-22T02:27:28.732341Z","steps":["trace[1035355718] 'process raft request' (duration: 135.184246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:27:28.732669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.372161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-09-22T02:27:28.732714Z","caller":"traceutil/trace.go:171","msg":"trace[2112818918] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:18032; }","duration":"106.451383ms","start":"2026-09-22T02:27:28.626251Z","end":"2026-09-22T02:27:28.732702Z","steps":["trace[2112818918] 'agreement among raft nodes before linearized reading' (duration: 106.30657ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:30:02.907876Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:30:02.917584Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-22T02:30:02.917684Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-22T02:31:28.335822Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17547} {"level":"info","ts":"2026-09-22T02:31:28.433344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17547,"took":"94.152034ms","hash":2243608550,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":34041856,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-22T02:31:28.433417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2243608550,"revision":17547,"compact-revision":15354} {"level":"info","ts":"2026-09-22T02:32:37.963548Z","caller":"traceutil/trace.go:171","msg":"trace[209662287] transaction","detail":"{read_only:false; response_revision:20441; number_of_response:1; }","duration":"152.103478ms","start":"2026-09-22T02:32:37.811413Z","end":"2026-09-22T02:32:37.963516Z","steps":["trace[209662287] 'process raft request' (duration: 147.758071ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:32:43.502376Z","caller":"traceutil/trace.go:171","msg":"trace[520360951] linearizableReadLoop","detail":"{readStateIndex:21156; appliedIndex:21155; }","duration":"155.80321ms","start":"2026-09-22T02:32:43.346543Z","end":"2026-09-22T02:32:43.502347Z","steps":["trace[520360951] 'read index received' (duration: 155.556105ms)","trace[520360951] 'applied index is now lower than readState.Index' (duration: 246.245µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:32:43.50265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.089796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-22T02:32:43.502754Z","caller":"traceutil/trace.go:171","msg":"trace[764627684] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20470; }","duration":"156.2435ms","start":"2026-09-22T02:32:43.346488Z","end":"2026-09-22T02:32:43.502732Z","steps":["trace[764627684] 'agreement among raft nodes before linearized reading' (duration: 156.050505ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:32:43.502479Z","caller":"traceutil/trace.go:171","msg":"trace[1937110871] transaction","detail":"{read_only:false; response_revision:20470; number_of_response:1; }","duration":"156.494766ms","start":"2026-09-22T02:32:43.345931Z","end":"2026-09-22T02:32:43.502425Z","steps":["trace[1937110871] 'process raft request' (duration: 156.2339ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:32:59.697816Z","caller":"traceutil/trace.go:171","msg":"trace[333443468] transaction","detail":"{read_only:false; response_revision:20552; number_of_response:1; }","duration":"138.76739ms","start":"2026-09-22T02:32:59.559025Z","end":"2026-09-22T02:32:59.697793Z","steps":["trace[333443468] 'process raft request' (duration: 138.629066ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:33:00.148238Z","caller":"traceutil/trace.go:171","msg":"trace[1664655021] transaction","detail":"{read_only:false; response_revision:20554; number_of_response:1; }","duration":"144.211891ms","start":"2026-09-22T02:33:00.003998Z","end":"2026-09-22T02:33:00.14821Z","steps":["trace[1664655021] 'process raft request' (duration: 144.075688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:00.422364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.171767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:33:00.422704Z","caller":"traceutil/trace.go:171","msg":"trace[1428217701] transaction","detail":"{read_only:false; response_revision:20555; number_of_response:1; }","duration":"400.435277ms","start":"2026-09-22T02:33:00.022229Z","end":"2026-09-22T02:33:00.422665Z","steps":["trace[1428217701] 'process raft request' (duration: 244.81289ms)","trace[1428217701] 'compare' (duration: 154.879001ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:33:00.422814Z","caller":"traceutil/trace.go:171","msg":"trace[420912333] linearizableReadLoop","detail":"{readStateIndex:21246; appliedIndex:21243; }","duration":"348.305252ms","start":"2026-09-22T02:33:00.074489Z","end":"2026-09-22T02:33:00.422794Z","steps":["trace[420912333] 'read index received' (duration: 73.646745ms)","trace[420912333] 'applied index is now lower than readState.Index' (duration: 274.657497ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:33:00.42286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:33:00.022204Z","time spent":"400.58901ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:33:00.422919Z","caller":"traceutil/trace.go:171","msg":"trace[1530504804] transaction","detail":"{read_only:false; response_revision:20556; number_of_response:1; }","duration":"359.483142ms","start":"2026-09-22T02:33:00.063403Z","end":"2026-09-22T02:33:00.422886Z","steps":["trace[1530504804] 'process raft request' (duration: 359.077473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:00.423202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.497324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-22T02:33:00.423141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.292159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:33:00.423247Z","caller":"traceutil/trace.go:171","msg":"trace[513074669] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20556; }","duration":"202.577425ms","start":"2026-09-22T02:33:00.220656Z","end":"2026-09-22T02:33:00.423234Z","steps":["trace[513074669] 'agreement among raft nodes before linearized reading' (duration: 202.423802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:00.423123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.823864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:33:00.423278Z","caller":"traceutil/trace.go:171","msg":"trace[166330100] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:20556; }","duration":"146.486323ms","start":"2026-09-22T02:33:00.276759Z","end":"2026-09-22T02:33:00.423245Z","steps":["trace[166330100] 'agreement among raft nodes before linearized reading' (duration: 146.305229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:00.423271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.376826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-22T02:33:00.42327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.746361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-22T02:33:00.423022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:33:00.063375Z","time spent":"359.598624ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:33:00.423424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.486959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-22T02:33:00.423468Z","caller":"traceutil/trace.go:171","msg":"trace[1174379824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20556; }","duration":"348.978337ms","start":"2026-09-22T02:33:00.074431Z","end":"2026-09-22T02:33:00.42341Z","steps":["trace[1174379824] 'agreement among raft nodes before linearized reading' (duration: 348.487896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:00.423445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.81611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-22T02:33:00.423518Z","caller":"traceutil/trace.go:171","msg":"trace[1355944354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20556; }","duration":"332.610181ms","start":"2026-09-22T02:33:00.090892Z","end":"2026-09-22T02:33:00.423503Z","steps":["trace[1355944354] 'agreement among raft nodes before linearized reading' (duration: 332.108539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:00.42357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:33:00.090878Z","time spent":"332.678974ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-22T02:33:00.423458Z","caller":"traceutil/trace.go:171","msg":"trace[1109396843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20556; }","duration":"328.493039ms","start":"2026-09-22T02:33:00.094831Z","end":"2026-09-22T02:33:00.423324Z","steps":["trace[1109396843] 'agreement among raft nodes before linearized reading' (duration: 328.215992ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:33:00.423579Z","caller":"traceutil/trace.go:171","msg":"trace[1472019818] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20556; }","duration":"322.007874ms","start":"2026-09-22T02:33:00.101535Z","end":"2026-09-22T02:33:00.423543Z","steps":["trace[1472019818] 'agreement among raft nodes before linearized reading' (duration: 321.509092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:00.423571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:33:00.074408Z","time spent":"349.142111ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T02:33:00.423659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:33:00.094822Z","time spent":"328.817937ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T02:33:00.423673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:33:00.101525Z","time spent":"322.136788ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-22T02:33:00.423295Z","caller":"traceutil/trace.go:171","msg":"trace[38281649] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:20556; }","duration":"217.083439ms","start":"2026-09-22T02:33:00.206191Z","end":"2026-09-22T02:33:00.423274Z","steps":["trace[38281649] 'agreement among raft nodes before linearized reading' (duration: 216.835664ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:36:28.344233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19973} {"level":"info","ts":"2026-09-22T02:36:28.471668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19973,"took":"123.760605ms","hash":412696345,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":30150656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T02:36:28.471744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":412696345,"revision":19973,"compact-revision":17547} {"level":"info","ts":"2026-09-22T02:36:56.94709Z","caller":"traceutil/trace.go:171","msg":"trace[92178510] linearizableReadLoop","detail":"{readStateIndex:23774; appliedIndex:23773; }","duration":"276.052088ms","start":"2026-09-22T02:36:56.670973Z","end":"2026-09-22T02:36:56.947025Z","steps":["trace[92178510] 'read index received' (duration: 221.222872ms)","trace[92178510] 'applied index is now lower than readState.Index' (duration: 54.827636ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:36:56.947147Z","caller":"traceutil/trace.go:171","msg":"trace[527320186] transaction","detail":"{read_only:false; response_revision:23012; number_of_response:1; }","duration":"298.445028ms","start":"2026-09-22T02:36:56.648654Z","end":"2026-09-22T02:36:56.947099Z","steps":["trace[527320186] 'process raft request' (duration: 243.519641ms)","trace[527320186] 'compare' (duration: 54.565539ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:36:56.947323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.098512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:36:56.947462Z","caller":"traceutil/trace.go:171","msg":"trace[1578763513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23012; }","duration":"269.268006ms","start":"2026-09-22T02:36:56.678166Z","end":"2026-09-22T02:36:56.947434Z","steps":["trace[1578763513] 'agreement among raft nodes before linearized reading' (duration: 269.077191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:36:56.947322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.310433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:36:56.947721Z","caller":"traceutil/trace.go:171","msg":"trace[978171695] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:23012; }","duration":"276.651361ms","start":"2026-09-22T02:36:56.670926Z","end":"2026-09-22T02:36:56.947578Z","steps":["trace[978171695] 'agreement among raft nodes before linearized reading' (duration: 276.285153ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:30.518243Z","caller":"traceutil/trace.go:171","msg":"trace[2006911444] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"130.178457ms","start":"2026-09-22T02:40:30.388023Z","end":"2026-09-22T02:40:30.518201Z","steps":["trace[2006911444] 'process raft request' (duration: 129.918841ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:36.354732Z","caller":"traceutil/trace.go:171","msg":"trace[1044961192] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"173.634566ms","start":"2026-09-22T02:40:36.181058Z","end":"2026-09-22T02:40:36.354693Z","steps":["trace[1044961192] 'process raft request' (duration: 173.33144ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:36.394556Z","caller":"traceutil/trace.go:171","msg":"trace[1237329357] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"194.207676ms","start":"2026-09-22T02:40:36.20031Z","end":"2026-09-22T02:40:36.394517Z","steps":["trace[1237329357] 'process raft request' (duration: 193.993291ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:36.394816Z","caller":"traceutil/trace.go:171","msg":"trace[2083219582] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"176.531701ms","start":"2026-09-22T02:40:36.21825Z","end":"2026-09-22T02:40:36.394782Z","steps":["trace[2083219582] 'process raft request' (duration: 176.217974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:40.790452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.222493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:40.790637Z","caller":"traceutil/trace.go:171","msg":"trace[1617728439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25249; }","duration":"111.51619ms","start":"2026-09-22T02:40:40.679094Z","end":"2026-09-22T02:40:40.79061Z","steps":["trace[1617728439] 'range keys from in-memory index tree' (duration: 111.112901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:41.022837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.313972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:40:41.023065Z","caller":"traceutil/trace.go:171","msg":"trace[158670394] linearizableReadLoop","detail":"{readStateIndex:26070; appliedIndex:26067; }","duration":"147.742359ms","start":"2026-09-22T02:40:40.8753Z","end":"2026-09-22T02:40:41.023042Z","steps":["trace[158670394] 'read index received' (duration: 9.569054ms)","trace[158670394] 'applied index is now lower than readState.Index' (duration: 138.172125ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:41.023271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.981934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:40:41.023324Z","caller":"traceutil/trace.go:171","msg":"trace[690128813] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25252; }","duration":"148.098157ms","start":"2026-09-22T02:40:40.875199Z","end":"2026-09-22T02:40:41.023297Z","steps":["trace[690128813] 'agreement among raft nodes before linearized reading' (duration: 147.908112ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:41.02347Z","caller":"traceutil/trace.go:171","msg":"trace[890507839] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"212.096155ms","start":"2026-09-22T02:40:40.811333Z","end":"2026-09-22T02:40:41.02343Z","steps":["trace[890507839] 'process raft request' (duration: 79.491165ms)","trace[890507839] 'compare' (duration: 131.153027ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:41.023739Z","caller":"traceutil/trace.go:171","msg":"trace[1760662692] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"153.346644ms","start":"2026-09-22T02:40:40.870369Z","end":"2026-09-22T02:40:41.023716Z","steps":["trace[1760662692] 'process raft request' (duration: 152.587797ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:41.271366Z","caller":"traceutil/trace.go:171","msg":"trace[1468799247] linearizableReadLoop","detail":"{readStateIndex:26071; appliedIndex:26070; }","duration":"225.543056ms","start":"2026-09-22T02:40:41.045795Z","end":"2026-09-22T02:40:41.271338Z","steps":["trace[1468799247] 'read index received' (duration: 205.009747ms)","trace[1468799247] 'applied index is now lower than readState.Index' (duration: 20.532309ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:41.271412Z","caller":"traceutil/trace.go:171","msg":"trace[228938716] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"231.324404ms","start":"2026-09-22T02:40:41.040041Z","end":"2026-09-22T02:40:41.271365Z","steps":["trace[228938716] 'process raft request' (duration: 210.722354ms)","trace[228938716] 'compare' (duration: 20.292423ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:41.27165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.822232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-22T02:40:41.271758Z","caller":"traceutil/trace.go:171","msg":"trace[1434627107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25253; }","duration":"226.018686ms","start":"2026-09-22T02:40:41.045719Z","end":"2026-09-22T02:40:41.271738Z","steps":["trace[1434627107] 'agreement among raft nodes before linearized reading' (duration: 225.72109ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:41.465317Z","caller":"traceutil/trace.go:171","msg":"trace[1641233622] linearizableReadLoop","detail":"{readStateIndex:26072; appliedIndex:26071; }","duration":"193.858277ms","start":"2026-09-22T02:40:41.271424Z","end":"2026-09-22T02:40:41.465283Z","steps":["trace[1641233622] 'read index received' (duration: 188.922147ms)","trace[1641233622] 'applied index is now lower than readState.Index' (duration: 4.93486ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:41.46564Z","caller":"traceutil/trace.go:171","msg":"trace[651239511] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"296.452659ms","start":"2026-09-22T02:40:41.169149Z","end":"2026-09-22T02:40:41.465602Z","steps":["trace[651239511] 'process raft request' (duration: 291.235363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:41.465785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.488178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T02:40:41.465872Z","caller":"traceutil/trace.go:171","msg":"trace[199570807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25254; }","duration":"394.654691ms","start":"2026-09-22T02:40:41.071197Z","end":"2026-09-22T02:40:41.465852Z","steps":["trace[199570807] 'agreement among raft nodes before linearized reading' (duration: 394.372625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:41.465912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:41.071167Z","time spent":"394.739323ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T02:40:41.473102Z","caller":"traceutil/trace.go:171","msg":"trace[2108980735] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"196.516329ms","start":"2026-09-22T02:40:41.276566Z","end":"2026-09-22T02:40:41.473082Z","steps":["trace[2108980735] 'process raft request' (duration: 196.330753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:41.473204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.061858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T02:40:41.473277Z","caller":"traceutil/trace.go:171","msg":"trace[854475620] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25255; }","duration":"175.15736ms","start":"2026-09-22T02:40:41.2981Z","end":"2026-09-22T02:40:41.473257Z","steps":["trace[854475620] 'agreement among raft nodes before linearized reading' (duration: 174.920894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:41.473318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.79287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:40:41.473444Z","caller":"traceutil/trace.go:171","msg":"trace[388572192] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25255; }","duration":"151.974104ms","start":"2026-09-22T02:40:41.321433Z","end":"2026-09-22T02:40:41.473407Z","steps":["trace[388572192] 'agreement among raft nodes before linearized reading' (duration: 151.730078ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:41.69411Z","caller":"traceutil/trace.go:171","msg":"trace[1488615212] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"215.706776ms","start":"2026-09-22T02:40:41.478362Z","end":"2026-09-22T02:40:41.694069Z","steps":["trace[1488615212] 'process raft request' (duration: 155.991913ms)","trace[1488615212] 'compare' (duration: 59.473297ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:41.69427Z","caller":"traceutil/trace.go:171","msg":"trace[577239286] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"167.872107ms","start":"2026-09-22T02:40:41.526361Z","end":"2026-09-22T02:40:41.694233Z","steps":["trace[577239286] 'process raft request' (duration: 167.661703ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:41.694255Z","caller":"traceutil/trace.go:171","msg":"trace[907256730] linearizableReadLoop","detail":"{readStateIndex:26076; appliedIndex:26074; }","duration":"118.100277ms","start":"2026-09-22T02:40:41.576116Z","end":"2026-09-22T02:40:41.694216Z","steps":["trace[907256730] 'read index received' (duration: 58.259251ms)","trace[907256730] 'applied index is now lower than readState.Index' (duration: 59.838266ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:41.694424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.26696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:41.694479Z","caller":"traceutil/trace.go:171","msg":"trace[1048813091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25258; }","duration":"118.409054ms","start":"2026-09-22T02:40:41.576056Z","end":"2026-09-22T02:40:41.694465Z","steps":["trace[1048813091] 'agreement among raft nodes before linearized reading' (duration: 118.26363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:42.098395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.266975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-22T02:40:42.098527Z","caller":"traceutil/trace.go:171","msg":"trace[420611004] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:25258; }","duration":"249.443429ms","start":"2026-09-22T02:40:41.849045Z","end":"2026-09-22T02:40:42.098489Z","steps":["trace[420611004] 'range keys from in-memory index tree' (duration: 249.013489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:42.099759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.847142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:40:42.099923Z","caller":"traceutil/trace.go:171","msg":"trace[1708535545] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"167.670504ms","start":"2026-09-22T02:40:41.932216Z","end":"2026-09-22T02:40:42.099886Z","steps":["trace[1708535545] 'process raft request' (duration: 10.484855ms)","trace[1708535545] 'compare' (duration: 156.667788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:42.401655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.445001ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-22T02:40:42.402568Z","caller":"traceutil/trace.go:171","msg":"trace[81995940] linearizableReadLoop","detail":"{readStateIndex:26078; appliedIndex:26077; }","duration":"295.959667ms","start":"2026-09-22T02:40:42.106588Z","end":"2026-09-22T02:40:42.402547Z","steps":["trace[81995940] 'read index received' (duration: 96.488034ms)","trace[81995940] 'applied index is now lower than readState.Index' (duration: 199.469833ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:42.402928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.330305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-22T02:40:42.403038Z","caller":"traceutil/trace.go:171","msg":"trace[1187791762] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25259; }","duration":"296.487968ms","start":"2026-09-22T02:40:42.106522Z","end":"2026-09-22T02:40:42.40301Z","steps":["trace[1187791762] 'agreement among raft nodes before linearized reading' (duration: 296.10499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:42.719947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.565409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:40:42.720053Z","caller":"traceutil/trace.go:171","msg":"trace[1021741345] linearizableReadLoop","detail":"{readStateIndex:26082; appliedIndex:26080; }","duration":"300.280065ms","start":"2026-09-22T02:40:42.419755Z","end":"2026-09-22T02:40:42.720035Z","steps":["trace[1021741345] 'read index received' (duration: 17.8833ms)","trace[1021741345] 'applied index is now lower than readState.Index' (duration: 282.395335ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:42.72014Z","caller":"traceutil/trace.go:171","msg":"trace[480236775] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"302.461342ms","start":"2026-09-22T02:40:42.417633Z","end":"2026-09-22T02:40:42.720095Z","steps":["trace[480236775] 'process raft request' (duration: 195.644888ms)","trace[480236775] 'compare' (duration: 106.099658ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:42.720238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.494349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-22T02:40:42.720259Z","caller":"traceutil/trace.go:171","msg":"trace[761162363] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25263; }","duration":"300.563361ms","start":"2026-09-22T02:40:42.419688Z","end":"2026-09-22T02:40:42.720251Z","steps":["trace[761162363] 'agreement among raft nodes before linearized reading' (duration: 300.412757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:42.720286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:42.419647Z","time spent":"300.632912ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-09-22T02:40:42.720336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:42.417599Z","time spent":"302.629426ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:40:42.730966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.243457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-09-22T02:40:42.730929Z","caller":"traceutil/trace.go:171","msg":"trace[829069498] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"269.984337ms","start":"2026-09-22T02:40:42.460883Z","end":"2026-09-22T02:40:42.730867Z","steps":["trace[829069498] 'process raft request' (duration: 269.602629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:42.731038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.320568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:40:42.731021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.715219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-09-22T02:40:42.731124Z","caller":"traceutil/trace.go:171","msg":"trace[332845487] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25264; }","duration":"104.881382ms","start":"2026-09-22T02:40:42.626215Z","end":"2026-09-22T02:40:42.731096Z","steps":["trace[332845487] 'agreement among raft nodes before linearized reading' (duration: 104.560224ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:42.731002Z","caller":"traceutil/trace.go:171","msg":"trace[1882689106] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25264; }","duration":"310.300878ms","start":"2026-09-22T02:40:42.420692Z","end":"2026-09-22T02:40:42.730993Z","steps":["trace[1882689106] 'agreement among raft nodes before linearized reading' (duration: 310.172724ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:42.731065Z","caller":"traceutil/trace.go:171","msg":"trace[1221448097] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25264; }","duration":"245.397599ms","start":"2026-09-22T02:40:42.485659Z","end":"2026-09-22T02:40:42.731057Z","steps":["trace[1221448097] 'agreement among raft nodes before linearized reading' (duration: 245.357018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:42.731243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:42.420658Z","time spent":"310.531603ms","remote":"127.0.0.1:52572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19254,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-22T02:40:43.093425Z","caller":"traceutil/trace.go:171","msg":"trace[1603137148] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"141.754436ms","start":"2026-09-22T02:40:42.951645Z","end":"2026-09-22T02:40:43.0934Z","steps":["trace[1603137148] 'process raft request' (duration: 141.583912ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:43.373863Z","caller":"traceutil/trace.go:171","msg":"trace[174133062] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"344.835039ms","start":"2026-09-22T02:40:43.028975Z","end":"2026-09-22T02:40:43.373811Z","steps":["trace[174133062] 'process raft request' (duration: 344.481221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:43.374104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:43.028944Z","time spent":"345.039203ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:40:43.374228Z","caller":"traceutil/trace.go:171","msg":"trace[277963661] linearizableReadLoop","detail":"{readStateIndex:26087; appliedIndex:26085; }","duration":"194.427541ms","start":"2026-09-22T02:40:43.179743Z","end":"2026-09-22T02:40:43.37417Z","steps":["trace[277963661] 'read index received' (duration: 193.799927ms)","trace[277963661] 'applied index is now lower than readState.Index' (duration: 624.684µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:43.374309Z","caller":"traceutil/trace.go:171","msg":"trace[1587632048] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"343.373065ms","start":"2026-09-22T02:40:43.03088Z","end":"2026-09-22T02:40:43.374253Z","steps":["trace[1587632048] 'process raft request' (duration: 342.746562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:43.374471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:43.03085Z","time spent":"343.523899ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:40:43.374508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.640968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:43.374559Z","caller":"traceutil/trace.go:171","msg":"trace[856559130] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25268; }","duration":"165.693019ms","start":"2026-09-22T02:40:43.208848Z","end":"2026-09-22T02:40:43.374541Z","steps":["trace[856559130] 'agreement among raft nodes before linearized reading' (duration: 165.592967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:43.374553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.754978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:43.374625Z","caller":"traceutil/trace.go:171","msg":"trace[251438119] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25268; }","duration":"194.86623ms","start":"2026-09-22T02:40:43.179731Z","end":"2026-09-22T02:40:43.374597Z","steps":["trace[251438119] 'agreement among raft nodes before linearized reading' (duration: 194.678006ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:43.51268Z","caller":"traceutil/trace.go:171","msg":"trace[1884958426] linearizableReadLoop","detail":"{readStateIndex:26088; appliedIndex:26087; }","duration":"129.719426ms","start":"2026-09-22T02:40:43.382908Z","end":"2026-09-22T02:40:43.512627Z","steps":["trace[1884958426] 'read index received' (duration: 128.009438ms)","trace[1884958426] 'applied index is now lower than readState.Index' (duration: 1.709108ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:43.512821Z","caller":"traceutil/trace.go:171","msg":"trace[682465360] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"133.070521ms","start":"2026-09-22T02:40:43.379741Z","end":"2026-09-22T02:40:43.512812Z","steps":["trace[682465360] 'process raft request' (duration: 131.114457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:43.513011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.073644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:43.513045Z","caller":"traceutil/trace.go:171","msg":"trace[143536557] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25269; }","duration":"130.178606ms","start":"2026-09-22T02:40:43.382855Z","end":"2026-09-22T02:40:43.513034Z","steps":["trace[143536557] 'agreement among raft nodes before linearized reading' (duration: 130.089204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:43.760982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.61883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:40:43.761159Z","caller":"traceutil/trace.go:171","msg":"trace[37658529] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"241.4463ms","start":"2026-09-22T02:40:43.519655Z","end":"2026-09-22T02:40:43.761101Z","steps":["trace[37658529] 'process raft request' (duration: 138.601195ms)","trace[37658529] 'compare' (duration: 102.453977ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:43.761566Z","caller":"traceutil/trace.go:171","msg":"trace[786233635] linearizableReadLoop","detail":"{readStateIndex:26090; appliedIndex:26088; }","duration":"234.482514ms","start":"2026-09-22T02:40:43.527068Z","end":"2026-09-22T02:40:43.761551Z","steps":["trace[786233635] 'read index received' (duration: 28.20705ms)","trace[786233635] 'applied index is now lower than readState.Index' (duration: 206.273234ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:43.762117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.016477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:43.762185Z","caller":"traceutil/trace.go:171","msg":"trace[1387384889] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25272; }","duration":"235.181911ms","start":"2026-09-22T02:40:43.526984Z","end":"2026-09-22T02:40:43.762165Z","steps":["trace[1387384889] 'agreement among raft nodes before linearized reading' (duration: 234.700539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:44.076852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.645814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:40:44.077022Z","caller":"traceutil/trace.go:171","msg":"trace[1826023303] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"309.227553ms","start":"2026-09-22T02:40:43.767764Z","end":"2026-09-22T02:40:44.076992Z","steps":["trace[1826023303] 'process raft request' (duration: 149.308503ms)","trace[1826023303] 'compare' (duration: 159.423159ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:44.077115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:43.767738Z","time spent":"309.332026ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:40:44.089733Z","caller":"traceutil/trace.go:171","msg":"trace[1712292158] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"320.101088ms","start":"2026-09-22T02:40:43.769611Z","end":"2026-09-22T02:40:44.089712Z","steps":["trace[1712292158] 'process raft request' (duration: 319.960325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:44.089844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:43.769572Z","time spent":"320.22392ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:40:45.008611Z","caller":"traceutil/trace.go:171","msg":"trace[126610181] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"215.569523ms","start":"2026-09-22T02:40:44.793005Z","end":"2026-09-22T02:40:45.008575Z","steps":["trace[126610181] 'process raft request' (duration: 215.329527ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:45.038319Z","caller":"traceutil/trace.go:171","msg":"trace[1290001986] linearizableReadLoop","detail":"{readStateIndex:26099; appliedIndex:26097; }","duration":"105.905335ms","start":"2026-09-22T02:40:44.932375Z","end":"2026-09-22T02:40:45.03828Z","steps":["trace[1290001986] 'read index received' (duration: 75.949385ms)","trace[1290001986] 'applied index is now lower than readState.Index' (duration: 29.95439ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:45.038529Z","caller":"traceutil/trace.go:171","msg":"trace[366476261] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"207.552134ms","start":"2026-09-22T02:40:44.830868Z","end":"2026-09-22T02:40:45.03842Z","steps":["trace[366476261] 'process raft request' (duration: 207.189606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:45.038574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.16011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:45.038746Z","caller":"traceutil/trace.go:171","msg":"trace[651040313] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25280; }","duration":"106.439336ms","start":"2026-09-22T02:40:44.932278Z","end":"2026-09-22T02:40:45.038717Z","steps":["trace[651040313] 'agreement among raft nodes before linearized reading' (duration: 106.209951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:45.243551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.619828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T02:40:45.243696Z","caller":"traceutil/trace.go:171","msg":"trace[2000443050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25280; }","duration":"144.774992ms","start":"2026-09-22T02:40:45.098875Z","end":"2026-09-22T02:40:45.24365Z","steps":["trace[2000443050] 'range keys from in-memory index tree' (duration: 144.382983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:45.68851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.749312ms","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:6"} {"level":"warn","ts":"2026-09-22T02:40:45.688593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.752404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T02:40:45.688682Z","caller":"traceutil/trace.go:171","msg":"trace[1250230811] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25283; }","duration":"119.934056ms","start":"2026-09-22T02:40:45.5687Z","end":"2026-09-22T02:40:45.688634Z","steps":["trace[1250230811] 'count revisions from in-memory index tree' (duration: 119.674611ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:45.688722Z","caller":"traceutil/trace.go:171","msg":"trace[1107055414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25283; }","duration":"128.914838ms","start":"2026-09-22T02:40:45.55978Z","end":"2026-09-22T02:40:45.688695Z","steps":["trace[1107055414] 'range keys from in-memory index tree' (duration: 128.493288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:45.688741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.981767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T02:40:45.688845Z","caller":"traceutil/trace.go:171","msg":"trace[776651369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25283; }","duration":"171.167411ms","start":"2026-09-22T02:40:45.517653Z","end":"2026-09-22T02:40:45.68882Z","steps":["trace[776651369] 'range keys from in-memory index tree' (duration: 170.728581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:57.135212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.552551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:40:57.13533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.030888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:40:57.135344Z","caller":"traceutil/trace.go:171","msg":"trace[2073815781] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25365; }","duration":"253.750474ms","start":"2026-09-22T02:40:56.881555Z","end":"2026-09-22T02:40:57.135305Z","steps":["trace[2073815781] 'count revisions from in-memory index tree' (duration: 253.421708ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:57.135419Z","caller":"traceutil/trace.go:171","msg":"trace[229262249] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25365; }","duration":"416.194351ms","start":"2026-09-22T02:40:56.719201Z","end":"2026-09-22T02:40:57.135396Z","steps":["trace[229262249] 'count revisions from in-memory index tree' (duration: 415.882145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:57.135462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:56.719174Z","time spent":"416.276614ms","remote":"127.0.0.1:52572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:40:57.135448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.795085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-09-22T02:40:57.13546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.556611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:57.135545Z","caller":"traceutil/trace.go:171","msg":"trace[2084928331] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25365; }","duration":"493.971278ms","start":"2026-09-22T02:40:56.641552Z","end":"2026-09-22T02:40:57.135523Z","steps":["trace[2084928331] 'range keys from in-memory index tree' (duration: 493.518998ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:57.135576Z","caller":"traceutil/trace.go:171","msg":"trace[1415006556] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:25365; }","duration":"438.745805ms","start":"2026-09-22T02:40:56.696798Z","end":"2026-09-22T02:40:57.135544Z","steps":["trace[1415006556] 'count revisions from in-memory index tree' (duration: 438.381897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:57.135702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:56.696769Z","time spent":"438.891349ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:40:57.135588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:56.641504Z","time spent":"494.074051ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-09-22T02:40:57.137593Z","caller":"traceutil/trace.go:171","msg":"trace[1484643981] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"255.962015ms","start":"2026-09-22T02:40:56.881596Z","end":"2026-09-22T02:40:57.137558Z","steps":["trace[1484643981] 'process raft request' (duration: 255.335971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:57.981709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.33159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T02:40:57.981855Z","caller":"traceutil/trace.go:171","msg":"trace[2081402279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25367; }","duration":"629.553535ms","start":"2026-09-22T02:40:57.352262Z","end":"2026-09-22T02:40:57.981815Z","steps":["trace[2081402279] 'range keys from in-memory index tree' (duration: 629.086795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:57.981918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.352234Z","time spent":"629.667158ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-22T02:40:58.024256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.645273ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-22T02:40:58.024651Z","caller":"traceutil/trace.go:171","msg":"trace[1951094390] linearizableReadLoop","detail":"{readStateIndex:26190; appliedIndex:26188; }","duration":"557.537908ms","start":"2026-09-22T02:40:57.467092Z","end":"2026-09-22T02:40:58.02463Z","steps":["trace[1951094390] 'read index received' (duration: 230.405194ms)","trace[1951094390] 'applied index is now lower than readState.Index' (duration: 327.131584ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:58.024724Z","caller":"traceutil/trace.go:171","msg":"trace[1681763412] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"561.640979ms","start":"2026-09-22T02:40:57.46296Z","end":"2026-09-22T02:40:58.024601Z","steps":["trace[1681763412] 'process raft request' (duration: 561.402264ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:58.024895Z","caller":"traceutil/trace.go:171","msg":"trace[844438919] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"554.5122ms","start":"2026-09-22T02:40:57.470369Z","end":"2026-09-22T02:40:58.024881Z","steps":["trace[844438919] 'process raft request' (duration: 554.175503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.025013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.470348Z","time spent":"554.583261ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:40:58.025002Z","caller":"traceutil/trace.go:171","msg":"trace[77694513] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"278.154139ms","start":"2026-09-22T02:40:57.746815Z","end":"2026-09-22T02:40:58.024969Z","steps":["trace[77694513] 'process raft request' (duration: 277.785431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.025096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.965607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:58.025202Z","caller":"traceutil/trace.go:171","msg":"trace[1091293059] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25370; }","duration":"558.151731ms","start":"2026-09-22T02:40:57.467023Z","end":"2026-09-22T02:40:58.025174Z","steps":["trace[1091293059] 'agreement among raft nodes before linearized reading' (duration: 557.929186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.025253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.46699Z","time spent":"558.248813ms","remote":"127.0.0.1:40210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:40:58.02545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.462921Z","time spent":"561.953896ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:40:58.400806Z","caller":"traceutil/trace.go:171","msg":"trace[1835850398] linearizableReadLoop","detail":"{readStateIndex:26194; appliedIndex:26192; }","duration":"376.078896ms","start":"2026-09-22T02:40:58.024709Z","end":"2026-09-22T02:40:58.400788Z","steps":["trace[1835850398] 'read index received' (duration: 302.301799ms)","trace[1835850398] 'applied index is now lower than readState.Index' (duration: 73.776147ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:58.400805Z","caller":"traceutil/trace.go:171","msg":"trace[972759834] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"563.542211ms","start":"2026-09-22T02:40:57.837228Z","end":"2026-09-22T02:40:58.40077Z","steps":["trace[972759834] 'process raft request' (duration: 489.769724ms)","trace[972759834] 'compare' (duration: 73.464271ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:58.400942Z","caller":"traceutil/trace.go:171","msg":"trace[895310279] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"412.034599ms","start":"2026-09-22T02:40:57.98886Z","end":"2026-09-22T02:40:58.400895Z","steps":["trace[895310279] 'process raft request' (duration: 411.790164ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:58.401008Z","caller":"traceutil/trace.go:171","msg":"trace[800628760] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"177.780509ms","start":"2026-09-22T02:40:58.223191Z","end":"2026-09-22T02:40:58.400972Z","steps":["trace[800628760] 'process raft request' (duration: 177.564824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.401047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.837191Z","time spent":"563.758336ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:40:58.40102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.666681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:58.401134Z","caller":"traceutil/trace.go:171","msg":"trace[1855665215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25373; }","duration":"721.835555ms","start":"2026-09-22T02:40:57.679275Z","end":"2026-09-22T02:40:58.40111Z","steps":["trace[1855665215] 'agreement among raft nodes before linearized reading' (duration: 721.657451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.40113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.815678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-22T02:40:58.401176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.24438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T02:40:58.401183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.988832Z","time spent":"412.212503ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:40:58.40121Z","caller":"traceutil/trace.go:171","msg":"trace[1340142894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25373; }","duration":"537.92887ms","start":"2026-09-22T02:40:57.863258Z","end":"2026-09-22T02:40:58.401187Z","steps":["trace[1340142894] 'agreement among raft nodes before linearized reading' (duration: 537.663104ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:58.401231Z","caller":"traceutil/trace.go:171","msg":"trace[1008565583] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25373; }","duration":"376.350542ms","start":"2026-09-22T02:40:58.024861Z","end":"2026-09-22T02:40:58.401211Z","steps":["trace[1008565583] 'agreement among raft nodes before linearized reading' (duration: 376.24362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.401257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.863235Z","time spent":"538.009982ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T02:40:58.401274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.604514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-22T02:40:58.401316Z","caller":"traceutil/trace.go:171","msg":"trace[1527516192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25373; }","duration":"168.678926ms","start":"2026-09-22T02:40:58.232621Z","end":"2026-09-22T02:40:58.4013Z","steps":["trace[1527516192] 'agreement among raft nodes before linearized reading' (duration: 168.500932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.401352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.324519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-22T02:40:58.401268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:58.02482Z","time spent":"376.436064ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":21,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:40:58.401387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.630728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:40:58.4014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.652064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-22T02:40:58.401398Z","caller":"traceutil/trace.go:171","msg":"trace[1557257132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25372; }","duration":"538.432021ms","start":"2026-09-22T02:40:57.862951Z","end":"2026-09-22T02:40:58.401383Z","steps":["trace[1557257132] 'agreement among raft nodes before linearized reading' (duration: 537.853678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.401393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.679248Z","time spent":"722.132702ms","remote":"127.0.0.1:52382","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-09-22T02:40:58.401435Z","caller":"traceutil/trace.go:171","msg":"trace[1581345001] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25373; }","duration":"681.671749ms","start":"2026-09-22T02:40:57.719748Z","end":"2026-09-22T02:40:58.401419Z","steps":["trace[1581345001] 'agreement among raft nodes before linearized reading' (duration: 681.591717ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:58.40143Z","caller":"traceutil/trace.go:171","msg":"trace[971918767] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25373; }","duration":"258.761707ms","start":"2026-09-22T02:40:58.14266Z","end":"2026-09-22T02:40:58.401422Z","steps":["trace[971918767] 'agreement among raft nodes before linearized reading' (duration: 258.624344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.401441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.862922Z","time spent":"538.510603ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T02:40:58.401466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.719658Z","time spent":"681.802332ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-22T02:40:58.401476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.670338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:58.401511Z","caller":"traceutil/trace.go:171","msg":"trace[876954828] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25373; }","duration":"122.723619ms","start":"2026-09-22T02:40:58.278774Z","end":"2026-09-22T02:40:58.401497Z","steps":["trace[876954828] 'agreement among raft nodes before linearized reading' (duration: 122.672308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.401023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.687027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:58.401611Z","caller":"traceutil/trace.go:171","msg":"trace[1378854975] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25373; }","duration":"595.355141ms","start":"2026-09-22T02:40:57.806236Z","end":"2026-09-22T02:40:58.401592Z","steps":["trace[1378854975] 'agreement among raft nodes before linearized reading' (duration: 594.649236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.401656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.806186Z","time spent":"595.455304ms","remote":"127.0.0.1:56310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:40:58.401816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.406222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T02:40:58.401944Z","caller":"traceutil/trace.go:171","msg":"trace[1038842268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25373; }","duration":"547.572386ms","start":"2026-09-22T02:40:57.854347Z","end":"2026-09-22T02:40:58.401919Z","steps":["trace[1038842268] 'agreement among raft nodes before linearized reading' (duration: 546.705766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:58.402066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:57.85432Z","time spent":"547.698359ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T02:40:58.742349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.364524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:40:58.742608Z","caller":"traceutil/trace.go:171","msg":"trace[43765291] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"292.462339ms","start":"2026-09-22T02:40:58.450085Z","end":"2026-09-22T02:40:58.742547Z","steps":["trace[43765291] 'process raft request' (duration: 151.793518ms)","trace[43765291] 'compare' (duration: 140.053797ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:58.742807Z","caller":"traceutil/trace.go:171","msg":"trace[30187340] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"193.535371ms","start":"2026-09-22T02:40:58.549247Z","end":"2026-09-22T02:40:58.742782Z","steps":["trace[30187340] 'process raft request' (duration: 193.48614ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:58.74303Z","caller":"traceutil/trace.go:171","msg":"trace[1451782044] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26199; }","duration":"259.886611ms","start":"2026-09-22T02:40:58.483129Z","end":"2026-09-22T02:40:58.743016Z","steps":["trace[1451782044] 'read index received' (duration: 118.770091ms)","trace[1451782044] 'applied index is now lower than readState.Index' (duration: 141.11491ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:58.743199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.048915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:40:58.743236Z","caller":"traceutil/trace.go:171","msg":"trace[1865339767] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25380; }","duration":"260.162788ms","start":"2026-09-22T02:40:58.483063Z","end":"2026-09-22T02:40:58.743226Z","steps":["trace[1865339767] 'agreement among raft nodes before linearized reading' (duration: 259.994984ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:40:58.743273Z","caller":"traceutil/trace.go:171","msg":"trace[875504546] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"202.03498ms","start":"2026-09-22T02:40:58.541223Z","end":"2026-09-22T02:40:58.743258Z","steps":["trace[875504546] 'process raft request' (duration: 201.262614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:59.272759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10595739324065811686,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T02:40:59.300157Z","caller":"traceutil/trace.go:171","msg":"trace[320176536] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"535.668089ms","start":"2026-09-22T02:40:58.76445Z","end":"2026-09-22T02:40:59.300118Z","steps":["trace[320176536] 'process raft request' (duration: 535.451414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:59.300381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:58.764426Z","time spent":"535.840383ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:40:59.601124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.952856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:40:59.601352Z","caller":"traceutil/trace.go:171","msg":"trace[1352621751] linearizableReadLoop","detail":"{readStateIndex:26204; appliedIndex:26202; }","duration":"829.286104ms","start":"2026-09-22T02:40:58.772039Z","end":"2026-09-22T02:40:59.601325Z","steps":["trace[1352621751] 'read index received' (duration: 527.831814ms)","trace[1352621751] 'applied index is now lower than readState.Index' (duration: 301.45248ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:40:59.601405Z","caller":"traceutil/trace.go:171","msg":"trace[644274405] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"456.097813ms","start":"2026-09-22T02:40:59.145264Z","end":"2026-09-22T02:40:59.601362Z","steps":["trace[644274405] 'process raft request' (duration: 336.316089ms)","trace[644274405] 'compare' (duration: 118.70877ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:40:59.601612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.145235Z","time spent":"456.262717ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:40:59.601626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.593322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-09-22T02:40:59.60167Z","caller":"traceutil/trace.go:171","msg":"trace[1219634634] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25382; }","duration":"829.685284ms","start":"2026-09-22T02:40:58.771968Z","end":"2026-09-22T02:40:59.601654Z","steps":["trace[1219634634] 'agreement among raft nodes before linearized reading' (duration: 829.50097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:40:59.601727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:58.77194Z","time spent":"829.778835ms","remote":"127.0.0.1:48778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5707,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-22T02:41:00.005385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.408848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:00.005544Z","caller":"traceutil/trace.go:171","msg":"trace[1332168071] linearizableReadLoop","detail":"{readStateIndex:26205; appliedIndex:26204; }","duration":"404.086371ms","start":"2026-09-22T02:40:59.601431Z","end":"2026-09-22T02:41:00.005517Z","steps":["trace[1332168071] 'read index received' (duration: 262.318395ms)","trace[1332168071] 'applied index is now lower than readState.Index' (duration: 141.766136ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:00.005736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.736435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:00.005707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.559568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:00.005941Z","caller":"traceutil/trace.go:171","msg":"trace[354700111] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25383; }","duration":"484.954837ms","start":"2026-09-22T02:40:59.520968Z","end":"2026-09-22T02:41:00.005922Z","steps":["trace[354700111] 'agreement among raft nodes before linearized reading' (duration: 484.557848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:00.006032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.520931Z","time spent":"485.08332ms","remote":"127.0.0.1:40064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:41:00.005793Z","caller":"traceutil/trace.go:171","msg":"trace[1169011424] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25383; }","duration":"666.905419ms","start":"2026-09-22T02:40:59.338871Z","end":"2026-09-22T02:41:00.005777Z","steps":["trace[1169011424] 'agreement among raft nodes before linearized reading' (duration: 666.751536ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:00.006077Z","caller":"traceutil/trace.go:171","msg":"trace[1130531762] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"696.584201ms","start":"2026-09-22T02:40:59.309434Z","end":"2026-09-22T02:41:00.006018Z","steps":["trace[1130531762] 'process raft request' (duration: 554.407527ms)","trace[1130531762] 'compare' (duration: 141.225363ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:00.006128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.347236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:00.005866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.475591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:00.006222Z","caller":"traceutil/trace.go:171","msg":"trace[1249660237] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25383; }","duration":"743.501339ms","start":"2026-09-22T02:40:59.262698Z","end":"2026-09-22T02:41:00.006199Z","steps":["trace[1249660237] 'agreement among raft nodes before linearized reading' (duration: 743.311325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:00.006216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.338843Z","time spent":"667.292038ms","remote":"127.0.0.1:40120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:00.006272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.262664Z","time spent":"743.595572ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:41:00.006265Z","caller":"traceutil/trace.go:171","msg":"trace[3073705] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25383; }","duration":"959.926951ms","start":"2026-09-22T02:40:59.04632Z","end":"2026-09-22T02:41:00.006247Z","steps":["trace[3073705] 'agreement among raft nodes before linearized reading' (duration: 959.499722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:00.006329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.04629Z","time spent":"960.019273ms","remote":"127.0.0.1:52748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:00.006331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.309399Z","time spent":"696.787957ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2720,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:00.007213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.284058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:00.007242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.164253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-09-22T02:41:00.007277Z","caller":"traceutil/trace.go:171","msg":"trace[1651933645] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25384; }","duration":"237.41335ms","start":"2026-09-22T02:40:59.769846Z","end":"2026-09-22T02:41:00.007259Z","steps":["trace[1651933645] 'agreement among raft nodes before linearized reading' (duration: 237.295688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:00.007255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.550418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-22T02:41:00.007249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.441043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:00.007384Z","caller":"traceutil/trace.go:171","msg":"trace[1656663479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25383; }","duration":"854.734573ms","start":"2026-09-22T02:40:59.15262Z","end":"2026-09-22T02:41:00.007355Z","steps":["trace[1656663479] 'agreement among raft nodes before linearized reading' (duration: 853.109346ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:00.007311Z","caller":"traceutil/trace.go:171","msg":"trace[1946140884] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:25384; }","duration":"395.262465ms","start":"2026-09-22T02:40:59.612012Z","end":"2026-09-22T02:41:00.007274Z","steps":["trace[1946140884] 'agreement among raft nodes before linearized reading' (duration: 395.091731ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:00.007443Z","caller":"traceutil/trace.go:171","msg":"trace[188637212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25384; }","duration":"328.675899ms","start":"2026-09-22T02:40:59.678734Z","end":"2026-09-22T02:41:00.00741Z","steps":["trace[188637212] 'agreement among raft nodes before linearized reading' (duration: 328.411903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:00.007449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.152603Z","time spent":"854.830075ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T02:41:00.007598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.611981Z","time spent":"395.599972ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":995,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-09-22T02:41:00.007712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:40:59.678715Z","time spent":"328.785681ms","remote":"127.0.0.1:52382","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-09-22T02:41:07.151653Z","caller":"traceutil/trace.go:171","msg":"trace[2013422070] linearizableReadLoop","detail":"{readStateIndex:26338; appliedIndex:26337; }","duration":"232.00088ms","start":"2026-09-22T02:41:06.919593Z","end":"2026-09-22T02:41:07.151594Z","steps":["trace[2013422070] 'read index received' (duration: 231.839496ms)","trace[2013422070] 'applied index is now lower than readState.Index' (duration: 159.904µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:07.151784Z","caller":"traceutil/trace.go:171","msg":"trace[1578007962] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"285.975665ms","start":"2026-09-22T02:41:06.865764Z","end":"2026-09-22T02:41:07.15174Z","steps":["trace[1578007962] 'process raft request' (duration: 285.598707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:07.15204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.403158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-22T02:41:07.152094Z","caller":"traceutil/trace.go:171","msg":"trace[1547077637] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25513; }","duration":"232.539221ms","start":"2026-09-22T02:41:06.919533Z","end":"2026-09-22T02:41:07.152072Z","steps":["trace[1547077637] 'agreement among raft nodes before linearized reading' (duration: 232.292526ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:07.493644Z","caller":"traceutil/trace.go:171","msg":"trace[609673673] linearizableReadLoop","detail":"{readStateIndex:26339; appliedIndex:26338; }","duration":"341.826641ms","start":"2026-09-22T02:41:07.151782Z","end":"2026-09-22T02:41:07.493608Z","steps":["trace[609673673] 'read index received' (duration: 289.512783ms)","trace[609673673] 'applied index is now lower than readState.Index' (duration: 52.311658ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:07.49384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.462449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:07.493944Z","caller":"traceutil/trace.go:171","msg":"trace[222576223] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25513; }","duration":"472.631712ms","start":"2026-09-22T02:41:07.021289Z","end":"2026-09-22T02:41:07.49392Z","steps":["trace[222576223] 'agreement among raft nodes before linearized reading' (duration: 472.446598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:07.494001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:07.021258Z","time spent":"472.731545ms","remote":"127.0.0.1:40220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:41:07.496259Z","caller":"traceutil/trace.go:171","msg":"trace[1347082232] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"336.661726ms","start":"2026-09-22T02:41:07.159552Z","end":"2026-09-22T02:41:07.496214Z","steps":["trace[1347082232] 'process raft request' (duration: 336.338419ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:07.496436Z","caller":"traceutil/trace.go:171","msg":"trace[985815477] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"335.990131ms","start":"2026-09-22T02:41:07.16043Z","end":"2026-09-22T02:41:07.49642Z","steps":["trace[985815477] 'process raft request' (duration: 335.716185ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:07.496405Z","caller":"traceutil/trace.go:171","msg":"trace[1371893795] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"336.254427ms","start":"2026-09-22T02:41:07.16009Z","end":"2026-09-22T02:41:07.496344Z","steps":["trace[1371893795] 'process raft request' (duration: 335.984071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:07.496498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:07.159523Z","time spent":"336.84346ms","remote":"127.0.0.1:52670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:07.496557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:07.160421Z","time spent":"336.064413ms","remote":"127.0.0.1:52670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:07.496631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:07.160067Z","time spent":"336.441261ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:07.496824Z","caller":"traceutil/trace.go:171","msg":"trace[509222079] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"335.990711ms","start":"2026-09-22T02:41:07.160817Z","end":"2026-09-22T02:41:07.496808Z","steps":["trace[509222079] 'process raft request' (duration: 335.556721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:07.496897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:07.160805Z","time spent":"336.055793ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:07.49697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.396106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:07.497036Z","caller":"traceutil/trace.go:171","msg":"trace[912280908] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25517; }","duration":"154.55618ms","start":"2026-09-22T02:41:07.342463Z","end":"2026-09-22T02:41:07.497019Z","steps":["trace[912280908] 'agreement among raft nodes before linearized reading' (duration: 154.392157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:07.497104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.63364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-47b51666-554f-4baf-ad0a-89fcf1ef124d\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-22T02:41:07.497158Z","caller":"traceutil/trace.go:171","msg":"trace[922843795] range","detail":"{range_begin:/registry/persistentvolumes/pvc-47b51666-554f-4baf-ad0a-89fcf1ef124d; range_end:; response_count:1; response_revision:25517; }","duration":"338.747943ms","start":"2026-09-22T02:41:07.158394Z","end":"2026-09-22T02:41:07.497142Z","steps":["trace[922843795] 'agreement among raft nodes before linearized reading' (duration: 338.400535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:07.497192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:07.158362Z","time spent":"338.822734ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-47b51666-554f-4baf-ad0a-89fcf1ef124d\" "} {"level":"warn","ts":"2026-09-22T02:41:07.497275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.490155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-22T02:41:07.49741Z","caller":"traceutil/trace.go:171","msg":"trace[600835918] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25517; }","duration":"139.688559ms","start":"2026-09-22T02:41:07.357697Z","end":"2026-09-22T02:41:07.497386Z","steps":["trace[600835918] 'agreement among raft nodes before linearized reading' (duration: 139.122376ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:28.351743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22249} {"level":"info","ts":"2026-09-22T02:41:28.467885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22249,"took":"111.791595ms","hash":4155191497,"current-db-size-bytes":57040896,"current-db-size":"57 MB","current-db-size-in-use-bytes":44908544,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-22T02:41:28.467966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4155191497,"revision":22249,"compact-revision":19973} {"level":"info","ts":"2026-09-22T02:41:29.025564Z","caller":"traceutil/trace.go:171","msg":"trace[2014800823] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"150.47897ms","start":"2026-09-22T02:41:28.875052Z","end":"2026-09-22T02:41:29.025531Z","steps":["trace[2014800823] 'process raft request' (duration: 150.266005ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:29.265331Z","caller":"traceutil/trace.go:171","msg":"trace[1142135655] linearizableReadLoop","detail":"{readStateIndex:26534; appliedIndex:26532; }","duration":"335.730475ms","start":"2026-09-22T02:41:28.929563Z","end":"2026-09-22T02:41:29.265294Z","steps":["trace[1142135655] 'read index received' (duration: 95.831389ms)","trace[1142135655] 'applied index is now lower than readState.Index' (duration: 239.897376ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:29.265424Z","caller":"traceutil/trace.go:171","msg":"trace[544544367] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"345.261628ms","start":"2026-09-22T02:41:28.920127Z","end":"2026-09-22T02:41:29.265388Z","steps":["trace[544544367] 'process raft request' (duration: 344.89372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:29.265545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.939781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T02:41:29.265614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:28.920098Z","time spent":"345.406381ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:29.265685Z","caller":"traceutil/trace.go:171","msg":"trace[56624387] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25703; }","duration":"336.156955ms","start":"2026-09-22T02:41:28.929508Z","end":"2026-09-22T02:41:29.265665Z","steps":["trace[56624387] 'agreement among raft nodes before linearized reading' (duration: 335.92487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:29.265745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:28.929491Z","time spent":"336.232227ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":31,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:41:30.40154Z","caller":"traceutil/trace.go:171","msg":"trace[879966826] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"227.9818ms","start":"2026-09-22T02:41:30.173523Z","end":"2026-09-22T02:41:30.401505Z","steps":["trace[879966826] 'process raft request' (duration: 192.913947ms)","trace[879966826] 'compare' (duration: 34.891769ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:30.564008Z","caller":"traceutil/trace.go:171","msg":"trace[339159174] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"381.860065ms","start":"2026-09-22T02:41:30.182099Z","end":"2026-09-22T02:41:30.563959Z","steps":["trace[339159174] 'process raft request' (duration: 346.615188ms)","trace[339159174] 'compare' (duration: 35.080243ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:30.564037Z","caller":"traceutil/trace.go:171","msg":"trace[588898598] linearizableReadLoop","detail":"{readStateIndex:26538; appliedIndex:26536; }","duration":"335.037989ms","start":"2026-09-22T02:41:30.228953Z","end":"2026-09-22T02:41:30.563991Z","steps":["trace[588898598] 'read index received' (duration: 137.4916ms)","trace[588898598] 'applied index is now lower than readState.Index' (duration: 197.536299ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:30.564309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:30.182067Z","time spent":"382.046049ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:30.564321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.341786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-22T02:41:30.564404Z","caller":"traceutil/trace.go:171","msg":"trace[1488066335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25708; }","duration":"335.48206ms","start":"2026-09-22T02:41:30.228903Z","end":"2026-09-22T02:41:30.564385Z","steps":["trace[1488066335] 'agreement among raft nodes before linearized reading' (duration: 335.229294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:30.564451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:30.228879Z","time spent":"335.563832ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T02:41:30.583529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.773554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-22T02:41:30.583562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.504161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-09-22T02:41:30.583649Z","caller":"traceutil/trace.go:171","msg":"trace[1715193455] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25709; }","duration":"205.908097ms","start":"2026-09-22T02:41:30.377684Z","end":"2026-09-22T02:41:30.583592Z","steps":["trace[1715193455] 'agreement among raft nodes before linearized reading' (duration: 205.651351ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:30.583674Z","caller":"traceutil/trace.go:171","msg":"trace[1117030529] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25709; }","duration":"310.635164ms","start":"2026-09-22T02:41:30.273019Z","end":"2026-09-22T02:41:30.583654Z","steps":["trace[1117030529] 'agreement among raft nodes before linearized reading' (duration: 310.332888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:30.583724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:30.272917Z","time spent":"310.791718ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3258,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-22T02:41:30.826245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.055481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:30.826378Z","caller":"traceutil/trace.go:171","msg":"trace[210454386] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26540; }","duration":"243.047536ms","start":"2026-09-22T02:41:30.583308Z","end":"2026-09-22T02:41:30.826355Z","steps":["trace[210454386] 'read index received' (duration: 130.905383ms)","trace[210454386] 'applied index is now lower than readState.Index' (duration: 112.140853ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:30.826456Z","caller":"traceutil/trace.go:171","msg":"trace[379510077] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"257.08462ms","start":"2026-09-22T02:41:30.569359Z","end":"2026-09-22T02:41:30.826444Z","steps":["trace[379510077] 'process raft request' (duration: 144.730462ms)","trace[379510077] 'compare' (duration: 111.870338ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:30.826859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.033073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T02:41:30.826891Z","caller":"traceutil/trace.go:171","msg":"trace[843616451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25710; }","duration":"246.137766ms","start":"2026-09-22T02:41:30.580743Z","end":"2026-09-22T02:41:30.826881Z","steps":["trace[843616451] 'agreement among raft nodes before linearized reading' (duration: 245.993823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:30.827083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.268144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T02:41:30.827121Z","caller":"traceutil/trace.go:171","msg":"trace[1362121832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25710; }","duration":"253.327205ms","start":"2026-09-22T02:41:30.573784Z","end":"2026-09-22T02:41:30.827111Z","steps":["trace[1362121832] 'agreement among raft nodes before linearized reading' (duration: 253.229733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:30.828462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.861428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-22T02:41:30.828553Z","caller":"traceutil/trace.go:171","msg":"trace[661418918] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25711; }","duration":"241.00266ms","start":"2026-09-22T02:41:30.587526Z","end":"2026-09-22T02:41:30.828529Z","steps":["trace[661418918] 'agreement among raft nodes before linearized reading' (duration: 240.721004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:30.82902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.245204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-09-22T02:41:30.829056Z","caller":"traceutil/trace.go:171","msg":"trace[1733594412] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25711; }","duration":"240.316595ms","start":"2026-09-22T02:41:30.58873Z","end":"2026-09-22T02:41:30.829047Z","steps":["trace[1733594412] 'agreement among raft nodes before linearized reading' (duration: 240.223013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:30.829008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.54308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-22T02:41:30.829129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.224098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-22T02:41:30.829115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.102102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:30.829183Z","caller":"traceutil/trace.go:171","msg":"trace[883092601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25711; }","duration":"152.369711ms","start":"2026-09-22T02:41:30.676797Z","end":"2026-09-22T02:41:30.829167Z","steps":["trace[883092601] 'agreement among raft nodes before linearized reading' (duration: 152.249328ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:30.829168Z","caller":"traceutil/trace.go:171","msg":"trace[324401943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25711; }","duration":"180.747544ms","start":"2026-09-22T02:41:30.648392Z","end":"2026-09-22T02:41:30.82914Z","steps":["trace[324401943] 'agreement among raft nodes before linearized reading' (duration: 180.503969ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:30.829222Z","caller":"traceutil/trace.go:171","msg":"trace[862041112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25711; }","duration":"150.267696ms","start":"2026-09-22T02:41:30.678927Z","end":"2026-09-22T02:41:30.829195Z","steps":["trace[862041112] 'agreement among raft nodes before linearized reading' (duration: 150.058741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:30.829292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.344645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T02:41:30.829319Z","caller":"traceutil/trace.go:171","msg":"trace[1506503737] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25711; }","duration":"145.393856ms","start":"2026-09-22T02:41:30.683915Z","end":"2026-09-22T02:41:30.829309Z","steps":["trace[1506503737] 'agreement among raft nodes before linearized reading' (duration: 145.327775ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:31.001429Z","caller":"traceutil/trace.go:171","msg":"trace[1599234460] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"170.098218ms","start":"2026-09-22T02:41:30.831297Z","end":"2026-09-22T02:41:31.001395Z","steps":["trace[1599234460] 'process raft request' (duration: 169.902222ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:31.002485Z","caller":"traceutil/trace.go:171","msg":"trace[1607570361] linearizableReadLoop","detail":"{readStateIndex:26545; appliedIndex:26542; }","duration":"166.481737ms","start":"2026-09-22T02:41:30.835976Z","end":"2026-09-22T02:41:31.002458Z","steps":["trace[1607570361] 'read index received' (duration: 165.27932ms)","trace[1607570361] 'applied index is now lower than readState.Index' (duration: 1.201187ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:31.002773Z","caller":"traceutil/trace.go:171","msg":"trace[792506522] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"166.216911ms","start":"2026-09-22T02:41:30.836504Z","end":"2026-09-22T02:41:31.002721Z","steps":["trace[792506522] 'process raft request' (duration: 165.918664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:31.002953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.835309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:31.003059Z","caller":"traceutil/trace.go:171","msg":"trace[2047105833] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25716; }","duration":"142.991993ms","start":"2026-09-22T02:41:30.860038Z","end":"2026-09-22T02:41:31.00303Z","steps":["trace[2047105833] 'agreement among raft nodes before linearized reading' (duration: 142.800798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:31.003097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.09575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:31.003147Z","caller":"traceutil/trace.go:171","msg":"trace[1406836196] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25716; }","duration":"167.221054ms","start":"2026-09-22T02:41:30.835907Z","end":"2026-09-22T02:41:31.003128Z","steps":["trace[1406836196] 'agreement among raft nodes before linearized reading' (duration: 167.122942ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:31.003178Z","caller":"traceutil/trace.go:171","msg":"trace[1245260146] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"168.574084ms","start":"2026-09-22T02:41:30.834592Z","end":"2026-09-22T02:41:31.003166Z","steps":["trace[1245260146] 'process raft request' (duration: 167.605942ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:31.003278Z","caller":"traceutil/trace.go:171","msg":"trace[754104481] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"166.998088ms","start":"2026-09-22T02:41:30.83626Z","end":"2026-09-22T02:41:31.003258Z","steps":["trace[754104481] 'process raft request' (duration: 166.131479ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:31.003463Z","caller":"traceutil/trace.go:171","msg":"trace[1601511268] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"167.96856ms","start":"2026-09-22T02:41:30.835472Z","end":"2026-09-22T02:41:31.003441Z","steps":["trace[1601511268] 'process raft request' (duration: 166.880456ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:31.399232Z","caller":"traceutil/trace.go:171","msg":"trace[1426435721] linearizableReadLoop","detail":"{readStateIndex:26549; appliedIndex:26548; }","duration":"105.468765ms","start":"2026-09-22T02:41:31.293732Z","end":"2026-09-22T02:41:31.399201Z","steps":["trace[1426435721] 'read index received' (duration: 105.161847ms)","trace[1426435721] 'applied index is now lower than readState.Index' (duration: 305.848µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:31.399332Z","caller":"traceutil/trace.go:171","msg":"trace[637713169] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"128.619422ms","start":"2026-09-22T02:41:31.270666Z","end":"2026-09-22T02:41:31.399286Z","steps":["trace[637713169] 'process raft request' (duration: 128.324534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:31.399448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.647969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:31.399509Z","caller":"traceutil/trace.go:171","msg":"trace[1179173227] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25718; }","duration":"105.816433ms","start":"2026-09-22T02:41:31.29368Z","end":"2026-09-22T02:41:31.399496Z","steps":["trace[1179173227] 'agreement among raft nodes before linearized reading' (duration: 105.657589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:31.679264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.953176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:31.679351Z","caller":"traceutil/trace.go:171","msg":"trace[622712506] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25718; }","duration":"184.08949ms","start":"2026-09-22T02:41:31.49524Z","end":"2026-09-22T02:41:31.67933Z","steps":["trace[622712506] 'count revisions from in-memory index tree' (duration: 183.848354ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:31.933319Z","caller":"traceutil/trace.go:171","msg":"trace[1748465466] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"174.974095ms","start":"2026-09-22T02:41:31.758306Z","end":"2026-09-22T02:41:31.93328Z","steps":["trace[1748465466] 'process raft request' (duration: 174.621808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:32.216188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.668602ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-22T02:41:35.806756Z","caller":"traceutil/trace.go:171","msg":"trace[1158277805] linearizableReadLoop","detail":"{readStateIndex:26579; appliedIndex:26578; }","duration":"270.921459ms","start":"2026-09-22T02:41:35.535798Z","end":"2026-09-22T02:41:35.806719Z","steps":["trace[1158277805] 'read index received' (duration: 270.720155ms)","trace[1158277805] 'applied index is now lower than readState.Index' (duration: 199.634µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:35.806831Z","caller":"traceutil/trace.go:171","msg":"trace[2008895107] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"343.549851ms","start":"2026-09-22T02:41:35.463243Z","end":"2026-09-22T02:41:35.806792Z","steps":["trace[2008895107] 'process raft request' (duration: 343.272825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.806972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.145304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:35.807049Z","caller":"traceutil/trace.go:171","msg":"trace[1595594702] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25747; }","duration":"271.302817ms","start":"2026-09-22T02:41:35.535727Z","end":"2026-09-22T02:41:35.80703Z","steps":["trace[1595594702] 'agreement among raft nodes before linearized reading' (duration: 271.147584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.807041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.46321Z","time spent":"343.707314ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:36.083714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.85443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:36.083929Z","caller":"traceutil/trace.go:171","msg":"trace[360731400] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"487.177197ms","start":"2026-09-22T02:41:35.596686Z","end":"2026-09-22T02:41:36.083864Z","steps":["trace[360731400] 'process raft request' (duration: 313.96657ms)","trace[360731400] 'compare' (duration: 171.922838ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:36.084037Z","caller":"traceutil/trace.go:171","msg":"trace[1124847476] linearizableReadLoop","detail":"{readStateIndex:26580; appliedIndex:26579; }","duration":"277.146118ms","start":"2026-09-22T02:41:35.806855Z","end":"2026-09-22T02:41:36.084001Z","steps":["trace[1124847476] 'read index received' (duration: 103.805198ms)","trace[1124847476] 'applied index is now lower than readState.Index' (duration: 173.33845ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:36.084106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.596648Z","time spent":"487.367972ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:36.084261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.790322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:36.0843Z","caller":"traceutil/trace.go:171","msg":"trace[567783530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25749; }","duration":"406.899995ms","start":"2026-09-22T02:41:35.677389Z","end":"2026-09-22T02:41:36.084289Z","steps":["trace[567783530] 'agreement among raft nodes before linearized reading' (duration: 406.777042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.084333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.677365Z","time spent":"406.959955ms","remote":"127.0.0.1:52382","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-09-22T02:41:36.877883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.550157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:36.877994Z","caller":"traceutil/trace.go:171","msg":"trace[1261666676] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25749; }","duration":"535.740551ms","start":"2026-09-22T02:41:36.342229Z","end":"2026-09-22T02:41:36.877969Z","steps":["trace[1261666676] 'count revisions from in-memory index tree' (duration: 535.439295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.87804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.342196Z","time spent":"535.830603ms","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:36.878204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.28016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-09-22T02:41:36.878326Z","caller":"traceutil/trace.go:171","msg":"trace[118425941] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25749; }","duration":"694.462405ms","start":"2026-09-22T02:41:36.183827Z","end":"2026-09-22T02:41:36.878289Z","steps":["trace[118425941] 'range keys from in-memory index tree' (duration: 693.948332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.878393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.1838Z","time spent":"694.573097ms","remote":"127.0.0.1:48778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5707,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-22T02:41:36.878589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.845848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:36.878809Z","caller":"traceutil/trace.go:171","msg":"trace[677813454] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"414.104606ms","start":"2026-09-22T02:41:36.464681Z","end":"2026-09-22T02:41:36.878785Z","steps":["trace[677813454] 'process raft request' (duration: 219.823168ms)","trace[677813454] 'compare' (duration: 193.407328ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:36.878963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.464648Z","time spent":"414.237848ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:37.232683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.810126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:37.233007Z","caller":"traceutil/trace.go:171","msg":"trace[609737791] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"646.270318ms","start":"2026-09-22T02:41:36.586683Z","end":"2026-09-22T02:41:37.232953Z","steps":["trace[609737791] 'process raft request' (duration: 435.046903ms)","trace[609737791] 'compare' (duration: 210.615912ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:37.23317Z","caller":"traceutil/trace.go:171","msg":"trace[1318983786] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"645.643664ms","start":"2026-09-22T02:41:36.587498Z","end":"2026-09-22T02:41:37.233141Z","steps":["trace[1318983786] 'process raft request' (duration: 645.316866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.2332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.586614Z","time spent":"646.485654ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:37.23324Z","caller":"traceutil/trace.go:171","msg":"trace[1897748095] linearizableReadLoop","detail":"{readStateIndex:26585; appliedIndex:26582; }","duration":"545.54638ms","start":"2026-09-22T02:41:36.68767Z","end":"2026-09-22T02:41:37.233216Z","steps":["trace[1897748095] 'read index received' (duration: 334.077649ms)","trace[1897748095] 'applied index is now lower than readState.Index' (duration: 211.466091ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:37.233269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.587463Z","time spent":"645.763946ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:37.233256Z","caller":"traceutil/trace.go:171","msg":"trace[84255302] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"632.080512ms","start":"2026-09-22T02:41:36.601124Z","end":"2026-09-22T02:41:37.233205Z","steps":["trace[84255302] 'process raft request' (duration: 631.749695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.233485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.601106Z","time spent":"632.247095ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:37.421182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.219682ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-09-22T02:41:37.421477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.781883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:37.421601Z","caller":"traceutil/trace.go:171","msg":"trace[149791827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25753; }","duration":"734.003987ms","start":"2026-09-22T02:41:36.68757Z","end":"2026-09-22T02:41:37.421574Z","steps":["trace[149791827] 'agreement among raft nodes before linearized reading' (duration: 545.752095ms)","trace[149791827] 'range keys from in-memory index tree' (duration: 188.090619ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:37.421675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.687542Z","time spent":"734.12132ms","remote":"127.0.0.1:52382","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-09-22T02:41:37.421904Z","caller":"traceutil/trace.go:171","msg":"trace[361513136] linearizableReadLoop","detail":"{readStateIndex:26587; appliedIndex:26585; }","duration":"188.57341ms","start":"2026-09-22T02:41:37.233286Z","end":"2026-09-22T02:41:37.421859Z","steps":["trace[361513136] 'read index received' (duration: 16.942458ms)","trace[361513136] 'applied index is now lower than readState.Index' (duration: 171.628112ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:37.421911Z","caller":"traceutil/trace.go:171","msg":"trace[277811259] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"306.492912ms","start":"2026-09-22T02:41:37.11537Z","end":"2026-09-22T02:41:37.421863Z","steps":["trace[277811259] 'process raft request' (duration: 305.94694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.422112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.115336Z","time spent":"306.676136ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:37.616676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.192827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-22T02:41:37.616669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.539474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-22T02:41:37.616727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.438544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:37.616838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.614816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:37.616879Z","caller":"traceutil/trace.go:171","msg":"trace[444284233] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25754; }","duration":"225.667488ms","start":"2026-09-22T02:41:37.39119Z","end":"2026-09-22T02:41:37.616857Z","steps":["trace[444284233] 'agreement among raft nodes before linearized reading' (duration: 30.872499ms)","trace[444284233] 'count revisions from in-memory index tree' (duration: 194.573313ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:37.616926Z","caller":"traceutil/trace.go:171","msg":"trace[1420891947] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25754; }","duration":"448.711528ms","start":"2026-09-22T02:41:37.168178Z","end":"2026-09-22T02:41:37.61689Z","steps":["trace[1420891947] 'agreement among raft nodes before linearized reading' (duration: 253.701734ms)","trace[1420891947] 'count revisions from in-memory index tree' (duration: 194.935173ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:37.616983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.168138Z","time spent":"448.832071ms","remote":"127.0.0.1:40248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:37.616999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.262735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:37.616954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.922013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-22T02:41:37.616761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.200668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-22T02:41:37.617058Z","caller":"traceutil/trace.go:171","msg":"trace[1633930528] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25754; }","duration":"582.604107ms","start":"2026-09-22T02:41:37.034445Z","end":"2026-09-22T02:41:37.61705Z","steps":["trace[1633930528] 'agreement among raft nodes before linearized reading' (duration: 387.442259ms)","trace[1633930528] 'range keys from in-memory index tree' (duration: 194.615796ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:37.617084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.034419Z","time spent":"582.656668ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-22T02:41:37.617072Z","caller":"traceutil/trace.go:171","msg":"trace[1196834424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25754; }","duration":"587.081807ms","start":"2026-09-22T02:41:37.02996Z","end":"2026-09-22T02:41:37.617042Z","steps":["trace[1196834424] 'agreement among raft nodes before linearized reading' (duration: 391.902359ms)","trace[1196834424] 'range keys from in-memory index tree' (duration: 194.854631ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:37.616743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.357561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T02:41:37.617125Z","caller":"traceutil/trace.go:171","msg":"trace[1175663103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25754; }","duration":"586.80135ms","start":"2026-09-22T02:41:37.030316Z","end":"2026-09-22T02:41:37.617117Z","steps":["trace[1175663103] 'agreement among raft nodes before linearized reading' (duration: 391.579291ms)","trace[1175663103] 'range keys from in-memory index tree' (duration: 194.659377ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:37.617131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.029933Z","time spent":"587.187389ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T02:41:37.617148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.030307Z","time spent":"586.832372ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T02:41:37.616804Z","caller":"traceutil/trace.go:171","msg":"trace[2043474987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25754; }","duration":"766.728349ms","start":"2026-09-22T02:41:36.85004Z","end":"2026-09-22T02:41:37.616769Z","steps":["trace[2043474987] 'agreement among raft nodes before linearized reading' (duration: 571.935419ms)","trace[2043474987] 'range keys from in-memory index tree' (duration: 194.449632ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:37.617024Z","caller":"traceutil/trace.go:171","msg":"trace[1577195083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25754; }","duration":"193.336716ms","start":"2026-09-22T02:41:37.423681Z","end":"2026-09-22T02:41:37.617018Z","steps":["trace[1577195083] 'range keys from in-memory index tree' (duration: 193.154602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.617131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.7317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T02:41:37.61683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.895742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-22T02:41:37.617208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.849959Z","time spent":"767.24005ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-22T02:41:37.617361Z","caller":"traceutil/trace.go:171","msg":"trace[938729562] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25754; }","duration":"634.999506ms","start":"2026-09-22T02:41:36.982336Z","end":"2026-09-22T02:41:37.617336Z","steps":["trace[938729562] 'agreement among raft nodes before linearized reading' (duration: 439.574653ms)","trace[938729562] 'count revisions from in-memory index tree' (duration: 194.746648ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:37.616736Z","caller":"traceutil/trace.go:171","msg":"trace[1522541366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25754; }","duration":"590.287789ms","start":"2026-09-22T02:41:37.026428Z","end":"2026-09-22T02:41:37.616716Z","steps":["trace[1522541366] 'agreement among raft nodes before linearized reading' (duration: 395.47459ms)","trace[1522541366] 'range keys from in-memory index tree' (duration: 194.599925ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:37.617428Z","caller":"traceutil/trace.go:171","msg":"trace[1817370739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25754; }","duration":"587.522767ms","start":"2026-09-22T02:41:37.029862Z","end":"2026-09-22T02:41:37.617385Z","steps":["trace[1817370739] 'agreement among raft nodes before linearized reading' (duration: 392.047033ms)","trace[1817370739] 'range keys from in-memory index tree' (duration: 194.79641ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:37.617512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.026416Z","time spent":"591.080637ms","remote":"127.0.0.1:52668","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-09-22T02:41:37.61755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.982312Z","time spent":"635.17858ms","remote":"127.0.0.1:52822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:37.617563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.029851Z","time spent":"587.663101ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-22T02:41:38.003686Z","caller":"traceutil/trace.go:171","msg":"trace[2084042792] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"381.791894ms","start":"2026-09-22T02:41:37.621801Z","end":"2026-09-22T02:41:38.003593Z","steps":["trace[2084042792] 'process raft request' (duration: 381.497597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.004069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.621767Z","time spent":"382.038178ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:38.438029Z","caller":"traceutil/trace.go:171","msg":"trace[166746259] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"816.031988ms","start":"2026-09-22T02:41:37.621963Z","end":"2026-09-22T02:41:38.437995Z","steps":["trace[166746259] 'process raft request' (duration: 815.791513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.43821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.62193Z","time spent":"816.185892ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:38.438391Z","caller":"traceutil/trace.go:171","msg":"trace[495802425] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"816.356966ms","start":"2026-09-22T02:41:37.621987Z","end":"2026-09-22T02:41:38.438344Z","steps":["trace[495802425] 'process raft request' (duration: 815.966897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.438598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.621948Z","time spent":"816.54342ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:38.438657Z","caller":"traceutil/trace.go:171","msg":"trace[1146165567] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"816.212463ms","start":"2026-09-22T02:41:37.622432Z","end":"2026-09-22T02:41:38.438644Z","steps":["trace[1146165567] 'process raft request' (duration: 815.775583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.438729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.622421Z","time spent":"816.265973ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:38.438755Z","caller":"traceutil/trace.go:171","msg":"trace[1655198941] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"815.944266ms","start":"2026-09-22T02:41:37.622783Z","end":"2026-09-22T02:41:38.438727Z","steps":["trace[1655198941] 'process raft request' (duration: 815.704221ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:38.438742Z","caller":"traceutil/trace.go:171","msg":"trace[989083131] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"624.897762ms","start":"2026-09-22T02:41:37.813796Z","end":"2026-09-22T02:41:38.438694Z","steps":["trace[989083131] 'process raft request' (duration: 624.80255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.438842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.622767Z","time spent":"816.032959ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:38.438899Z","caller":"traceutil/trace.go:171","msg":"trace[2129533941] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"815.748932ms","start":"2026-09-22T02:41:37.623091Z","end":"2026-09-22T02:41:38.43884Z","steps":["trace[2129533941] 'process raft request' (duration: 815.454055ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.43899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.813752Z","time spent":"625.109506ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:38.439168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.623031Z","time spent":"815.998869ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:39.059728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.196699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:39.05982Z","caller":"traceutil/trace.go:171","msg":"trace[686131236] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26594; }","duration":"589.991302ms","start":"2026-09-22T02:41:38.469814Z","end":"2026-09-22T02:41:39.059805Z","steps":["trace[686131236] 'read index received' (duration: 226.609959ms)","trace[686131236] 'applied index is now lower than readState.Index' (duration: 363.380453ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:39.059897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.090643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:39.059914Z","caller":"traceutil/trace.go:171","msg":"trace[517326734] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25762; }","duration":"590.157546ms","start":"2026-09-22T02:41:38.469747Z","end":"2026-09-22T02:41:39.059904Z","steps":["trace[517326734] 'agreement among raft nodes before linearized reading' (duration: 590.112325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.059935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.469721Z","time spent":"590.207117ms","remote":"127.0.0.1:48794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:41:39.059957Z","caller":"traceutil/trace.go:171","msg":"trace[2092008142] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"970.285202ms","start":"2026-09-22T02:41:38.089598Z","end":"2026-09-22T02:41:39.059883Z","steps":["trace[2092008142] 'process raft request' (duration: 606.858638ms)","trace[2092008142] 'compare' (duration: 362.885282ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:39.060231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.089566Z","time spent":"970.500567ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:39.103411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.518071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:39.103447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.291597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-09-22T02:41:39.103469Z","caller":"traceutil/trace.go:171","msg":"trace[1779199476] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25763; }","duration":"365.635534ms","start":"2026-09-22T02:41:38.737817Z","end":"2026-09-22T02:41:39.103452Z","steps":["trace[1779199476] 'agreement among raft nodes before linearized reading' (duration: 365.531372ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:39.103501Z","caller":"traceutil/trace.go:171","msg":"trace[405825328] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25763; }","duration":"606.4114ms","start":"2026-09-22T02:41:38.497075Z","end":"2026-09-22T02:41:39.103486Z","steps":["trace[405825328] 'agreement among raft nodes before linearized reading' (duration: 606.211135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.103466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.783854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:39.103519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.737781Z","time spent":"365.729786ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:39.103535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.497045Z","time spent":"606.483161ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-09-22T02:41:39.103552Z","caller":"traceutil/trace.go:171","msg":"trace[798829926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25763; }","duration":"424.972259ms","start":"2026-09-22T02:41:38.678559Z","end":"2026-09-22T02:41:39.103532Z","steps":["trace[798829926] 'agreement among raft nodes before linearized reading' (duration: 424.801545ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:39.103515Z","caller":"traceutil/trace.go:171","msg":"trace[1053693541] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"219.405489ms","start":"2026-09-22T02:41:38.884065Z","end":"2026-09-22T02:41:39.10347Z","steps":["trace[1053693541] 'process raft request' (duration: 219.014561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.103597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.678526Z","time spent":"425.065351ms","remote":"127.0.0.1:52382","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-09-22T02:41:39.103901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.528148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:39.103953Z","caller":"traceutil/trace.go:171","msg":"trace[2010204285] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25763; }","duration":"226.63409ms","start":"2026-09-22T02:41:38.877303Z","end":"2026-09-22T02:41:39.103937Z","steps":["trace[2010204285] 'agreement among raft nodes before linearized reading' (duration: 226.030187ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:39.366377Z","caller":"traceutil/trace.go:171","msg":"trace[1069480767] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"126.440183ms","start":"2026-09-22T02:41:39.23991Z","end":"2026-09-22T02:41:39.36635Z","steps":["trace[1069480767] 'process raft request' (duration: 126.223958ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:39.366552Z","caller":"traceutil/trace.go:171","msg":"trace[19839323] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"126.404833ms","start":"2026-09-22T02:41:39.240129Z","end":"2026-09-22T02:41:39.366534Z","steps":["trace[19839323] 'process raft request' (duration: 126.176178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.964847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.606058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:39.964976Z","caller":"traceutil/trace.go:171","msg":"trace[916418158] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25769; }","duration":"191.789161ms","start":"2026-09-22T02:41:39.77316Z","end":"2026-09-22T02:41:39.964949Z","steps":["trace[916418158] 'count revisions from in-memory index tree' (duration: 191.517425ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:55.289191Z","caller":"traceutil/trace.go:171","msg":"trace[1278467628] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"131.255109ms","start":"2026-09-22T02:41:55.157895Z","end":"2026-09-22T02:41:55.28915Z","steps":["trace[1278467628] 'process raft request' (duration: 56.970151ms)","trace[1278467628] 'compare' (duration: 74.040483ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:55.289389Z","caller":"traceutil/trace.go:171","msg":"trace[1062141442] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"121.434282ms","start":"2026-09-22T02:41:55.167908Z","end":"2026-09-22T02:41:55.289343Z","steps":["trace[1062141442] 'process raft request' (duration: 121.183296ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:55.520254Z","caller":"traceutil/trace.go:171","msg":"trace[2058887856] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"193.097381ms","start":"2026-09-22T02:41:55.327114Z","end":"2026-09-22T02:41:55.520211Z","steps":["trace[2058887856] 'process raft request' (duration: 106.402146ms)","trace[2058887856] 'compare' (duration: 86.356718ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:55.520317Z","caller":"traceutil/trace.go:171","msg":"trace[1850852864] linearizableReadLoop","detail":"{readStateIndex:26721; appliedIndex:26720; }","duration":"173.767689ms","start":"2026-09-22T02:41:55.346496Z","end":"2026-09-22T02:41:55.520264Z","steps":["trace[1850852864] 'read index received' (duration: 86.982732ms)","trace[1850852864] 'applied index is now lower than readState.Index' (duration: 86.780797ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:55.520789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.954953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:55.520848Z","caller":"traceutil/trace.go:171","msg":"trace[793004674] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25887; }","duration":"174.392463ms","start":"2026-09-22T02:41:55.34644Z","end":"2026-09-22T02:41:55.520833Z","steps":["trace[793004674] 'agreement among raft nodes before linearized reading' (duration: 173.949363ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.661725Z","caller":"traceutil/trace.go:171","msg":"trace[1485063163] linearizableReadLoop","detail":"{readStateIndex:26727; appliedIndex:26726; }","duration":"128.841796ms","start":"2026-09-22T02:41:56.532841Z","end":"2026-09-22T02:41:56.661683Z","steps":["trace[1485063163] 'read index received' (duration: 128.593451ms)","trace[1485063163] 'applied index is now lower than readState.Index' (duration: 247.095µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:56.661743Z","caller":"traceutil/trace.go:171","msg":"trace[1517321819] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"143.34058ms","start":"2026-09-22T02:41:56.518376Z","end":"2026-09-22T02:41:56.661716Z","steps":["trace[1517321819] 'process raft request' (duration: 143.127165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:56.661989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.009651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-22T02:41:56.661989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.132894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T02:41:56.662031Z","caller":"traceutil/trace.go:171","msg":"trace[594920844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25891; }","duration":"129.118434ms","start":"2026-09-22T02:41:56.532901Z","end":"2026-09-22T02:41:56.662019Z","steps":["trace[594920844] 'agreement among raft nodes before linearized reading' (duration: 128.938669ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.662039Z","caller":"traceutil/trace.go:171","msg":"trace[1042685085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25891; }","duration":"129.244437ms","start":"2026-09-22T02:41:56.532783Z","end":"2026-09-22T02:41:56.662028Z","steps":["trace[1042685085] 'agreement among raft nodes before linearized reading' (duration: 129.020501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:56.66216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.811638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-22T02:41:56.662172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.702934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T02:41:56.66221Z","caller":"traceutil/trace.go:171","msg":"trace[855679213] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25891; }","duration":"102.757415ms","start":"2026-09-22T02:41:56.55944Z","end":"2026-09-22T02:41:56.662198Z","steps":["trace[855679213] 'agreement among raft nodes before linearized reading' (duration: 102.672033ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.66221Z","caller":"traceutil/trace.go:171","msg":"trace[1969427501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25891; }","duration":"125.912102ms","start":"2026-09-22T02:41:56.536282Z","end":"2026-09-22T02:41:56.662194Z","steps":["trace[1969427501] 'agreement among raft nodes before linearized reading' (duration: 125.785679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:56.662145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.145866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:56.66228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.51666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T02:41:56.662374Z","caller":"traceutil/trace.go:171","msg":"trace[1518427272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25891; }","duration":"124.685974ms","start":"2026-09-22T02:41:56.537664Z","end":"2026-09-22T02:41:56.66235Z","steps":["trace[1518427272] 'agreement among raft nodes before linearized reading' (duration: 124.342736ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.662343Z","caller":"traceutil/trace.go:171","msg":"trace[209639543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25891; }","duration":"109.446083ms","start":"2026-09-22T02:41:56.552871Z","end":"2026-09-22T02:41:56.662317Z","steps":["trace[209639543] 'agreement among raft nodes before linearized reading' (duration: 109.079235ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.85708Z","caller":"traceutil/trace.go:171","msg":"trace[1228743183] linearizableReadLoop","detail":"{readStateIndex:26732; appliedIndex:26728; }","duration":"179.866105ms","start":"2026-09-22T02:41:56.677183Z","end":"2026-09-22T02:41:56.857049Z","steps":["trace[1228743183] 'read index received' (duration: 39.226086ms)","trace[1228743183] 'applied index is now lower than readState.Index' (duration: 140.638419ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:56.857257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.048519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:56.857316Z","caller":"traceutil/trace.go:171","msg":"trace[2059243024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25896; }","duration":"180.130021ms","start":"2026-09-22T02:41:56.677154Z","end":"2026-09-22T02:41:56.857284Z","steps":["trace[2059243024] 'agreement among raft nodes before linearized reading' (duration: 180.011569ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.857677Z","caller":"traceutil/trace.go:171","msg":"trace[1046960986] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"190.028472ms","start":"2026-09-22T02:41:56.667633Z","end":"2026-09-22T02:41:56.857662Z","steps":["trace[1046960986] 'process raft request' (duration: 189.373377ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.857711Z","caller":"traceutil/trace.go:171","msg":"trace[317882399] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"190.252558ms","start":"2026-09-22T02:41:56.667412Z","end":"2026-09-22T02:41:56.857665Z","steps":["trace[317882399] 'process raft request' (duration: 189.559752ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:56.857763Z","caller":"traceutil/trace.go:171","msg":"trace[162541522] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"190.82474ms","start":"2026-09-22T02:41:56.666893Z","end":"2026-09-22T02:41:56.857717Z","steps":["trace[162541522] 'process raft request' (duration: 168.990013ms)","trace[162541522] 'compare' (duration: 20.800374ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:56.85788Z","caller":"traceutil/trace.go:171","msg":"trace[1061616034] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"190.912872ms","start":"2026-09-22T02:41:56.666952Z","end":"2026-09-22T02:41:56.857865Z","steps":["trace[1061616034] 'process raft request' (duration: 189.963451ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:12.262475Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068ed.wal"} {"level":"info","ts":"2026-09-22T02:42:19.09092Z","caller":"traceutil/trace.go:171","msg":"trace[499046506] linearizableReadLoop","detail":"{readStateIndex:27046; appliedIndex:27045; }","duration":"101.072996ms","start":"2026-09-22T02:42:18.989804Z","end":"2026-09-22T02:42:19.090877Z","steps":["trace[499046506] 'read index received' (duration: 29.411286ms)","trace[499046506] 'applied index is now lower than readState.Index' (duration: 71.65996ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:19.091339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.517367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T02:42:19.091509Z","caller":"traceutil/trace.go:171","msg":"trace[497644646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26203; }","duration":"101.748152ms","start":"2026-09-22T02:42:18.98974Z","end":"2026-09-22T02:42:19.091488Z","steps":["trace[497644646] 'agreement among raft nodes before linearized reading' (duration: 101.400844ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:19.09172Z","caller":"traceutil/trace.go:171","msg":"trace[478066285] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"221.71204ms","start":"2026-09-22T02:42:18.86996Z","end":"2026-09-22T02:42:19.091672Z","steps":["trace[478066285] 'process raft request' (duration: 149.322793ms)","trace[478066285] 'compare' (duration: 71.414915ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:19.554993Z","caller":"traceutil/trace.go:171","msg":"trace[2137744440] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"458.155839ms","start":"2026-09-22T02:42:19.096776Z","end":"2026-09-22T02:42:19.554932Z","steps":["trace[2137744440] 'process raft request' (duration: 447.050641ms)","trace[2137744440] 'compare' (duration: 10.563455ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:19.555117Z","caller":"traceutil/trace.go:171","msg":"trace[3161137] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"148.146397ms","start":"2026-09-22T02:42:19.406938Z","end":"2026-09-22T02:42:19.555085Z","steps":["trace[3161137] 'process raft request' (duration: 148.100556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:19.555188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.096748Z","time spent":"458.337903ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:19.555282Z","caller":"traceutil/trace.go:171","msg":"trace[1532908281] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"457.613888ms","start":"2026-09-22T02:42:19.097624Z","end":"2026-09-22T02:42:19.555238Z","steps":["trace[1532908281] 'process raft request' (duration: 457.311741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:19.556165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.097614Z","time spent":"458.293023ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:19.555356Z","caller":"traceutil/trace.go:171","msg":"trace[475671275] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"458.496597ms","start":"2026-09-22T02:42:19.096848Z","end":"2026-09-22T02:42:19.555345Z","steps":["trace[475671275] 'process raft request' (duration: 457.969326ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:19.555432Z","caller":"traceutil/trace.go:171","msg":"trace[1544561853] linearizableReadLoop","detail":"{readStateIndex:27050; appliedIndex:27047; }","duration":"323.555404ms","start":"2026-09-22T02:42:19.231864Z","end":"2026-09-22T02:42:19.555419Z","steps":["trace[1544561853] 'read index received' (duration: 312.048317ms)","trace[1544561853] 'applied index is now lower than readState.Index' (duration: 11.505967ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:19.555535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.640776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:42:19.55647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.096821Z","time spent":"459.529589ms","remote":"127.0.0.1:52668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:19.556603Z","caller":"traceutil/trace.go:171","msg":"trace[76763857] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26209; }","duration":"324.72324ms","start":"2026-09-22T02:42:19.231848Z","end":"2026-09-22T02:42:19.556571Z","steps":["trace[76763857] 'agreement among raft nodes before linearized reading' (duration: 323.608576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:19.556639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.000032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:19.556686Z","caller":"traceutil/trace.go:171","msg":"trace[1360676013] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26209; }","duration":"134.134175ms","start":"2026-09-22T02:42:19.422538Z","end":"2026-09-22T02:42:19.556672Z","steps":["trace[1360676013] 'agreement among raft nodes before linearized reading' (duration: 134.047373ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:19.555539Z","caller":"traceutil/trace.go:171","msg":"trace[280176423] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"283.797177ms","start":"2026-09-22T02:42:19.271699Z","end":"2026-09-22T02:42:19.555497Z","steps":["trace[280176423] 'process raft request' (duration: 283.294515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:19.556686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.23182Z","time spent":"324.840492ms","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:42:19.749804Z","caller":"traceutil/trace.go:171","msg":"trace[1991742486] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"113.612856ms","start":"2026-09-22T02:42:19.636143Z","end":"2026-09-22T02:42:19.749756Z","steps":["trace[1991742486] 'process raft request' (duration: 69.563923ms)","trace[1991742486] 'compare' (duration: 43.752107ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:19.797435Z","caller":"traceutil/trace.go:171","msg":"trace[735258882] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"131.412044ms","start":"2026-09-22T02:42:19.66599Z","end":"2026-09-22T02:42:19.797402Z","steps":["trace[735258882] 'process raft request' (duration: 131.300691ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:19.797705Z","caller":"traceutil/trace.go:171","msg":"trace[456028522] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"128.770565ms","start":"2026-09-22T02:42:19.668912Z","end":"2026-09-22T02:42:19.797683Z","steps":["trace[456028522] 'process raft request' (duration: 128.435288ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:19.797512Z","caller":"traceutil/trace.go:171","msg":"trace[870012229] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"132.099209ms","start":"2026-09-22T02:42:19.665321Z","end":"2026-09-22T02:42:19.797421Z","steps":["trace[870012229] 'process raft request' (duration: 131.775722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:45:08.397159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.968586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:45:08.397311Z","caller":"traceutil/trace.go:171","msg":"trace[1115400410] transaction","detail":"{read_only:false; response_revision:27824; number_of_response:1; }","duration":"131.611142ms","start":"2026-09-22T02:45:08.265675Z","end":"2026-09-22T02:45:08.397286Z","steps":["trace[1115400410] 'process raft request' (duration: 18.409891ms)","trace[1115400410] 'compare' (duration: 112.817282ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:46:28.359044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25694} {"level":"info","ts":"2026-09-22T02:46:28.533899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25694,"took":"169.471947ms","hash":4059620940,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":35893248,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-22T02:46:28.533994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4059620940,"revision":25694,"compact-revision":22249} {"level":"info","ts":"2026-09-22T02:47:39.426698Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:47:39.434865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-22T02:47:39.435014Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-22T02:51:28.365362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28559} {"level":"info","ts":"2026-09-22T02:51:28.509658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28559,"took":"141.134024ms","hash":948102302,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:51:28.509726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":948102302,"revision":28559,"compact-revision":25694} {"level":"info","ts":"2026-09-22T02:56:28.371605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30789} {"level":"info","ts":"2026-09-22T02:56:28.504841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30789,"took":"128.823767ms","hash":824025267,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":31305728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:56:28.504919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":824025267,"revision":30789,"compact-revision":28559} {"level":"info","ts":"2026-09-22T03:01:28.382287Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33043} {"level":"info","ts":"2026-09-22T03:01:28.54365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33043,"took":"151.222447ms","hash":2202722231,"current-db-size-bytes":60203008,"current-db-size":"60 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T03:01:28.543713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2202722231,"revision":33043,"compact-revision":30789} {"level":"info","ts":"2026-09-22T03:06:28.40183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35369} {"level":"info","ts":"2026-09-22T03:06:28.551965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35369,"took":"146.033881ms","hash":640129105,"current-db-size-bytes":60203008,"current-db-size":"60 MB","current-db-size-in-use-bytes":32153600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T03:06:28.552037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":640129105,"revision":35369,"compact-revision":33043} {"level":"info","ts":"2026-09-22T03:08:59.548217Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T03:08:59.562514Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-22T03:08:59.562638Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-22T03:11:28.410547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37663} {"level":"info","ts":"2026-09-22T03:11:28.553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37663,"took":"137.171723ms","hash":1651777343,"current-db-size-bytes":60203008,"current-db-size":"60 MB","current-db-size-in-use-bytes":32505856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T03:11:28.553086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1651777343,"revision":37663,"compact-revision":35369} {"level":"info","ts":"2026-09-22T03:16:28.418708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39935} {"level":"info","ts":"2026-09-22T03:16:28.554864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39935,"took":"132.645613ms","hash":2405832792,"current-db-size-bytes":60203008,"current-db-size":"60 MB","current-db-size-in-use-bytes":30728192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T03:16:28.554929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2405832792,"revision":39935,"compact-revision":37663}