{"level":"warn","ts":"2026-05-09T16:54:24.292715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:54:24.292811Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-05-09T16:54:24.292889Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:54:24.292909Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-09T16:54:24.292933Z","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-05-09T16:54:24.293726Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-09T16:54:24.293821Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-05-09T16:54:24.297731Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.628073ms"} {"level":"info","ts":"2026-05-09T16:54:24.309495Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-09T16:54:24.309672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:54:24.309707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-09T16:54:24.309743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:54:24.309762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-09T16:54:24.309829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-09T16:54:24.317158Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:54:24.318587Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:54:24.319809Z","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-05-09T16:54:24.321937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:54:24.322128Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:54:24.322323Z","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-05-09T16:54:24.322574Z","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-05-09T16:54:24.322637Z","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-05-09T16:54:24.324954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-09T16:54:24.32529Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-09T16:54:24.327482Z","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-05-09T16:54:24.327579Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-09T16:54:24.327651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-09T16:54:24.327716Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:54:24.327761Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:54:24.81063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:54:24.810742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:54:24.8108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-09T16:54:24.810834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:54:24.810847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-09T16:54:24.810873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-09T16:54:24.81089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-09T16:54:24.812152Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:54:24.813233Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:54:24.813347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:54:24.813451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:54:24.813799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:54:24.813844Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:54:24.813998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:54:24.814194Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:54:24.814287Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:54:24.818111Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-05-09T16:54:24.818284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:54:41.640236Z","caller":"traceutil/trace.go:171","msg":"trace[1312991056] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"252.58142ms","start":"2026-05-09T16:54:41.387618Z","end":"2026-05-09T16:54:41.640199Z","steps":["trace[1312991056] 'process raft request' (duration: 252.382366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:54:42.091159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.74828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-09T16:54:42.091303Z","caller":"traceutil/trace.go:171","msg":"trace[622186670] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:373; }","duration":"122.980974ms","start":"2026-05-09T16:54:41.968288Z","end":"2026-05-09T16:54:42.091269Z","steps":["trace[622186670] 'range keys from in-memory index tree' (duration: 122.602777ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:55:01.57797Z","caller":"traceutil/trace.go:171","msg":"trace[399098873] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"108.3356ms","start":"2026-05-09T16:55:01.469599Z","end":"2026-05-09T16:55:01.577934Z","steps":["trace[399098873] 'process raft request' (duration: 107.660768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:02.065859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.482014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:55:02.06599Z","caller":"traceutil/trace.go:171","msg":"trace[2122600095] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"290.386724ms","start":"2026-05-09T16:55:01.775586Z","end":"2026-05-09T16:55:02.065972Z","steps":["trace[2122600095] 'read index received' (duration: 182.072303ms)","trace[2122600095] 'applied index is now lower than readState.Index' (duration: 108.31303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:55:02.066171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.208094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8sbcv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-09T16:55:02.066153Z","caller":"traceutil/trace.go:171","msg":"trace[684240891] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"432.407273ms","start":"2026-05-09T16:55:01.633585Z","end":"2026-05-09T16:55:02.065992Z","steps":["trace[684240891] 'process raft request' (duration: 324.092233ms)","trace[684240891] 'compare' (duration: 107.336571ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:55:02.066232Z","caller":"traceutil/trace.go:171","msg":"trace[1394861226] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8sbcv; range_end:; response_count:1; response_revision:578; }","duration":"169.320636ms","start":"2026-05-09T16:55:01.896896Z","end":"2026-05-09T16:55:02.066217Z","steps":["trace[1394861226] 'agreement among raft nodes before linearized reading' (duration: 169.185824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:02.066171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.589867ms","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-05-09T16:55:02.066399Z","caller":"traceutil/trace.go:171","msg":"trace[2126317683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"290.872632ms","start":"2026-05-09T16:55:01.775506Z","end":"2026-05-09T16:55:02.066379Z","steps":["trace[2126317683] 'agreement among raft nodes before linearized reading' (duration: 290.556256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:55:02.066312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:55:01.633562Z","time spent":"432.678078ms","remote":"127.0.0.1:41376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T16:55:24.682797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.372943ms","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-05-09T16:55:24.682888Z","caller":"traceutil/trace.go:171","msg":"trace[1386848377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"191.514586ms","start":"2026-05-09T16:55:24.491354Z","end":"2026-05-09T16:55:24.682869Z","steps":["trace[1386848377] 'range keys from in-memory index tree' (duration: 191.183919ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:55:51.509159Z","caller":"traceutil/trace.go:171","msg":"trace[51147245] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"116.205372ms","start":"2026-05-09T16:55:51.392924Z","end":"2026-05-09T16:55:51.509129Z","steps":["trace[51147245] 'process raft request' (duration: 116.015759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:56:40.114417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.717561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c83796a-9b87-4527-8676-a1facb8e22e7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-09T16:56:40.114516Z","caller":"traceutil/trace.go:171","msg":"trace[1924226265] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8c83796a-9b87-4527-8676-a1facb8e22e7; range_end:; response_count:1; response_revision:1839; }","duration":"119.882334ms","start":"2026-05-09T16:56:39.994613Z","end":"2026-05-09T16:56:40.114496Z","steps":["trace[1924226265] 'range keys from in-memory index tree' (duration: 119.500386ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:56:40.573929Z","caller":"traceutil/trace.go:171","msg":"trace[444794127] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"165.128106ms","start":"2026-05-09T16:56:40.408772Z","end":"2026-05-09T16:56:40.5739Z","steps":["trace[444794127] 'process raft request' (duration: 164.945053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:56:53.160613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.350606ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-09T16:56:56.793178Z","caller":"traceutil/trace.go:171","msg":"trace[2141988266] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"108.262525ms","start":"2026-05-09T16:56:56.684888Z","end":"2026-05-09T16:56:56.79315Z","steps":["trace[2141988266] 'process raft request' (duration: 108.098932ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:56:56.794877Z","caller":"traceutil/trace.go:171","msg":"trace[1910424074] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"107.025411ms","start":"2026-05-09T16:56:56.687818Z","end":"2026-05-09T16:56:56.794843Z","steps":["trace[1910424074] 'process raft request' (duration: 106.809197ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:29.561896Z","caller":"traceutil/trace.go:171","msg":"trace[147511806] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"106.60838ms","start":"2026-05-09T16:57:29.455255Z","end":"2026-05-09T16:57:29.561864Z","steps":["trace[147511806] 'process raft request' (duration: 106.386315ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:29.619245Z","caller":"traceutil/trace.go:171","msg":"trace[309571910] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"163.382303ms","start":"2026-05-09T16:57:29.455824Z","end":"2026-05-09T16:57:29.619206Z","steps":["trace[309571910] 'process raft request' (duration: 163.002275ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:29.619333Z","caller":"traceutil/trace.go:171","msg":"trace[70247394] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"141.700077ms","start":"2026-05-09T16:57:29.477608Z","end":"2026-05-09T16:57:29.619308Z","steps":["trace[70247394] 'process raft request' (duration: 141.472562ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:29.619337Z","caller":"traceutil/trace.go:171","msg":"trace[1618786675] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"112.586909ms","start":"2026-05-09T16:57:29.506736Z","end":"2026-05-09T16:57:29.619322Z","steps":["trace[1618786675] 'process raft request' (duration: 112.444456ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:30.109701Z","caller":"traceutil/trace.go:171","msg":"trace[2062199474] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"102.511497ms","start":"2026-05-09T16:57:30.007152Z","end":"2026-05-09T16:57:30.109664Z","steps":["trace[2062199474] 'process raft request' (duration: 102.259672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:57:35.713608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.112691ms","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-05-09T16:57:35.713817Z","caller":"traceutil/trace.go:171","msg":"trace[9590936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2187; }","duration":"126.384287ms","start":"2026-05-09T16:57:35.587406Z","end":"2026-05-09T16:57:35.71379Z","steps":["trace[9590936] 'range keys from in-memory index tree' (duration: 125.802714ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:35.958081Z","caller":"traceutil/trace.go:171","msg":"trace[1084498373] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2278; }","duration":"233.886744ms","start":"2026-05-09T16:57:35.723678Z","end":"2026-05-09T16:57:35.957564Z","steps":["trace[1084498373] 'read index received' (duration: 233.001455ms)","trace[1084498373] 'applied index is now lower than readState.Index' (duration: 882.189µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:57:35.958496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.356222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T16:57:35.958639Z","caller":"traceutil/trace.go:171","msg":"trace[1944009903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2190; }","duration":"288.534885ms","start":"2026-05-09T16:57:35.670057Z","end":"2026-05-09T16:57:35.958592Z","steps":["trace[1944009903] 'agreement among raft nodes before linearized reading' (duration: 287.692159ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:35.957513Z","caller":"traceutil/trace.go:171","msg":"trace[1590564164] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"311.069401ms","start":"2026-05-09T16:57:35.646375Z","end":"2026-05-09T16:57:35.957444Z","steps":["trace[1590564164] 'process raft request' (duration: 310.186502ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:35.957747Z","caller":"traceutil/trace.go:171","msg":"trace[1493826251] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"238.756453ms","start":"2026-05-09T16:57:35.718969Z","end":"2026-05-09T16:57:35.957725Z","steps":["trace[1493826251] 'process raft request' (duration: 237.781663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:57:35.963396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:57:35.646332Z","time spent":"316.371447ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:57:36.006286Z","caller":"traceutil/trace.go:171","msg":"trace[814687246] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"278.168306ms","start":"2026-05-09T16:57:35.728083Z","end":"2026-05-09T16:57:36.006252Z","steps":["trace[814687246] 'process raft request' (duration: 255.403927ms)","trace[814687246] 'compare' (duration: 22.610886ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:58:44.451883Z","caller":"traceutil/trace.go:171","msg":"trace[1136097803] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"135.420122ms","start":"2026-05-09T16:58:44.316428Z","end":"2026-05-09T16:58:44.451848Z","steps":["trace[1136097803] 'process raft request' (duration: 107.1832ms)","trace[1136097803] 'compare' (duration: 27.721532ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:44.833995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.78373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:58:44.83413Z","caller":"traceutil/trace.go:171","msg":"trace[1452304417] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"228.441325ms","start":"2026-05-09T16:58:44.605667Z","end":"2026-05-09T16:58:44.834108Z","steps":["trace[1452304417] 'process raft request' (duration: 125.4334ms)","trace[1452304417] 'compare' (duration: 102.624017ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:01:26.246187Z","caller":"traceutil/trace.go:171","msg":"trace[881381097] linearizableReadLoop","detail":"{readStateIndex:3959; appliedIndex:3958; }","duration":"120.337071ms","start":"2026-05-09T17:01:26.125828Z","end":"2026-05-09T17:01:26.246165Z","steps":["trace[881381097] 'read index received' (duration: 120.176097ms)","trace[881381097] 'applied index is now lower than readState.Index' (duration: 159.804µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:01:26.246263Z","caller":"traceutil/trace.go:171","msg":"trace[1667441686] transaction","detail":"{read_only:false; response_revision:3808; number_of_response:1; }","duration":"168.696731ms","start":"2026-05-09T17:01:26.077518Z","end":"2026-05-09T17:01:26.246215Z","steps":["trace[1667441686] 'process raft request' (duration: 168.496857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:01:26.246444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.597915ms","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":"info","ts":"2026-05-09T17:01:26.246495Z","caller":"traceutil/trace.go:171","msg":"trace[1438051622] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3808; }","duration":"120.709309ms","start":"2026-05-09T17:01:26.125771Z","end":"2026-05-09T17:01:26.24648Z","steps":["trace[1438051622] 'agreement among raft nodes before linearized reading' (duration: 120.518324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:01:26.248686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.37895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:01:26.248809Z","caller":"traceutil/trace.go:171","msg":"trace[1690594061] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:3809; }","duration":"118.563404ms","start":"2026-05-09T17:01:26.130215Z","end":"2026-05-09T17:01:26.248778Z","steps":["trace[1690594061] 'agreement among raft nodes before linearized reading' (duration: 118.299468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:02:52.866142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.097505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T17:02:52.866302Z","caller":"traceutil/trace.go:171","msg":"trace[1005892254] linearizableReadLoop","detail":"{readStateIndex:5205; appliedIndex:5204; }","duration":"145.403903ms","start":"2026-05-09T17:02:52.720873Z","end":"2026-05-09T17:02:52.866277Z","steps":["trace[1005892254] 'read index received' (duration: 11.910779ms)","trace[1005892254] 'applied index is now lower than readState.Index' (duration: 133.491554ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:02:52.866344Z","caller":"traceutil/trace.go:171","msg":"trace[1395978632] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"145.966434ms","start":"2026-05-09T17:02:52.720326Z","end":"2026-05-09T17:02:52.866292Z","steps":["trace[1395978632] 'process raft request' (duration: 12.584613ms)","trace[1395978632] 'compare' (duration: 132.870161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:02:52.866555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.670488ms","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-05-09T17:02:52.8666Z","caller":"traceutil/trace.go:171","msg":"trace[1566719887] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5008; }","duration":"145.742899ms","start":"2026-05-09T17:02:52.720845Z","end":"2026-05-09T17:02:52.866588Z","steps":["trace[1566719887] 'agreement among raft nodes before linearized reading' (duration: 145.493364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:03:02.769607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.277336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T17:03:02.769737Z","caller":"traceutil/trace.go:171","msg":"trace[1617289904] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"144.857562ms","start":"2026-05-09T17:03:02.624859Z","end":"2026-05-09T17:03:02.769717Z","steps":["trace[1617289904] 'process raft request' (duration: 37.378092ms)","trace[1617289904] 'compare' (duration: 107.165033ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:03:03.297807Z","caller":"traceutil/trace.go:171","msg":"trace[417126832] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5420; }","duration":"180.209422ms","start":"2026-05-09T17:03:03.117558Z","end":"2026-05-09T17:03:03.297767Z","steps":["trace[417126832] 'read index received' (duration: 180.10172ms)","trace[417126832] 'applied index is now lower than readState.Index' (duration: 106.232µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:03:03.297878Z","caller":"traceutil/trace.go:171","msg":"trace[1367027868] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"225.31429ms","start":"2026-05-09T17:03:03.072518Z","end":"2026-05-09T17:03:03.297832Z","steps":["trace[1367027868] 'process raft request' (duration: 225.041664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:03:03.298317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.715743ms","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-05-09T17:03:03.29843Z","caller":"traceutil/trace.go:171","msg":"trace[59883985] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5216; }","duration":"180.900396ms","start":"2026-05-09T17:03:03.117508Z","end":"2026-05-09T17:03:03.298408Z","steps":["trace[59883985] 'agreement among raft nodes before linearized reading' (duration: 180.406976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:03:03.304098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.629761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rwwsm\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-09T17:03:03.304194Z","caller":"traceutil/trace.go:171","msg":"trace[1396518913] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rwwsm; range_end:; response_count:1; response_revision:5217; }","duration":"182.758374ms","start":"2026-05-09T17:03:03.121416Z","end":"2026-05-09T17:03:03.304174Z","steps":["trace[1396518913] 'agreement among raft nodes before linearized reading' (duration: 182.5293ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:10.973013Z","caller":"traceutil/trace.go:171","msg":"trace[1922036013] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5587; }","duration":"232.260429ms","start":"2026-05-09T17:03:10.740727Z","end":"2026-05-09T17:03:10.972987Z","steps":["trace[1922036013] 'read index received' (duration: 232.113076ms)","trace[1922036013] 'applied index is now lower than readState.Index' (duration: 146.033µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:03:10.973147Z","caller":"traceutil/trace.go:171","msg":"trace[1076744630] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"252.376113ms","start":"2026-05-09T17:03:10.720723Z","end":"2026-05-09T17:03:10.973099Z","steps":["trace[1076744630] 'process raft request' (duration: 252.080627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:03:10.97335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.580905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f995ea9-6ad8-4a41-8e22-abb1898c344f\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-05-09T17:03:10.97349Z","caller":"traceutil/trace.go:171","msg":"trace[450310414] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f995ea9-6ad8-4a41-8e22-abb1898c344f; range_end:; response_count:1; response_revision:5379; }","duration":"232.778209ms","start":"2026-05-09T17:03:10.740669Z","end":"2026-05-09T17:03:10.973448Z","steps":["trace[450310414] 'agreement among raft nodes before linearized reading' (duration: 232.491603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:03:11.046996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.771763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:03:11.047115Z","caller":"traceutil/trace.go:171","msg":"trace[611803181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5380; }","duration":"168.951516ms","start":"2026-05-09T17:03:10.87813Z","end":"2026-05-09T17:03:11.047082Z","steps":["trace[611803181] 'agreement among raft nodes before linearized reading' (duration: 168.749692ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:11.047468Z","caller":"traceutil/trace.go:171","msg":"trace[1843545431] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"244.011685ms","start":"2026-05-09T17:03:10.803419Z","end":"2026-05-09T17:03:11.047431Z","steps":["trace[1843545431] 'process raft request' (duration: 213.585783ms)","trace[1843545431] 'compare' (duration: 29.558115ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:03:11.047557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.311045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-09T17:03:11.047659Z","caller":"traceutil/trace.go:171","msg":"trace[778843130] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5380; }","duration":"274.446737ms","start":"2026-05-09T17:03:10.773185Z","end":"2026-05-09T17:03:11.047632Z","steps":["trace[778843130] 'agreement among raft nodes before linearized reading' (duration: 274.254173ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:11.239024Z","caller":"traceutil/trace.go:171","msg":"trace[1958079921] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"101.455271ms","start":"2026-05-09T17:03:11.137513Z","end":"2026-05-09T17:03:11.238968Z","steps":["trace[1958079921] 'process raft request' (duration: 34.023604ms)","trace[1958079921] 'compare' (duration: 67.235872ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:03:11.23907Z","caller":"traceutil/trace.go:171","msg":"trace[1816045800] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"101.084873ms","start":"2026-05-09T17:03:11.137955Z","end":"2026-05-09T17:03:11.23904Z","steps":["trace[1816045800] 'process raft request' (duration: 100.94002ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:11.239096Z","caller":"traceutil/trace.go:171","msg":"trace[1945725776] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"100.206055ms","start":"2026-05-09T17:03:11.138872Z","end":"2026-05-09T17:03:11.239078Z","steps":["trace[1945725776] 'process raft request' (duration: 100.137973ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:11.239178Z","caller":"traceutil/trace.go:171","msg":"trace[1493214293] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"100.740716ms","start":"2026-05-09T17:03:11.138381Z","end":"2026-05-09T17:03:11.239122Z","steps":["trace[1493214293] 'process raft request' (duration: 100.565832ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:11.239145Z","caller":"traceutil/trace.go:171","msg":"trace[1333737146] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"100.267896ms","start":"2026-05-09T17:03:11.138837Z","end":"2026-05-09T17:03:11.239104Z","steps":["trace[1333737146] 'process raft request' (duration: 100.143064ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:24.845852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-05-09T17:04:24.918025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"70.337386ms","hash":3519557465,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":33681408,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T17:04:24.918097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3519557465,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-05-09T17:09:24.859149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6061} {"level":"info","ts":"2026-05-09T17:09:24.952544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6061,"took":"91.480528ms","hash":3023055109,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":21606400,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-09T17:09:24.952616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3023055109,"revision":6061,"compact-revision":2979} {"level":"info","ts":"2026-05-09T17:09:29.899253Z","caller":"traceutil/trace.go:171","msg":"trace[1104250927] transaction","detail":"{read_only:false; response_revision:8281; number_of_response:1; }","duration":"114.348506ms","start":"2026-05-09T17:09:29.78487Z","end":"2026-05-09T17:09:29.899219Z","steps":["trace[1104250927] 'process raft request' (duration: 114.128912ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:10.618482Z","caller":"traceutil/trace.go:171","msg":"trace[403141469] linearizableReadLoop","detail":"{readStateIndex:8860; appliedIndex:8859; }","duration":"121.464125ms","start":"2026-05-09T17:10:10.496989Z","end":"2026-05-09T17:10:10.618453Z","steps":["trace[403141469] 'read index received' (duration: 121.223781ms)","trace[403141469] 'applied index is now lower than readState.Index' (duration: 239.324µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:10.618835Z","caller":"traceutil/trace.go:171","msg":"trace[837771173] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"147.68622ms","start":"2026-05-09T17:10:10.471137Z","end":"2026-05-09T17:10:10.618824Z","steps":["trace[837771173] 'process raft request' (duration: 147.099048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:10.618883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.836532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:10:10.618957Z","caller":"traceutil/trace.go:171","msg":"trace[1085314626] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8550; }","duration":"121.982035ms","start":"2026-05-09T17:10:10.496953Z","end":"2026-05-09T17:10:10.618935Z","steps":["trace[1085314626] 'agreement among raft nodes before linearized reading' (duration: 121.818592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:10.619014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.051296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T17:10:10.619098Z","caller":"traceutil/trace.go:171","msg":"trace[1481963794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8550; }","duration":"119.18871ms","start":"2026-05-09T17:10:10.499893Z","end":"2026-05-09T17:10:10.619081Z","steps":["trace[1481963794] 'agreement among raft nodes before linearized reading' (duration: 118.920964ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:10.805413Z","caller":"traceutil/trace.go:171","msg":"trace[592958327] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8860; }","duration":"172.007095ms","start":"2026-05-09T17:10:10.633384Z","end":"2026-05-09T17:10:10.805392Z","steps":["trace[592958327] 'read index received' (duration: 169.319782ms)","trace[592958327] 'applied index is now lower than readState.Index' (duration: 2.685793ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:10.805469Z","caller":"traceutil/trace.go:171","msg":"trace[813540969] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"176.551335ms","start":"2026-05-09T17:10:10.628869Z","end":"2026-05-09T17:10:10.80542Z","steps":["trace[813540969] 'process raft request' (duration: 173.81419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:10.805554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.149648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:10:10.805589Z","caller":"traceutil/trace.go:171","msg":"trace[1274070213] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8551; }","duration":"172.24991ms","start":"2026-05-09T17:10:10.63333Z","end":"2026-05-09T17:10:10.805579Z","steps":["trace[1274070213] 'agreement among raft nodes before linearized reading' (duration: 172.165328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:10.812414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.950095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-09T17:10:10.812475Z","caller":"traceutil/trace.go:171","msg":"trace[269306531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8552; }","duration":"122.038836ms","start":"2026-05-09T17:10:10.690421Z","end":"2026-05-09T17:10:10.81246Z","steps":["trace[269306531] 'agreement among raft nodes before linearized reading' (duration: 121.787381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:10.812872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.007757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-68bd8bff99-stbts.18adf4c65ea59d2d\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-05-09T17:10:10.812919Z","caller":"traceutil/trace.go:171","msg":"trace[467644680] range","detail":"{range_begin:/registry/events/openstack/barbican-api-68bd8bff99-stbts.18adf4c65ea59d2d; range_end:; response_count:1; response_revision:8552; }","duration":"101.063658ms","start":"2026-05-09T17:10:10.71184Z","end":"2026-05-09T17:10:10.812904Z","steps":["trace[467644680] 'agreement among raft nodes before linearized reading' (duration: 100.955226ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:14.771456Z","caller":"traceutil/trace.go:171","msg":"trace[468942701] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"127.801081ms","start":"2026-05-09T17:10:14.643617Z","end":"2026-05-09T17:10:14.771418Z","steps":["trace[468942701] 'process raft request' (duration: 127.620738ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:24.985728Z","caller":"traceutil/trace.go:171","msg":"trace[46589461] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"100.881425ms","start":"2026-05-09T17:10:24.884812Z","end":"2026-05-09T17:10:24.985693Z","steps":["trace[46589461] 'process raft request' (duration: 78.418176ms)","trace[46589461] 'compare' (duration: 22.135561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:10:24.98597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.035507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T17:10:24.986084Z","caller":"traceutil/trace.go:171","msg":"trace[1083889297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8672; }","duration":"100.207231ms","start":"2026-05-09T17:10:24.885852Z","end":"2026-05-09T17:10:24.98606Z","steps":["trace[1083889297] 'agreement among raft nodes before linearized reading' (duration: 99.888575ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:40.341579Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ae.wal"} {"level":"info","ts":"2026-05-09T17:11:44.291673Z","caller":"traceutil/trace.go:171","msg":"trace[1740183842] linearizableReadLoop","detail":"{readStateIndex:9770; appliedIndex:9769; }","duration":"271.513771ms","start":"2026-05-09T17:11:44.020121Z","end":"2026-05-09T17:11:44.291634Z","steps":["trace[1740183842] 'read index received' (duration: 181.77597ms)","trace[1740183842] 'applied index is now lower than readState.Index' (duration: 89.735751ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:11:44.29193Z","caller":"traceutil/trace.go:171","msg":"trace[1110185441] transaction","detail":"{read_only:false; response_revision:9434; number_of_response:1; }","duration":"289.836167ms","start":"2026-05-09T17:11:44.002046Z","end":"2026-05-09T17:11:44.291882Z","steps":["trace[1110185441] 'process raft request' (duration: 199.873851ms)","trace[1110185441] 'compare' (duration: 89.537348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:11:44.291984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.824377ms","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-05-09T17:11:44.292192Z","caller":"traceutil/trace.go:171","msg":"trace[785963252] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9434; }","duration":"272.015472ms","start":"2026-05-09T17:11:44.020064Z","end":"2026-05-09T17:11:44.29208Z","steps":["trace[785963252] 'agreement among raft nodes before linearized reading' (duration: 271.795637ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:12:16.123916Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:12:16.12994Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T17:12:16.130079Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T17:14:24.865949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8125} {"level":"info","ts":"2026-05-09T17:14:24.933488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8125,"took":"64.968159ms","hash":4099160168,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":28213248,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T17:14:24.933561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4099160168,"revision":8125,"compact-revision":6061} {"level":"info","ts":"2026-05-09T17:17:21.438522Z","caller":"traceutil/trace.go:171","msg":"trace[1240209295] linearizableReadLoop","detail":"{readStateIndex:12272; appliedIndex:12270; }","duration":"141.535152ms","start":"2026-05-09T17:17:21.296955Z","end":"2026-05-09T17:17:21.43849Z","steps":["trace[1240209295] 'read index received' (duration: 49.757152ms)","trace[1240209295] 'applied index is now lower than readState.Index' (duration: 91.77688ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:17:21.438579Z","caller":"traceutil/trace.go:171","msg":"trace[28703982] transaction","detail":"{read_only:false; response_revision:11859; number_of_response:1; }","duration":"144.029312ms","start":"2026-05-09T17:17:21.294504Z","end":"2026-05-09T17:17:21.438534Z","steps":["trace[28703982] 'process raft request' (duration: 95.504804ms)","trace[28703982] 'compare' (duration: 48.321804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:17:21.438729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.746916ms","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-05-09T17:17:21.438816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.289679ms","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-05-09T17:17:21.438859Z","caller":"traceutil/trace.go:171","msg":"trace[545267915] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11859; }","duration":"115.374061ms","start":"2026-05-09T17:17:21.323475Z","end":"2026-05-09T17:17:21.438849Z","steps":["trace[545267915] 'agreement among raft nodes before linearized reading' (duration: 115.198977ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:17:21.43881Z","caller":"traceutil/trace.go:171","msg":"trace[1673151469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11859; }","duration":"141.904459ms","start":"2026-05-09T17:17:21.296887Z","end":"2026-05-09T17:17:21.438791Z","steps":["trace[1673151469] 'agreement among raft nodes before linearized reading' (duration: 141.723005ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:19:24.873072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10479} {"level":"info","ts":"2026-05-09T17:19:24.957695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10479,"took":"81.305501ms","hash":806517719,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":26066944,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-09T17:19:24.957785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":806517719,"revision":10479,"compact-revision":8125} {"level":"info","ts":"2026-05-09T17:23:53.798584Z","caller":"traceutil/trace.go:171","msg":"trace[1762599028] transaction","detail":"{read_only:false; response_revision:14472; number_of_response:1; }","duration":"149.373619ms","start":"2026-05-09T17:23:53.649179Z","end":"2026-05-09T17:23:53.798553Z","steps":["trace[1762599028] 'process raft request' (duration: 149.217066ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:24:00.869491Z","caller":"traceutil/trace.go:171","msg":"trace[620007518] transaction","detail":"{read_only:false; response_revision:14508; number_of_response:1; }","duration":"165.749515ms","start":"2026-05-09T17:24:00.70371Z","end":"2026-05-09T17:24:00.869459Z","steps":["trace[620007518] 'process raft request' (duration: 165.5077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:24:01.072197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.439768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:24:01.072311Z","caller":"traceutil/trace.go:171","msg":"trace[1105458066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14508; }","duration":"193.620351ms","start":"2026-05-09T17:24:00.878667Z","end":"2026-05-09T17:24:01.072288Z","steps":["trace[1105458066] 'range keys from in-memory index tree' (duration: 193.309714ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:24:12.443837Z","caller":"traceutil/trace.go:171","msg":"trace[353664774] transaction","detail":"{read_only:false; response_revision:14701; number_of_response:1; }","duration":"158.187844ms","start":"2026-05-09T17:24:12.285624Z","end":"2026-05-09T17:24:12.443812Z","steps":["trace[353664774] 'process raft request' (duration: 158.01895ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:24:12.450763Z","caller":"traceutil/trace.go:171","msg":"trace[1532598703] transaction","detail":"{read_only:false; response_revision:14702; number_of_response:1; }","duration":"161.779916ms","start":"2026-05-09T17:24:12.288955Z","end":"2026-05-09T17:24:12.450735Z","steps":["trace[1532598703] 'process raft request' (duration: 161.592213ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:24:24.883329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12699} {"level":"info","ts":"2026-05-09T17:24:24.959055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12699,"took":"72.633959ms","hash":2235874453,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":28336128,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T17:24:24.959116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2235874453,"revision":12699,"compact-revision":10479} {"level":"warn","ts":"2026-05-09T17:25:54.62812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.00252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:25:54.628269Z","caller":"traceutil/trace.go:171","msg":"trace[791742333] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:15799; }","duration":"242.218274ms","start":"2026-05-09T17:25:54.38602Z","end":"2026-05-09T17:25:54.628239Z","steps":["trace[791742333] 'count revisions from in-memory index tree' (duration: 241.872577ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:25:54.975092Z","caller":"traceutil/trace.go:171","msg":"trace[1880313986] linearizableReadLoop","detail":"{readStateIndex:16349; appliedIndex:16348; }","duration":"108.97677ms","start":"2026-05-09T17:25:54.866086Z","end":"2026-05-09T17:25:54.975063Z","steps":["trace[1880313986] 'read index received' (duration: 106.566812ms)","trace[1880313986] 'applied index is now lower than readState.Index' (duration: 2.408398ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:25:54.975357Z","caller":"traceutil/trace.go:171","msg":"trace[501944402] transaction","detail":"{read_only:false; response_revision:15801; number_of_response:1; }","duration":"110.793377ms","start":"2026-05-09T17:25:54.864547Z","end":"2026-05-09T17:25:54.975341Z","steps":["trace[501944402] 'process raft request' (duration: 108.101033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:25:54.975703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.581972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-09T17:25:54.97584Z","caller":"traceutil/trace.go:171","msg":"trace[1398326731] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15801; }","duration":"109.791266ms","start":"2026-05-09T17:25:54.866021Z","end":"2026-05-09T17:25:54.975812Z","steps":["trace[1398326731] 'agreement among raft nodes before linearized reading' (duration: 109.45898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:25:54.988531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.46492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:25:54.988631Z","caller":"traceutil/trace.go:171","msg":"trace[297039230] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15803; }","duration":"121.606993ms","start":"2026-05-09T17:25:54.867005Z","end":"2026-05-09T17:25:54.988612Z","steps":["trace[297039230] 'agreement among raft nodes before linearized reading' (duration: 121.410169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:25:54.988869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.123034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:25:54.988905Z","caller":"traceutil/trace.go:171","msg":"trace[529899178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15803; }","duration":"111.160104ms","start":"2026-05-09T17:25:54.877736Z","end":"2026-05-09T17:25:54.988896Z","steps":["trace[529899178] 'agreement among raft nodes before linearized reading' (duration: 111.102523ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:25:54.988989Z","caller":"traceutil/trace.go:171","msg":"trace[504008034] transaction","detail":"{read_only:false; response_revision:15802; number_of_response:1; }","duration":"121.899119ms","start":"2026-05-09T17:25:54.867077Z","end":"2026-05-09T17:25:54.988976Z","steps":["trace[504008034] 'process raft request' (duration: 121.142744ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:25:54.989124Z","caller":"traceutil/trace.go:171","msg":"trace[1221062782] transaction","detail":"{read_only:false; response_revision:15803; number_of_response:1; }","duration":"119.697975ms","start":"2026-05-09T17:25:54.869418Z","end":"2026-05-09T17:25:54.989116Z","steps":["trace[1221062782] 'process raft request' (duration: 118.93818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:25:54.989143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.194934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-09T17:25:54.989174Z","caller":"traceutil/trace.go:171","msg":"trace[1778772930] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15803; }","duration":"121.247955ms","start":"2026-05-09T17:25:54.867917Z","end":"2026-05-09T17:25:54.989165Z","steps":["trace[1778772930] 'agreement among raft nodes before linearized reading' (duration: 121.137252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:25:54.989457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.930539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-09T17:25:54.989497Z","caller":"traceutil/trace.go:171","msg":"trace[593217614] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15803; }","duration":"121.98699ms","start":"2026-05-09T17:25:54.867499Z","end":"2026-05-09T17:25:54.989486Z","steps":["trace[593217614] 'agreement among raft nodes before linearized reading' (duration: 121.836997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:25:54.989617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.424059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:25:54.989641Z","caller":"traceutil/trace.go:171","msg":"trace[1376240422] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15803; }","duration":"122.45013ms","start":"2026-05-09T17:25:54.867185Z","end":"2026-05-09T17:25:54.989635Z","steps":["trace[1376240422] 'agreement among raft nodes before linearized reading' (duration: 122.409149ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:29:24.88878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14911} {"level":"info","ts":"2026-05-09T17:29:24.984985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14911,"took":"93.024869ms","hash":1034615890,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T17:29:24.985079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1034615890,"revision":14911,"compact-revision":12699} {"level":"info","ts":"2026-05-09T17:31:30.227636Z","caller":"traceutil/trace.go:171","msg":"trace[1394057246] linearizableReadLoop","detail":"{readStateIndex:18988; appliedIndex:18987; }","duration":"142.34808ms","start":"2026-05-09T17:31:30.085255Z","end":"2026-05-09T17:31:30.227603Z","steps":["trace[1394057246] 'read index received' (duration: 142.112555ms)","trace[1394057246] 'applied index is now lower than readState.Index' (duration: 233.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:31:30.227741Z","caller":"traceutil/trace.go:171","msg":"trace[1495905629] transaction","detail":"{read_only:false; response_revision:18358; number_of_response:1; }","duration":"143.346431ms","start":"2026-05-09T17:31:30.084348Z","end":"2026-05-09T17:31:30.227695Z","steps":["trace[1495905629] 'process raft request' (duration: 143.045594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:31:30.228148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.85129ms","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-05-09T17:31:30.228276Z","caller":"traceutil/trace.go:171","msg":"trace[563439278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18358; }","duration":"143.025824ms","start":"2026-05-09T17:31:30.085223Z","end":"2026-05-09T17:31:30.228249Z","steps":["trace[563439278] 'agreement among raft nodes before linearized reading' (duration: 142.567605ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:31:32.480178Z","caller":"traceutil/trace.go:171","msg":"trace[792216623] transaction","detail":"{read_only:false; response_revision:18374; number_of_response:1; }","duration":"169.603977ms","start":"2026-05-09T17:31:32.310544Z","end":"2026-05-09T17:31:32.480148Z","steps":["trace[792216623] 'process raft request' (duration: 169.507805ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:31:32.480222Z","caller":"traceutil/trace.go:171","msg":"trace[772222298] transaction","detail":"{read_only:false; response_revision:18373; number_of_response:1; }","duration":"191.164521ms","start":"2026-05-09T17:31:32.289012Z","end":"2026-05-09T17:31:32.480176Z","steps":["trace[772222298] 'process raft request' (duration: 190.868746ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:31:32.480398Z","caller":"traceutil/trace.go:171","msg":"trace[1579445705] linearizableReadLoop","detail":"{readStateIndex:19005; appliedIndex:19002; }","duration":"151.797526ms","start":"2026-05-09T17:31:32.328574Z","end":"2026-05-09T17:31:32.480372Z","steps":["trace[1579445705] 'read index received' (duration: 151.362828ms)","trace[1579445705] 'applied index is now lower than readState.Index' (duration: 432.808µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:31:32.480519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.310564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:31:32.480559Z","caller":"traceutil/trace.go:171","msg":"trace[1463978413] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:18375; }","duration":"191.418526ms","start":"2026-05-09T17:31:32.289129Z","end":"2026-05-09T17:31:32.480548Z","steps":["trace[1463978413] 'agreement among raft nodes before linearized reading' (duration: 191.322725ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:31:32.48065Z","caller":"traceutil/trace.go:171","msg":"trace[923095737] transaction","detail":"{read_only:false; response_revision:18375; number_of_response:1; }","duration":"170.074866ms","start":"2026-05-09T17:31:32.31056Z","end":"2026-05-09T17:31:32.480635Z","steps":["trace[923095737] 'process raft request' (duration: 169.548756ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:31:32.497963Z","caller":"traceutil/trace.go:171","msg":"trace[886478384] transaction","detail":"{read_only:false; response_revision:18376; number_of_response:1; }","duration":"163.067438ms","start":"2026-05-09T17:31:32.334863Z","end":"2026-05-09T17:31:32.49793Z","steps":["trace[886478384] 'process raft request' (duration: 162.953795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:31:37.053148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.069113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:31:37.053275Z","caller":"traceutil/trace.go:171","msg":"trace[1590654830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18418; }","duration":"175.259907ms","start":"2026-05-09T17:31:36.877995Z","end":"2026-05-09T17:31:37.053255Z","steps":["trace[1590654830] 'range keys from in-memory index tree' (duration: 174.953921ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:33:14.897363Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:33:14.903219Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T17:33:14.903376Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T17:34:24.896349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17415} {"level":"info","ts":"2026-05-09T17:34:24.999496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17415,"took":"98.143581ms","hash":4175983592,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":34078720,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T17:34:24.999598Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4175983592,"revision":17415,"compact-revision":14911} {"level":"warn","ts":"2026-05-09T17:37:19.655857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.606995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:37:19.65595Z","caller":"traceutil/trace.go:171","msg":"trace[2067124881] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:21002; }","duration":"150.81085ms","start":"2026-05-09T17:37:19.505121Z","end":"2026-05-09T17:37:19.655932Z","steps":["trace[2067124881] 'count revisions from in-memory index tree' (duration: 150.510343ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:37:20.016882Z","caller":"traceutil/trace.go:171","msg":"trace[1608267957] transaction","detail":"{read_only:false; response_revision:21003; number_of_response:1; }","duration":"136.81092ms","start":"2026-05-09T17:37:19.880028Z","end":"2026-05-09T17:37:20.016839Z","steps":["trace[1608267957] 'process raft request' (duration: 136.533525ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:37:20.158274Z","caller":"traceutil/trace.go:171","msg":"trace[1913122637] linearizableReadLoop","detail":"{readStateIndex:21723; appliedIndex:21720; }","duration":"259.554211ms","start":"2026-05-09T17:37:19.898699Z","end":"2026-05-09T17:37:20.158253Z","steps":["trace[1913122637] 'read index received' (duration: 117.928935ms)","trace[1913122637] 'applied index is now lower than readState.Index' (duration: 141.624136ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:37:20.158519Z","caller":"traceutil/trace.go:171","msg":"trace[437699731] transaction","detail":"{read_only:false; response_revision:21004; number_of_response:1; }","duration":"274.483608ms","start":"2026-05-09T17:37:19.884025Z","end":"2026-05-09T17:37:20.158509Z","steps":["trace[437699731] 'process raft request' (duration: 250.028202ms)","trace[437699731] 'compare' (duration: 24.032818ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:37:20.158682Z","caller":"traceutil/trace.go:171","msg":"trace[854273769] transaction","detail":"{read_only:false; response_revision:21005; number_of_response:1; }","duration":"266.229334ms","start":"2026-05-09T17:37:19.892413Z","end":"2026-05-09T17:37:20.158643Z","steps":["trace[854273769] 'process raft request' (duration: 265.793226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:37:20.158795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.958852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"warn","ts":"2026-05-09T17:37:20.158865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.147023ms","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-05-09T17:37:20.158893Z","caller":"traceutil/trace.go:171","msg":"trace[1160329702] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:21005; }","duration":"233.103705ms","start":"2026-05-09T17:37:19.925769Z","end":"2026-05-09T17:37:20.158873Z","steps":["trace[1160329702] 'agreement among raft nodes before linearized reading' (duration: 232.826909ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:37:20.159006Z","caller":"traceutil/trace.go:171","msg":"trace[523142463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21005; }","duration":"260.342127ms","start":"2026-05-09T17:37:19.898643Z","end":"2026-05-09T17:37:20.158985Z","steps":["trace[523142463] 'agreement among raft nodes before linearized reading' (duration: 260.00887ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:37:20.336136Z","caller":"traceutil/trace.go:171","msg":"trace[728952079] transaction","detail":"{read_only:false; response_revision:21006; number_of_response:1; }","duration":"171.888908ms","start":"2026-05-09T17:37:20.164217Z","end":"2026-05-09T17:37:20.336106Z","steps":["trace[728952079] 'process raft request' (duration: 116.696391ms)","trace[728952079] 'compare' (duration: 54.928291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:37:20.625479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.049276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:37:20.625651Z","caller":"traceutil/trace.go:171","msg":"trace[863323082] transaction","detail":"{read_only:false; response_revision:21007; number_of_response:1; }","duration":"285.485277ms","start":"2026-05-09T17:37:20.34014Z","end":"2026-05-09T17:37:20.625625Z","steps":["trace[863323082] 'process raft request' (duration: 149.155426ms)","trace[863323082] 'compare' (duration: 135.74238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:37:20.865005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.007225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:37:20.865211Z","caller":"traceutil/trace.go:171","msg":"trace[1670645657] transaction","detail":"{read_only:false; response_revision:21008; number_of_response:1; }","duration":"524.53225ms","start":"2026-05-09T17:37:20.340646Z","end":"2026-05-09T17:37:20.865178Z","steps":["trace[1670645657] 'process raft request' (duration: 393.26ms)","trace[1670645657] 'compare' (duration: 130.847282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:37:20.865389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:37:20.340628Z","time spent":"524.669553ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:37:20.86525Z","caller":"traceutil/trace.go:171","msg":"trace[1700683057] transaction","detail":"{read_only:false; response_revision:21010; number_of_response:1; }","duration":"223.580616ms","start":"2026-05-09T17:37:20.641649Z","end":"2026-05-09T17:37:20.86523Z","steps":["trace[1700683057] 'process raft request' (duration: 223.529895ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:37:20.865906Z","caller":"traceutil/trace.go:171","msg":"trace[1934860251] linearizableReadLoop","detail":"{readStateIndex:21727; appliedIndex:21724; }","duration":"435.568301ms","start":"2026-05-09T17:37:20.430329Z","end":"2026-05-09T17:37:20.865897Z","steps":["trace[1934860251] 'read index received' (duration: 59.054374ms)","trace[1934860251] 'applied index is now lower than readState.Index' (duration: 376.512907ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:37:20.865936Z","caller":"traceutil/trace.go:171","msg":"trace[547989862] transaction","detail":"{read_only:false; response_revision:21009; number_of_response:1; }","duration":"524.864547ms","start":"2026-05-09T17:37:20.341033Z","end":"2026-05-09T17:37:20.865898Z","steps":["trace[547989862] 'process raft request' (duration: 524.081351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:37:20.866138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:37:20.341023Z","time spent":"525.00815ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:37:20.868653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.320326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T17:37:20.868855Z","caller":"traceutil/trace.go:171","msg":"trace[1226717263] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:21010; }","duration":"438.561261ms","start":"2026-05-09T17:37:20.430281Z","end":"2026-05-09T17:37:20.868842Z","steps":["trace[1226717263] 'agreement among raft nodes before linearized reading' (duration: 435.681594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:37:20.869007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:37:20.430267Z","time spent":"438.703744ms","remote":"127.0.0.1:41388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2164,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:39:24.904977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19810} {"level":"info","ts":"2026-05-09T17:39:25.028888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19810,"took":"118.846124ms","hash":1072832163,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30031872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T17:39:25.029491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1072832163,"revision":19810,"compact-revision":17415} {"level":"warn","ts":"2026-05-09T17:41:19.982969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.748698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T17:41:19.983079Z","caller":"traceutil/trace.go:171","msg":"trace[1638362116] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:23473; }","duration":"236.925181ms","start":"2026-05-09T17:41:19.746132Z","end":"2026-05-09T17:41:19.983057Z","steps":["trace[1638362116] 'count revisions from in-memory index tree' (duration: 236.611176ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:20.013338Z","caller":"traceutil/trace.go:171","msg":"trace[1406981452] linearizableReadLoop","detail":"{readStateIndex:24267; appliedIndex:24266; }","duration":"134.547615ms","start":"2026-05-09T17:41:19.878759Z","end":"2026-05-09T17:41:20.013307Z","steps":["trace[1406981452] 'read index received' (duration: 134.27318ms)","trace[1406981452] 'applied index is now lower than readState.Index' (duration: 273.035µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:20.01345Z","caller":"traceutil/trace.go:171","msg":"trace[1455297027] transaction","detail":"{read_only:false; response_revision:23474; number_of_response:1; }","duration":"182.760925ms","start":"2026-05-09T17:41:19.830654Z","end":"2026-05-09T17:41:20.013415Z","steps":["trace[1455297027] 'process raft request' (duration: 182.436419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:20.013574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.79342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:20.013665Z","caller":"traceutil/trace.go:171","msg":"trace[480699782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23474; }","duration":"134.936702ms","start":"2026-05-09T17:41:19.878702Z","end":"2026-05-09T17:41:20.013638Z","steps":["trace[480699782] 'agreement among raft nodes before linearized reading' (duration: 134.758149ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:20.127259Z","caller":"traceutil/trace.go:171","msg":"trace[1687094141] linearizableReadLoop","detail":"{readStateIndex:24268; appliedIndex:24267; }","duration":"113.794532ms","start":"2026-05-09T17:41:20.013426Z","end":"2026-05-09T17:41:20.127221Z","steps":["trace[1687094141] 'read index received' (duration: 83.45545ms)","trace[1687094141] 'applied index is now lower than readState.Index' (duration: 30.337612ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:20.127472Z","caller":"traceutil/trace.go:171","msg":"trace[447495121] transaction","detail":"{read_only:false; response_revision:23475; number_of_response:1; }","duration":"196.745122ms","start":"2026-05-09T17:41:19.930707Z","end":"2026-05-09T17:41:20.127452Z","steps":["trace[447495121] 'process raft request' (duration: 166.159054ms)","trace[447495121] 'compare' (duration: 30.038857ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:20.127897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.666731ms","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-05-09T17:41:20.128006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.012968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:20.12813Z","caller":"traceutil/trace.go:171","msg":"trace[907150032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23477; }","duration":"113.190892ms","start":"2026-05-09T17:41:20.014905Z","end":"2026-05-09T17:41:20.128096Z","steps":["trace[907150032] 'agreement among raft nodes before linearized reading' (duration: 113.011268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:20.128386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.086219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-05-09T17:41:20.12846Z","caller":"traceutil/trace.go:171","msg":"trace[606380168] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:23477; }","duration":"201.1784ms","start":"2026-05-09T17:41:19.927261Z","end":"2026-05-09T17:41:20.128439Z","steps":["trace[606380168] 'agreement among raft nodes before linearized reading' (duration: 200.990447ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:20.127983Z","caller":"traceutil/trace.go:171","msg":"trace[1209855509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23476; }","duration":"200.780703ms","start":"2026-05-09T17:41:19.92718Z","end":"2026-05-09T17:41:20.12796Z","steps":["trace[1209855509] 'agreement among raft nodes before linearized reading' (duration: 200.557289ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:57.288087Z","caller":"traceutil/trace.go:171","msg":"trace[1335924879] linearizableReadLoop","detail":"{readStateIndex:25288; appliedIndex:25287; }","duration":"105.190726ms","start":"2026-05-09T17:42:57.18287Z","end":"2026-05-09T17:42:57.288061Z","steps":["trace[1335924879] 'read index received' (duration: 104.917751ms)","trace[1335924879] 'applied index is now lower than readState.Index' (duration: 271.935µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:57.288212Z","caller":"traceutil/trace.go:171","msg":"trace[238018628] transaction","detail":"{read_only:false; response_revision:24471; number_of_response:1; }","duration":"109.95578ms","start":"2026-05-09T17:42:57.178215Z","end":"2026-05-09T17:42:57.288171Z","steps":["trace[238018628] 'process raft request' (duration: 109.653584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:57.288304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.41343ms","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-05-09T17:42:57.288551Z","caller":"traceutil/trace.go:171","msg":"trace[322988991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24471; }","duration":"105.706456ms","start":"2026-05-09T17:42:57.18283Z","end":"2026-05-09T17:42:57.288536Z","steps":["trace[322988991] 'agreement among raft nodes before linearized reading' (duration: 105.343459ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:44:24.910572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22006} {"level":"info","ts":"2026-05-09T17:44:25.019355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22006,"took":"103.181965ms","hash":537528580,"current-db-size-bytes":60174336,"current-db-size":"60 MB","current-db-size-in-use-bytes":42905600,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-09T17:44:25.019406Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":537528580,"revision":22006,"compact-revision":19810} {"level":"warn","ts":"2026-05-09T17:45:02.083681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.158622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T17:45:02.083791Z","caller":"traceutil/trace.go:171","msg":"trace[1186015757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25658; }","duration":"144.304225ms","start":"2026-05-09T17:45:01.939462Z","end":"2026-05-09T17:45:02.083766Z","steps":["trace[1186015757] 'range keys from in-memory index tree' (duration: 144.010049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:02.083852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.301034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29639145-zpk6p\" ","response":"range_response_count:1 size:6984"} {"level":"info","ts":"2026-05-09T17:45:02.083948Z","caller":"traceutil/trace.go:171","msg":"trace[993399011] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29639145-zpk6p; range_end:; response_count:1; response_revision:25658; }","duration":"161.454267ms","start":"2026-05-09T17:45:01.922465Z","end":"2026-05-09T17:45:02.083919Z","steps":["trace[993399011] 'range keys from in-memory index tree' (duration: 161.074819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:02.0847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.288106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:45:02.084948Z","caller":"traceutil/trace.go:171","msg":"trace[1680732344] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25658; }","duration":"111.445499ms","start":"2026-05-09T17:45:01.973482Z","end":"2026-05-09T17:45:02.084927Z","steps":["trace[1680732344] 'count revisions from in-memory index tree' (duration: 110.084742ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:02.329976Z","caller":"traceutil/trace.go:171","msg":"trace[1615712690] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"230.320958ms","start":"2026-05-09T17:45:02.099629Z","end":"2026-05-09T17:45:02.32995Z","steps":["trace[1615712690] 'process raft request' (duration: 230.236866ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:02.330038Z","caller":"traceutil/trace.go:171","msg":"trace[1251490838] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"232.023102ms","start":"2026-05-09T17:45:02.097973Z","end":"2026-05-09T17:45:02.329997Z","steps":["trace[1251490838] 'process raft request' (duration: 231.665875ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:02.536746Z","caller":"traceutil/trace.go:171","msg":"trace[61322671] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"338.027634ms","start":"2026-05-09T17:45:02.198693Z","end":"2026-05-09T17:45:02.53672Z","steps":["trace[61322671] 'process raft request' (duration: 337.82507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:02.536916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:02.19866Z","time spent":"338.166367ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:45:02.536913Z","caller":"traceutil/trace.go:171","msg":"trace[1089655424] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"210.194347ms","start":"2026-05-09T17:45:02.326681Z","end":"2026-05-09T17:45:02.536875Z","steps":["trace[1089655424] 'process raft request' (duration: 210.003773ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:02.738914Z","caller":"traceutil/trace.go:171","msg":"trace[1705411055] linearizableReadLoop","detail":"{readStateIndex:26515; appliedIndex:26514; }","duration":"154.078479ms","start":"2026-05-09T17:45:02.584796Z","end":"2026-05-09T17:45:02.738875Z","steps":["trace[1705411055] 'read index received' (duration: 153.853245ms)","trace[1705411055] 'applied index is now lower than readState.Index' (duration: 223.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:02.739058Z","caller":"traceutil/trace.go:171","msg":"trace[819452646] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"190.381243ms","start":"2026-05-09T17:45:02.548667Z","end":"2026-05-09T17:45:02.739049Z","steps":["trace[819452646] 'process raft request' (duration: 189.994345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:02.739376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.520027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:45:02.739578Z","caller":"traceutil/trace.go:171","msg":"trace[2075807465] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25666; }","duration":"154.908185ms","start":"2026-05-09T17:45:02.584646Z","end":"2026-05-09T17:45:02.739554Z","steps":["trace[2075807465] 'agreement among raft nodes before linearized reading' (duration: 154.584369ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:03.456561Z","caller":"traceutil/trace.go:171","msg":"trace[2087627246] linearizableReadLoop","detail":"{readStateIndex:26525; appliedIndex:26524; }","duration":"127.542591ms","start":"2026-05-09T17:45:03.328991Z","end":"2026-05-09T17:45:03.456533Z","steps":["trace[2087627246] 'read index received' (duration: 127.381658ms)","trace[2087627246] 'applied index is now lower than readState.Index' (duration: 159.943µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:03.456851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.828917ms","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-05-09T17:45:03.456922Z","caller":"traceutil/trace.go:171","msg":"trace[1600853465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25676; }","duration":"127.945349ms","start":"2026-05-09T17:45:03.328963Z","end":"2026-05-09T17:45:03.456908Z","steps":["trace[1600853465] 'agreement among raft nodes before linearized reading' (duration: 127.777815ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:03.457221Z","caller":"traceutil/trace.go:171","msg":"trace[207212379] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"134.266394ms","start":"2026-05-09T17:45:03.322936Z","end":"2026-05-09T17:45:03.457202Z","steps":["trace[207212379] 'process raft request' (duration: 133.350216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:03.763198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.450198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:03.76347Z","caller":"traceutil/trace.go:171","msg":"trace[1885712616] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"235.44856ms","start":"2026-05-09T17:45:03.527979Z","end":"2026-05-09T17:45:03.763427Z","steps":["trace[1885712616] 'process raft request' (duration: 105.670085ms)","trace[1885712616] 'compare' (duration: 129.278895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:03.9155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.154082ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T17:45:03.919669Z","caller":"traceutil/trace.go:171","msg":"trace[837195272] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"147.508458ms","start":"2026-05-09T17:45:03.772129Z","end":"2026-05-09T17:45:03.919637Z","steps":["trace[837195272] 'process raft request' (duration: 147.202782ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:04.065236Z","caller":"traceutil/trace.go:171","msg":"trace[2035638432] linearizableReadLoop","detail":"{readStateIndex:26530; appliedIndex:26528; }","duration":"187.834401ms","start":"2026-05-09T17:45:03.877367Z","end":"2026-05-09T17:45:04.065201Z","steps":["trace[2035638432] 'read index received' (duration: 41.944685ms)","trace[2035638432] 'applied index is now lower than readState.Index' (duration: 145.884726ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:04.065764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.365462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:04.065873Z","caller":"traceutil/trace.go:171","msg":"trace[753971232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25680; }","duration":"188.545305ms","start":"2026-05-09T17:45:03.877301Z","end":"2026-05-09T17:45:04.065846Z","steps":["trace[753971232] 'agreement among raft nodes before linearized reading' (duration: 188.341431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:04.065882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.638413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:04.065936Z","caller":"traceutil/trace.go:171","msg":"trace[2006840384] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25680; }","duration":"118.765085ms","start":"2026-05-09T17:45:03.947155Z","end":"2026-05-09T17:45:04.06592Z","steps":["trace[2006840384] 'agreement among raft nodes before linearized reading' (duration: 118.648723ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:04.06607Z","caller":"traceutil/trace.go:171","msg":"trace[1703653655] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"238.775386ms","start":"2026-05-09T17:45:03.827249Z","end":"2026-05-09T17:45:04.066024Z","steps":["trace[1703653655] 'process raft request' (duration: 234.389049ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:04.267695Z","caller":"traceutil/trace.go:171","msg":"trace[1392443159] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"175.981075ms","start":"2026-05-09T17:45:04.091681Z","end":"2026-05-09T17:45:04.267662Z","steps":["trace[1392443159] 'process raft request' (duration: 175.769971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:04.553732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.935066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:04.553852Z","caller":"traceutil/trace.go:171","msg":"trace[1503631986] linearizableReadLoop","detail":"{readStateIndex:26532; appliedIndex:26530; }","duration":"434.72064ms","start":"2026-05-09T17:45:04.119111Z","end":"2026-05-09T17:45:04.553832Z","steps":["trace[1503631986] 'read index received' (duration: 148.420456ms)","trace[1503631986] 'applied index is now lower than readState.Index' (duration: 286.298764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:04.553956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.850863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:04.553929Z","caller":"traceutil/trace.go:171","msg":"trace[1177092562] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"460.778929ms","start":"2026-05-09T17:45:04.09311Z","end":"2026-05-09T17:45:04.553889Z","steps":["trace[1177092562] 'process raft request' (duration: 302.563727ms)","trace[1177092562] 'compare' (duration: 157.787303ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:04.553989Z","caller":"traceutil/trace.go:171","msg":"trace[991357045] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25682; }","duration":"434.926224ms","start":"2026-05-09T17:45:04.119048Z","end":"2026-05-09T17:45:04.553974Z","steps":["trace[991357045] 'agreement among raft nodes before linearized reading' (duration: 434.861172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:04.554039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:04.119025Z","time spent":"435.002856ms","remote":"127.0.0.1:50176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:45:04.554149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:04.09309Z","time spent":"460.932531ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:45:04.814235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.384627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:04.814532Z","caller":"traceutil/trace.go:171","msg":"trace[1134289931] linearizableReadLoop","detail":"{readStateIndex:26534; appliedIndex:26532; }","duration":"260.634041ms","start":"2026-05-09T17:45:04.553879Z","end":"2026-05-09T17:45:04.814513Z","steps":["trace[1134289931] 'read index received' (duration: 124.804445ms)","trace[1134289931] 'applied index is now lower than readState.Index' (duration: 135.828176ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:04.814549Z","caller":"traceutil/trace.go:171","msg":"trace[1423520798] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"271.251273ms","start":"2026-05-09T17:45:04.543254Z","end":"2026-05-09T17:45:04.814505Z","steps":["trace[1423520798] 'process raft request' (duration: 135.490239ms)","trace[1423520798] 'compare' (duration: 135.125871ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:04.814595Z","caller":"traceutil/trace.go:171","msg":"trace[177301300] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"270.005209ms","start":"2026-05-09T17:45:04.544536Z","end":"2026-05-09T17:45:04.814542Z","steps":["trace[177301300] 'process raft request' (duration: 269.874456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:04.814699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.371064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T17:45:04.814719Z","caller":"traceutil/trace.go:171","msg":"trace[652386580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25684; }","duration":"666.418114ms","start":"2026-05-09T17:45:04.148293Z","end":"2026-05-09T17:45:04.814711Z","steps":["trace[652386580] 'agreement among raft nodes before linearized reading' (duration: 666.266131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:04.814742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:04.148281Z","time spent":"666.456246ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T17:45:05.096957Z","caller":"traceutil/trace.go:171","msg":"trace[1370641400] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"177.945865ms","start":"2026-05-09T17:45:04.918991Z","end":"2026-05-09T17:45:05.096937Z","steps":["trace[1370641400] 'process raft request' (duration: 177.827992ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:05.109362Z","caller":"traceutil/trace.go:171","msg":"trace[1009060342] linearizableReadLoop","detail":"{readStateIndex:26537; appliedIndex:26535; }","duration":"124.363577ms","start":"2026-05-09T17:45:04.984964Z","end":"2026-05-09T17:45:05.109327Z","steps":["trace[1009060342] 'read index received' (duration: 111.912538ms)","trace[1009060342] 'applied index is now lower than readState.Index' (duration: 12.449349ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:05.109522Z","caller":"traceutil/trace.go:171","msg":"trace[1197207881] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"126.50913ms","start":"2026-05-09T17:45:04.982972Z","end":"2026-05-09T17:45:05.109481Z","steps":["trace[1197207881] 'process raft request' (duration: 126.137463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:05.109672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.658562ms","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-05-09T17:45:05.109721Z","caller":"traceutil/trace.go:171","msg":"trace[751803617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25687; }","duration":"124.767434ms","start":"2026-05-09T17:45:04.984939Z","end":"2026-05-09T17:45:05.109707Z","steps":["trace[751803617] 'agreement among raft nodes before linearized reading' (duration: 124.509919ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:05.66179Z","caller":"traceutil/trace.go:171","msg":"trace[1116952668] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"103.768507ms","start":"2026-05-09T17:45:05.557969Z","end":"2026-05-09T17:45:05.661738Z","steps":["trace[1116952668] 'process raft request' (duration: 102.151065ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:05.922787Z","caller":"traceutil/trace.go:171","msg":"trace[1370352042] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26540; }","duration":"154.001647ms","start":"2026-05-09T17:45:05.768754Z","end":"2026-05-09T17:45:05.922755Z","steps":["trace[1370352042] 'read index received' (duration: 153.590409ms)","trace[1370352042] 'applied index is now lower than readState.Index' (duration: 409.778µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:05.922883Z","caller":"traceutil/trace.go:171","msg":"trace[51080076] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"193.353171ms","start":"2026-05-09T17:45:05.729488Z","end":"2026-05-09T17:45:05.922841Z","steps":["trace[51080076] 'process raft request' (duration: 193.022304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:05.923876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.09065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:45:05.923939Z","caller":"traceutil/trace.go:171","msg":"trace[408995882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25691; }","duration":"155.182362ms","start":"2026-05-09T17:45:05.768742Z","end":"2026-05-09T17:45:05.923925Z","steps":["trace[408995882] 'agreement among raft nodes before linearized reading' (duration: 154.228302ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:06.102799Z","caller":"traceutil/trace.go:171","msg":"trace[1034521792] linearizableReadLoop","detail":"{readStateIndex:26542; appliedIndex:26541; }","duration":"175.114679ms","start":"2026-05-09T17:45:05.927649Z","end":"2026-05-09T17:45:06.102763Z","steps":["trace[1034521792] 'read index received' (duration: 133.54212ms)","trace[1034521792] 'applied index is now lower than readState.Index' (duration: 41.570949ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:06.102985Z","caller":"traceutil/trace.go:171","msg":"trace[214985487] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"173.129069ms","start":"2026-05-09T17:45:05.929828Z","end":"2026-05-09T17:45:06.102957Z","steps":["trace[214985487] 'process raft request' (duration: 172.892094ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:06.103011Z","caller":"traceutil/trace.go:171","msg":"trace[1141808161] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"177.429105ms","start":"2026-05-09T17:45:05.925535Z","end":"2026-05-09T17:45:06.102964Z","steps":["trace[1141808161] 'process raft request' (duration: 135.687473ms)","trace[1141808161] 'compare' (duration: 41.305142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:06.103059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.389774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d8691c54-a7d8-43b6-80ef-713296f61f5a\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-09T17:45:06.103106Z","caller":"traceutil/trace.go:171","msg":"trace[675971192] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d8691c54-a7d8-43b6-80ef-713296f61f5a; range_end:; response_count:1; response_revision:25692; }","duration":"175.477326ms","start":"2026-05-09T17:45:05.927613Z","end":"2026-05-09T17:45:06.10309Z","steps":["trace[675971192] 'agreement among raft nodes before linearized reading' (duration: 175.275162ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:06.199813Z","caller":"traceutil/trace.go:171","msg":"trace[1390875887] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"127.299545ms","start":"2026-05-09T17:45:06.07249Z","end":"2026-05-09T17:45:06.19979Z","steps":["trace[1390875887] 'process raft request' (duration: 127.106051ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:06.671081Z","caller":"traceutil/trace.go:171","msg":"trace[1122183308] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"103.605593ms","start":"2026-05-09T17:45:06.567423Z","end":"2026-05-09T17:45:06.671028Z","steps":["trace[1122183308] 'process raft request' (duration: 103.318477ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:06.938644Z","caller":"traceutil/trace.go:171","msg":"trace[1905826459] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"118.142773ms","start":"2026-05-09T17:45:06.820472Z","end":"2026-05-09T17:45:06.938614Z","steps":["trace[1905826459] 'process raft request' (duration: 117.394608ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:07.059437Z","caller":"traceutil/trace.go:171","msg":"trace[735007786] linearizableReadLoop","detail":"{readStateIndex:26548; appliedIndex:26546; }","duration":"209.026374ms","start":"2026-05-09T17:45:06.850383Z","end":"2026-05-09T17:45:07.059409Z","steps":["trace[735007786] 'read index received' (duration: 87.598855ms)","trace[735007786] 'applied index is now lower than readState.Index' (duration: 121.426499ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:07.059527Z","caller":"traceutil/trace.go:171","msg":"trace[2130303899] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"238.065832ms","start":"2026-05-09T17:45:06.821425Z","end":"2026-05-09T17:45:07.059491Z","steps":["trace[2130303899] 'process raft request' (duration: 234.768127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:07.059644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.230797ms","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-05-09T17:45:07.059724Z","caller":"traceutil/trace.go:171","msg":"trace[577420473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25698; }","duration":"209.395631ms","start":"2026-05-09T17:45:06.850311Z","end":"2026-05-09T17:45:07.059707Z","steps":["trace[577420473] 'agreement among raft nodes before linearized reading' (duration: 209.188997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:07.059765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.252193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T17:45:07.059829Z","caller":"traceutil/trace.go:171","msg":"trace[1822970077] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25698; }","duration":"156.400026ms","start":"2026-05-09T17:45:06.903415Z","end":"2026-05-09T17:45:07.059815Z","steps":["trace[1822970077] 'agreement among raft nodes before linearized reading' (duration: 156.146941ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:07.944614Z","caller":"traceutil/trace.go:171","msg":"trace[473712679] linearizableReadLoop","detail":"{readStateIndex:26554; appliedIndex:26553; }","duration":"205.254838ms","start":"2026-05-09T17:45:07.739324Z","end":"2026-05-09T17:45:07.944579Z","steps":["trace[473712679] 'read index received' (duration: 153.755762ms)","trace[473712679] 'applied index is now lower than readState.Index' (duration: 51.497396ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:07.944871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.497083ms","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-05-09T17:45:07.944978Z","caller":"traceutil/trace.go:171","msg":"trace[604508217] 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:25704; }","duration":"205.683497ms","start":"2026-05-09T17:45:07.73927Z","end":"2026-05-09T17:45:07.944954Z","steps":["trace[604508217] 'agreement among raft nodes before linearized reading' (duration: 205.456443ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:07.944961Z","caller":"traceutil/trace.go:171","msg":"trace[1416007580] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"275.07693ms","start":"2026-05-09T17:45:07.669649Z","end":"2026-05-09T17:45:07.944726Z","steps":["trace[1416007580] 'process raft request' (duration: 223.515703ms)","trace[1416007580] 'compare' (duration: 50.937904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:08.184592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.258947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-09T17:45:08.1847Z","caller":"traceutil/trace.go:171","msg":"trace[732868646] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:25704; }","duration":"118.398029ms","start":"2026-05-09T17:45:08.06627Z","end":"2026-05-09T17:45:08.184668Z","steps":["trace[732868646] 'range keys from in-memory index tree' (duration: 118.023751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:13.260062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.632051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:13.260209Z","caller":"traceutil/trace.go:171","msg":"trace[721756879] linearizableReadLoop","detail":"{readStateIndex:26599; appliedIndex:26598; }","duration":"142.883736ms","start":"2026-05-09T17:45:13.117303Z","end":"2026-05-09T17:45:13.260187Z","steps":["trace[721756879] 'read index received' (duration: 7.059141ms)","trace[721756879] 'applied index is now lower than readState.Index' (duration: 135.822755ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:13.260298Z","caller":"traceutil/trace.go:171","msg":"trace[652985446] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"171.507647ms","start":"2026-05-09T17:45:13.088755Z","end":"2026-05-09T17:45:13.260263Z","steps":["trace[652985446] 'process raft request' (duration: 35.496627ms)","trace[652985446] 'compare' (duration: 135.467528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:13.260427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.133351ms","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-05-09T17:45:13.26048Z","caller":"traceutil/trace.go:171","msg":"trace[1712122289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25748; }","duration":"143.231773ms","start":"2026-05-09T17:45:13.117238Z","end":"2026-05-09T17:45:13.26047Z","steps":["trace[1712122289] 'agreement among raft nodes before linearized reading' (duration: 143.037409ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:13.262184Z","caller":"traceutil/trace.go:171","msg":"trace[1073704346] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"113.094392ms","start":"2026-05-09T17:45:13.149041Z","end":"2026-05-09T17:45:13.262135Z","steps":["trace[1073704346] 'process raft request' (duration: 112.834997ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:13.263657Z","caller":"traceutil/trace.go:171","msg":"trace[1636287397] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"103.086163ms","start":"2026-05-09T17:45:13.160524Z","end":"2026-05-09T17:45:13.26361Z","steps":["trace[1636287397] 'process raft request' (duration: 102.869579ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:13.44493Z","caller":"traceutil/trace.go:171","msg":"trace[541820531] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"176.382302ms","start":"2026-05-09T17:45:13.268501Z","end":"2026-05-09T17:45:13.444883Z","steps":["trace[541820531] 'process raft request' (duration: 175.698319ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:13.826224Z","caller":"traceutil/trace.go:171","msg":"trace[1438737812] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"137.077851ms","start":"2026-05-09T17:45:13.689106Z","end":"2026-05-09T17:45:13.826184Z","steps":["trace[1438737812] 'process raft request' (duration: 136.848655ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:14.387079Z","caller":"traceutil/trace.go:171","msg":"trace[1720690375] linearizableReadLoop","detail":"{readStateIndex:26608; appliedIndex:26607; }","duration":"145.722892ms","start":"2026-05-09T17:45:14.241326Z","end":"2026-05-09T17:45:14.387049Z","steps":["trace[1720690375] 'read index received' (duration: 142.241323ms)","trace[1720690375] 'applied index is now lower than readState.Index' (duration: 3.479689ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:14.387167Z","caller":"traceutil/trace.go:171","msg":"trace[70593875] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"159.706111ms","start":"2026-05-09T17:45:14.227421Z","end":"2026-05-09T17:45:14.387127Z","steps":["trace[70593875] 'process raft request' (duration: 156.16281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:14.387324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.987708ms","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-05-09T17:45:14.387359Z","caller":"traceutil/trace.go:171","msg":"trace[408764606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25756; }","duration":"146.09384ms","start":"2026-05-09T17:45:14.241256Z","end":"2026-05-09T17:45:14.38735Z","steps":["trace[408764606] 'agreement among raft nodes before linearized reading' (duration: 145.922267ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:14.611095Z","caller":"traceutil/trace.go:171","msg":"trace[1901441911] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"198.100297ms","start":"2026-05-09T17:45:14.412961Z","end":"2026-05-09T17:45:14.611061Z","steps":["trace[1901441911] 'process raft request' (duration: 197.889292ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:14.837714Z","caller":"traceutil/trace.go:171","msg":"trace[750037344] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"136.888697ms","start":"2026-05-09T17:45:14.700709Z","end":"2026-05-09T17:45:14.837598Z","steps":["trace[750037344] 'process raft request' (duration: 84.865691ms)","trace[750037344] 'compare' (duration: 51.762061ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:15.097758Z","caller":"traceutil/trace.go:171","msg":"trace[710499212] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"125.141702ms","start":"2026-05-09T17:45:14.972586Z","end":"2026-05-09T17:45:15.097728Z","steps":["trace[710499212] 'process raft request' (duration: 122.417049ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:15.40196Z","caller":"traceutil/trace.go:171","msg":"trace[516141587] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"132.506569ms","start":"2026-05-09T17:45:15.269421Z","end":"2026-05-09T17:45:15.401928Z","steps":["trace[516141587] 'process raft request' (duration: 118.166213ms)","trace[516141587] 'compare' (duration: 13.564721ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:15.427018Z","caller":"traceutil/trace.go:171","msg":"trace[78762183] linearizableReadLoop","detail":"{readStateIndex:26615; appliedIndex:26612; }","duration":"137.777384ms","start":"2026-05-09T17:45:15.289217Z","end":"2026-05-09T17:45:15.426994Z","steps":["trace[78762183] 'read index received' (duration: 98.085764ms)","trace[78762183] 'applied index is now lower than readState.Index' (duration: 39.69066ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:15.42721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.983178ms","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-05-09T17:45:15.427241Z","caller":"traceutil/trace.go:171","msg":"trace[934818102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25763; }","duration":"138.06088ms","start":"2026-05-09T17:45:15.289171Z","end":"2026-05-09T17:45:15.427232Z","steps":["trace[934818102] 'agreement among raft nodes before linearized reading' (duration: 137.915777ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:15.427471Z","caller":"traceutil/trace.go:171","msg":"trace[152065060] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"149.375395ms","start":"2026-05-09T17:45:15.278086Z","end":"2026-05-09T17:45:15.427461Z","steps":["trace[152065060] 'process raft request' (duration: 148.768413ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:15.427568Z","caller":"traceutil/trace.go:171","msg":"trace[781146672] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"149.257023ms","start":"2026-05-09T17:45:15.278304Z","end":"2026-05-09T17:45:15.427561Z","steps":["trace[781146672] 'process raft request' (duration: 148.654331ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:15.549728Z","caller":"traceutil/trace.go:171","msg":"trace[27660013] linearizableReadLoop","detail":"{readStateIndex:26616; appliedIndex:26615; }","duration":"100.492722ms","start":"2026-05-09T17:45:15.449213Z","end":"2026-05-09T17:45:15.549706Z","steps":["trace[27660013] 'read index received' (duration: 84.022323ms)","trace[27660013] 'applied index is now lower than readState.Index' (duration: 16.469399ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:15.550024Z","caller":"traceutil/trace.go:171","msg":"trace[2064200658] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"115.275597ms","start":"2026-05-09T17:45:15.434732Z","end":"2026-05-09T17:45:15.550008Z","steps":["trace[2064200658] 'process raft request' (duration: 98.595944ms)","trace[2064200658] 'compare' (duration: 16.005999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:15.550499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.259758ms","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-05-09T17:45:15.5506Z","caller":"traceutil/trace.go:171","msg":"trace[1704015926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25764; }","duration":"101.42452ms","start":"2026-05-09T17:45:15.449153Z","end":"2026-05-09T17:45:15.550578Z","steps":["trace[1704015926] 'agreement among raft nodes before linearized reading' (duration: 101.116984ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:17.55512Z","caller":"traceutil/trace.go:171","msg":"trace[2061172400] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"147.572319ms","start":"2026-05-09T17:45:17.407521Z","end":"2026-05-09T17:45:17.555093Z","steps":["trace[2061172400] 'process raft request' (duration: 146.877225ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:17.71088Z","caller":"traceutil/trace.go:171","msg":"trace[436265715] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"276.263022ms","start":"2026-05-09T17:45:17.434588Z","end":"2026-05-09T17:45:17.710851Z","steps":["trace[436265715] 'process raft request' (duration: 264.222023ms)","trace[436265715] 'compare' (duration: 11.650812ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:17.711257Z","caller":"traceutil/trace.go:171","msg":"trace[1478792063] linearizableReadLoop","detail":"{readStateIndex:26634; appliedIndex:26630; }","duration":"183.180578ms","start":"2026-05-09T17:45:17.528052Z","end":"2026-05-09T17:45:17.711232Z","steps":["trace[1478792063] 'read index received' (duration: 26.313763ms)","trace[1478792063] 'applied index is now lower than readState.Index' (duration: 156.865545ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:17.711238Z","caller":"traceutil/trace.go:171","msg":"trace[1975860629] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"273.544368ms","start":"2026-05-09T17:45:17.437683Z","end":"2026-05-09T17:45:17.711227Z","steps":["trace[1975860629] 'process raft request' (duration: 273.115089ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:17.711486Z","caller":"traceutil/trace.go:171","msg":"trace[158297935] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"227.861148ms","start":"2026-05-09T17:45:17.483577Z","end":"2026-05-09T17:45:17.711438Z","steps":["trace[158297935] 'process raft request' (duration: 227.545102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:17.711539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.463764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:17.711583Z","caller":"traceutil/trace.go:171","msg":"trace[1712923554] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:25782; }","duration":"183.561717ms","start":"2026-05-09T17:45:17.528005Z","end":"2026-05-09T17:45:17.711567Z","steps":["trace[1712923554] 'agreement among raft nodes before linearized reading' (duration: 183.416533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:17.711796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.075679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T17:45:17.711836Z","caller":"traceutil/trace.go:171","msg":"trace[520064940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25782; }","duration":"157.178481ms","start":"2026-05-09T17:45:17.55465Z","end":"2026-05-09T17:45:17.711828Z","steps":["trace[520064940] 'agreement among raft nodes before linearized reading' (duration: 157.036628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:17.711961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.697021ms","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-05-09T17:45:17.71205Z","caller":"traceutil/trace.go:171","msg":"trace[1412186094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25782; }","duration":"152.821365ms","start":"2026-05-09T17:45:17.559211Z","end":"2026-05-09T17:45:17.712032Z","steps":["trace[1412186094] 'agreement among raft nodes before linearized reading' (duration: 152.519169ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:17.938282Z","caller":"traceutil/trace.go:171","msg":"trace[1567020201] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"180.349862ms","start":"2026-05-09T17:45:17.757888Z","end":"2026-05-09T17:45:17.938237Z","steps":["trace[1567020201] 'process raft request' (duration: 130.801405ms)","trace[1567020201] 'compare' (duration: 49.182959ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:18.113667Z","caller":"traceutil/trace.go:171","msg":"trace[1743610102] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"169.874254ms","start":"2026-05-09T17:45:17.943761Z","end":"2026-05-09T17:45:18.113635Z","steps":["trace[1743610102] 'process raft request' (duration: 169.68223ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:18.345421Z","caller":"traceutil/trace.go:171","msg":"trace[1169270888] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"116.282776ms","start":"2026-05-09T17:45:18.229093Z","end":"2026-05-09T17:45:18.345375Z","steps":["trace[1169270888] 'process raft request' (duration: 102.183376ms)","trace[1169270888] 'compare' (duration: 13.064409ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:18.671745Z","caller":"traceutil/trace.go:171","msg":"trace[666296906] transaction","detail":"{read_only:false; number_of_response:1; response_revision:25792; }","duration":"105.153914ms","start":"2026-05-09T17:45:18.56656Z","end":"2026-05-09T17:45:18.671714Z","steps":["trace[666296906] 'process raft request' (duration: 104.92535ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:24.493738Z","caller":"traceutil/trace.go:171","msg":"trace[653658350] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"121.656224ms","start":"2026-05-09T17:45:24.372046Z","end":"2026-05-09T17:45:24.493702Z","steps":["trace[653658350] 'process raft request' (duration: 121.448479ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:24.494536Z","caller":"traceutil/trace.go:171","msg":"trace[500091946] linearizableReadLoop","detail":"{readStateIndex:26756; appliedIndex:26756; }","duration":"111.901439ms","start":"2026-05-09T17:45:24.38262Z","end":"2026-05-09T17:45:24.494522Z","steps":["trace[500091946] 'read index received' (duration: 111.894888ms)","trace[500091946] 'applied index is now lower than readState.Index' (duration: 5.861µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:24.494897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.245506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-044c0c13-cc79-41fe-8cd4-eed0c94a57e5\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-09T17:45:24.495032Z","caller":"traceutil/trace.go:171","msg":"trace[255166680] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-044c0c13-cc79-41fe-8cd4-eed0c94a57e5; range_end:; response_count:1; response_revision:25901; }","duration":"112.45149ms","start":"2026-05-09T17:45:24.382553Z","end":"2026-05-09T17:45:24.495004Z","steps":["trace[255166680] 'agreement among raft nodes before linearized reading' (duration: 112.141984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:24.726944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.001228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:24.72705Z","caller":"traceutil/trace.go:171","msg":"trace[2130060903] linearizableReadLoop","detail":"{readStateIndex:26757; appliedIndex:26756; }","duration":"232.375719ms","start":"2026-05-09T17:45:24.494657Z","end":"2026-05-09T17:45:24.727033Z","steps":["trace[2130060903] 'read index received' (duration: 94.204967ms)","trace[2130060903] 'applied index is now lower than readState.Index' (duration: 138.169692ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:24.727132Z","caller":"traceutil/trace.go:171","msg":"trace[2127782962] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"295.68707ms","start":"2026-05-09T17:45:24.431429Z","end":"2026-05-09T17:45:24.727116Z","steps":["trace[2127782962] 'process raft request' (duration: 157.412086ms)","trace[2127782962] 'compare' (duration: 137.877406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:24.727327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.679001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:45:24.727426Z","caller":"traceutil/trace.go:171","msg":"trace[73776442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25902; }","duration":"282.791613ms","start":"2026-05-09T17:45:24.444607Z","end":"2026-05-09T17:45:24.727398Z","steps":["trace[73776442] 'agreement among raft nodes before linearized reading' (duration: 282.532678ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:24.780178Z","caller":"traceutil/trace.go:171","msg":"trace[135103240] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"277.765542ms","start":"2026-05-09T17:45:24.502379Z","end":"2026-05-09T17:45:24.780145Z","steps":["trace[135103240] 'process raft request' (duration: 277.564138ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:25.029681Z","caller":"traceutil/trace.go:171","msg":"trace[1486460420] linearizableReadLoop","detail":"{readStateIndex:26759; appliedIndex:26757; }","duration":"302.576847ms","start":"2026-05-09T17:45:24.727072Z","end":"2026-05-09T17:45:25.029649Z","steps":["trace[1486460420] 'read index received' (duration: 52.839283ms)","trace[1486460420] 'applied index is now lower than readState.Index' (duration: 249.735864ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:25.029749Z","caller":"traceutil/trace.go:171","msg":"trace[1623072232] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"324.551225ms","start":"2026-05-09T17:45:24.70515Z","end":"2026-05-09T17:45:25.029701Z","steps":["trace[1623072232] 'process raft request' (duration: 324.217148ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:25.029818Z","caller":"traceutil/trace.go:171","msg":"trace[90185471] transaction","detail":"{read_only:false; response_revision:25905; number_of_response:1; }","duration":"298.222681ms","start":"2026-05-09T17:45:24.731549Z","end":"2026-05-09T17:45:25.029771Z","steps":["trace[90185471] 'process raft request' (duration: 298.039808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:25.02995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:24.705122Z","time spent":"324.724818ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:45:25.030099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.754343ms","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-05-09T17:45:25.030158Z","caller":"traceutil/trace.go:171","msg":"trace[1707271087] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25905; }","duration":"533.869455ms","start":"2026-05-09T17:45:24.496272Z","end":"2026-05-09T17:45:25.030141Z","steps":["trace[1707271087] 'agreement among raft nodes before linearized reading' (duration: 533.528078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:25.030226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:24.496211Z","time spent":"534.004007ms","remote":"127.0.0.1:41046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":242,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:45:25.278811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.524339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:25.279044Z","caller":"traceutil/trace.go:171","msg":"trace[1279783683] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"397.411236ms","start":"2026-05-09T17:45:24.881588Z","end":"2026-05-09T17:45:25.278999Z","steps":["trace[1279783683] 'process raft request' (duration: 259.581171ms)","trace[1279783683] 'compare' (duration: 137.349696ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:25.279096Z","caller":"traceutil/trace.go:171","msg":"trace[2032054587] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"148.030189ms","start":"2026-05-09T17:45:25.131044Z","end":"2026-05-09T17:45:25.279074Z","steps":["trace[2032054587] 'process raft request' (duration: 147.948977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:25.279145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.10376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:25.279107Z","caller":"traceutil/trace.go:171","msg":"trace[1011586741] linearizableReadLoop","detail":"{readStateIndex:26761; appliedIndex:26760; }","duration":"249.315247ms","start":"2026-05-09T17:45:25.029759Z","end":"2026-05-09T17:45:25.279074Z","steps":["trace[1011586741] 'read index received' (duration: 111.42593ms)","trace[1011586741] 'applied index is now lower than readState.Index' (duration: 137.885777ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:25.279176Z","caller":"traceutil/trace.go:171","msg":"trace[161951799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25907; }","duration":"400.194242ms","start":"2026-05-09T17:45:24.87897Z","end":"2026-05-09T17:45:25.279164Z","steps":["trace[161951799] 'agreement among raft nodes before linearized reading' (duration: 400.130121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:25.279195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:24.878938Z","time spent":"400.251373ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T17:45:25.279223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:24.881563Z","time spent":"397.557009ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:45:25.279333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.449461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:45:25.279333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.493721ms","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-05-09T17:45:25.279418Z","caller":"traceutil/trace.go:171","msg":"trace[1671807912] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25907; }","duration":"222.587674ms","start":"2026-05-09T17:45:25.05681Z","end":"2026-05-09T17:45:25.279398Z","steps":["trace[1671807912] 'agreement among raft nodes before linearized reading' (duration: 222.436551ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:25.279426Z","caller":"traceutil/trace.go:171","msg":"trace[833224302] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25907; }","duration":"482.645964ms","start":"2026-05-09T17:45:24.796762Z","end":"2026-05-09T17:45:25.279408Z","steps":["trace[833224302] 'agreement among raft nodes before linearized reading' (duration: 482.45076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:25.279467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:24.796747Z","time spent":"482.709315ms","remote":"127.0.0.1:33894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:45:25.442418Z","caller":"traceutil/trace.go:171","msg":"trace[1742205093] linearizableReadLoop","detail":"{readStateIndex:26763; appliedIndex:26762; }","duration":"101.831148ms","start":"2026-05-09T17:45:25.340554Z","end":"2026-05-09T17:45:25.442385Z","steps":["trace[1742205093] 'read index received' (duration: 79.135256ms)","trace[1742205093] 'applied index is now lower than readState.Index' (duration: 22.694212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:25.442689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.102153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-09T17:45:25.44258Z","caller":"traceutil/trace.go:171","msg":"trace[1384175753] transaction","detail":"{read_only:false; response_revision:25908; number_of_response:1; }","duration":"104.652014ms","start":"2026-05-09T17:45:25.337881Z","end":"2026-05-09T17:45:25.442533Z","steps":["trace[1384175753] 'process raft request' (duration: 81.765218ms)","trace[1384175753] 'compare' (duration: 22.55429ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:25.442731Z","caller":"traceutil/trace.go:171","msg":"trace[608939590] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25908; }","duration":"102.198806ms","start":"2026-05-09T17:45:25.340521Z","end":"2026-05-09T17:45:25.44272Z","steps":["trace[608939590] 'agreement among raft nodes before linearized reading' (duration: 101.983292ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:27.858283Z","caller":"traceutil/trace.go:171","msg":"trace[2014292857] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"107.581994ms","start":"2026-05-09T17:45:27.75066Z","end":"2026-05-09T17:45:27.858242Z","steps":["trace[2014292857] 'process raft request' (duration: 107.256227ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:32.875235Z","caller":"traceutil/trace.go:171","msg":"trace[732228709] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"116.353207ms","start":"2026-05-09T17:45:32.758863Z","end":"2026-05-09T17:45:32.875216Z","steps":["trace[732228709] 'process raft request' (duration: 116.218054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:33.148318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.843747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:33.148444Z","caller":"traceutil/trace.go:171","msg":"trace[1751728286] linearizableReadLoop","detail":"{readStateIndex:26844; appliedIndex:26843; }","duration":"265.773894ms","start":"2026-05-09T17:45:32.88265Z","end":"2026-05-09T17:45:33.148423Z","steps":["trace[1751728286] 'read index received' (duration: 134.600581ms)","trace[1751728286] 'applied index is now lower than readState.Index' (duration: 131.169263ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:33.148521Z","caller":"traceutil/trace.go:171","msg":"trace[56544241] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"344.715327ms","start":"2026-05-09T17:45:32.803796Z","end":"2026-05-09T17:45:33.148511Z","steps":["trace[56544241] 'process raft request' (duration: 213.568704ms)","trace[56544241] 'compare' (duration: 130.599312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:33.148559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:32.803773Z","time spent":"344.766727ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:45:33.148829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.146351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-09T17:45:33.148955Z","caller":"traceutil/trace.go:171","msg":"trace[1390909377] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25988; }","duration":"266.289884ms","start":"2026-05-09T17:45:32.882604Z","end":"2026-05-09T17:45:33.148894Z","steps":["trace[1390909377] 'agreement among raft nodes before linearized reading' (duration: 266.001209ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:33.294071Z","caller":"traceutil/trace.go:171","msg":"trace[1110550917] linearizableReadLoop","detail":"{readStateIndex:26845; appliedIndex:26844; }","duration":"145.577399ms","start":"2026-05-09T17:45:33.148463Z","end":"2026-05-09T17:45:33.29404Z","steps":["trace[1110550917] 'read index received' (duration: 145.266353ms)","trace[1110550917] 'applied index is now lower than readState.Index' (duration: 309.866µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:33.29428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.973256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:33.294292Z","caller":"traceutil/trace.go:171","msg":"trace[1026025845] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"239.047962ms","start":"2026-05-09T17:45:33.05521Z","end":"2026-05-09T17:45:33.294258Z","steps":["trace[1026025845] 'process raft request' (duration: 238.600673ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:33.294379Z","caller":"traceutil/trace.go:171","msg":"trace[1767457519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25989; }","duration":"408.095239ms","start":"2026-05-09T17:45:32.886223Z","end":"2026-05-09T17:45:33.294318Z","steps":["trace[1767457519] 'agreement among raft nodes before linearized reading' (duration: 407.982217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:33.294372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.651512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-05-09T17:45:33.294403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.585242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-09T17:45:33.294419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.158494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-05-09T17:45:33.294439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:32.8862Z","time spent":"408.224792ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T17:45:33.294452Z","caller":"traceutil/trace.go:171","msg":"trace[1152851483] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25989; }","duration":"249.788775ms","start":"2026-05-09T17:45:33.044645Z","end":"2026-05-09T17:45:33.294434Z","steps":["trace[1152851483] 'agreement among raft nodes before linearized reading' (duration: 249.470079ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:33.294456Z","caller":"traceutil/trace.go:171","msg":"trace[1450668931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25989; }","duration":"228.740456ms","start":"2026-05-09T17:45:33.0657Z","end":"2026-05-09T17:45:33.294441Z","steps":["trace[1450668931] 'agreement among raft nodes before linearized reading' (duration: 228.394399ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:33.294485Z","caller":"traceutil/trace.go:171","msg":"trace[573869869] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25989; }","duration":"351.264327ms","start":"2026-05-09T17:45:32.943203Z","end":"2026-05-09T17:45:33.294467Z","steps":["trace[573869869] 'agreement among raft nodes before linearized reading' (duration: 351.013462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:33.294536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.777166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T17:45:33.29454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:45:32.943181Z","time spent":"351.346368ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-09T17:45:33.294573Z","caller":"traceutil/trace.go:171","msg":"trace[1141378836] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25989; }","duration":"110.833147ms","start":"2026-05-09T17:45:33.183728Z","end":"2026-05-09T17:45:33.294561Z","steps":["trace[1141378836] 'agreement among raft nodes before linearized reading' (duration: 110.755095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:33.294665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.696482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e435aed0-0be3-4247-98bb-e615b1ebaa04\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-05-09T17:45:33.294759Z","caller":"traceutil/trace.go:171","msg":"trace[932155966] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e435aed0-0be3-4247-98bb-e615b1ebaa04; range_end:; response_count:1; response_revision:25989; }","duration":"141.890735ms","start":"2026-05-09T17:45:33.152845Z","end":"2026-05-09T17:45:33.294736Z","steps":["trace[932155966] 'agreement among raft nodes before linearized reading' (duration: 141.595979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:33.595404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.757343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:33.595613Z","caller":"traceutil/trace.go:171","msg":"trace[1150615415] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"294.422514ms","start":"2026-05-09T17:45:33.301149Z","end":"2026-05-09T17:45:33.595571Z","steps":["trace[1150615415] 'process raft request' (duration: 142.377805ms)","trace[1150615415] 'compare' (duration: 151.582879ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:33.595767Z","caller":"traceutil/trace.go:171","msg":"trace[577885318] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"293.327113ms","start":"2026-05-09T17:45:33.302428Z","end":"2026-05-09T17:45:33.595755Z","steps":["trace[577885318] 'process raft request' (duration: 293.122279ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:33.595785Z","caller":"traceutil/trace.go:171","msg":"trace[1334377567] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"289.330764ms","start":"2026-05-09T17:45:33.306431Z","end":"2026-05-09T17:45:33.595762Z","steps":["trace[1334377567] 'process raft request' (duration: 289.205141ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:33.59659Z","caller":"traceutil/trace.go:171","msg":"trace[1668519874] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"285.797472ms","start":"2026-05-09T17:45:33.310761Z","end":"2026-05-09T17:45:33.596559Z","steps":["trace[1668519874] 'process raft request' (duration: 284.927145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:33.763113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.916784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T17:45:33.763258Z","caller":"traceutil/trace.go:171","msg":"trace[1229155650] linearizableReadLoop","detail":"{readStateIndex:26850; appliedIndex:26849; }","duration":"156.930616ms","start":"2026-05-09T17:45:33.606304Z","end":"2026-05-09T17:45:33.763235Z","steps":["trace[1229155650] 'read index received' (duration: 128.934µs)","trace[1229155650] 'applied index is now lower than readState.Index' (duration: 156.799642ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:33.763478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.169992ms","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-05-09T17:45:33.76352Z","caller":"traceutil/trace.go:171","msg":"trace[9920028] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25993; }","duration":"157.228203ms","start":"2026-05-09T17:45:33.606279Z","end":"2026-05-09T17:45:33.763508Z","steps":["trace[9920028] 'agreement among raft nodes before linearized reading' (duration: 157.022779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:33.763811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.546496ms","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":"info","ts":"2026-05-09T17:45:33.763914Z","caller":"traceutil/trace.go:171","msg":"trace[1229288047] 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:25993; }","duration":"196.72065ms","start":"2026-05-09T17:45:33.567163Z","end":"2026-05-09T17:45:33.763884Z","steps":["trace[1229288047] 'agreement among raft nodes before linearized reading' (duration: 29.406727ms)","trace[1229288047] 'count revisions from in-memory index tree' (duration: 167.15061ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:48.151072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.498171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:48.151438Z","caller":"traceutil/trace.go:171","msg":"trace[1220180762] linearizableReadLoop","detail":"{readStateIndex:26963; appliedIndex:26961; }","duration":"145.681262ms","start":"2026-05-09T17:45:48.005733Z","end":"2026-05-09T17:45:48.151414Z","steps":["trace[1220180762] 'read index received' (duration: 39.886185ms)","trace[1220180762] 'applied index is now lower than readState.Index' (duration: 105.793117ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:48.151429Z","caller":"traceutil/trace.go:171","msg":"trace[202575721] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"242.888218ms","start":"2026-05-09T17:45:47.908482Z","end":"2026-05-09T17:45:48.15137Z","steps":["trace[202575721] 'process raft request' (duration: 136.965708ms)","trace[202575721] 'compare' (duration: 105.320637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:48.151768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.003228ms","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-05-09T17:45:48.151857Z","caller":"traceutil/trace.go:171","msg":"trace[1299207288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26104; }","duration":"146.166941ms","start":"2026-05-09T17:45:48.005671Z","end":"2026-05-09T17:45:48.151838Z","steps":["trace[1299207288] 'agreement among raft nodes before linearized reading' (duration: 145.866345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:48.15188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.034208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T17:45:48.151977Z","caller":"traceutil/trace.go:171","msg":"trace[724074612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26104; }","duration":"146.163532ms","start":"2026-05-09T17:45:48.005791Z","end":"2026-05-09T17:45:48.151955Z","steps":["trace[724074612] 'agreement among raft nodes before linearized reading' (duration: 145.864436ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.152242Z","caller":"traceutil/trace.go:171","msg":"trace[394592366] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"224.131275ms","start":"2026-05-09T17:45:47.928028Z","end":"2026-05-09T17:45:48.152159Z","steps":["trace[394592366] 'process raft request' (duration: 223.293578ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.447857Z","caller":"traceutil/trace.go:171","msg":"trace[1199040260] linearizableReadLoop","detail":"{readStateIndex:26966; appliedIndex:26963; }","duration":"283.15885ms","start":"2026-05-09T17:45:48.164677Z","end":"2026-05-09T17:45:48.447836Z","steps":["trace[1199040260] 'read index received' (duration: 127.839446ms)","trace[1199040260] 'applied index is now lower than readState.Index' (duration: 155.318404ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:48.447928Z","caller":"traceutil/trace.go:171","msg":"trace[1922502181] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"291.031716ms","start":"2026-05-09T17:45:48.156854Z","end":"2026-05-09T17:45:48.447885Z","steps":["trace[1922502181] 'process raft request' (duration: 272.535628ms)","trace[1922502181] 'compare' (duration: 18.233122ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:48.448028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.342134ms","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-05-09T17:45:48.448068Z","caller":"traceutil/trace.go:171","msg":"trace[727773570] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26106; }","duration":"283.435657ms","start":"2026-05-09T17:45:48.16462Z","end":"2026-05-09T17:45:48.448055Z","steps":["trace[727773570] 'agreement among raft nodes before linearized reading' (duration: 283.342514ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.448059Z","caller":"traceutil/trace.go:171","msg":"trace[761251780] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"291.097667ms","start":"2026-05-09T17:45:48.156856Z","end":"2026-05-09T17:45:48.447954Z","steps":["trace[761251780] 'process raft request' (duration: 290.924304ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.462611Z","caller":"traceutil/trace.go:171","msg":"trace[1240643439] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"158.218212ms","start":"2026-05-09T17:45:48.30434Z","end":"2026-05-09T17:45:48.462558Z","steps":["trace[1240643439] 'process raft request' (duration: 157.939596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:48.462664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.03379ms","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-05-09T17:45:48.462738Z","caller":"traceutil/trace.go:171","msg":"trace[1416311050] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:26107; }","duration":"264.170993ms","start":"2026-05-09T17:45:48.198545Z","end":"2026-05-09T17:45:48.462716Z","steps":["trace[1416311050] 'agreement among raft nodes before linearized reading' (duration: 263.974609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:48.462852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.123572ms","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-05-09T17:45:48.462891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.777762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T17:45:48.462973Z","caller":"traceutil/trace.go:171","msg":"trace[1713553979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26107; }","duration":"168.909995ms","start":"2026-05-09T17:45:48.294042Z","end":"2026-05-09T17:45:48.462952Z","steps":["trace[1713553979] 'agreement among raft nodes before linearized reading' (duration: 168.573528ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.462969Z","caller":"traceutil/trace.go:171","msg":"trace[1444777928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26107; }","duration":"139.290455ms","start":"2026-05-09T17:45:48.323653Z","end":"2026-05-09T17:45:48.462944Z","steps":["trace[1444777928] 'agreement among raft nodes before linearized reading' (duration: 138.907058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:48.463123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.851827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-09T17:45:48.46317Z","caller":"traceutil/trace.go:171","msg":"trace[917749721] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26107; }","duration":"131.919918ms","start":"2026-05-09T17:45:48.331239Z","end":"2026-05-09T17:45:48.463159Z","steps":["trace[917749721] 'agreement among raft nodes before linearized reading' (duration: 131.758015ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.579227Z","caller":"traceutil/trace.go:171","msg":"trace[368318703] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"111.341747ms","start":"2026-05-09T17:45:48.467846Z","end":"2026-05-09T17:45:48.579188Z","steps":["trace[368318703] 'process raft request' (duration: 108.211075ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.579548Z","caller":"traceutil/trace.go:171","msg":"trace[2140884844] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"111.235225ms","start":"2026-05-09T17:45:48.468265Z","end":"2026-05-09T17:45:48.579501Z","steps":["trace[2140884844] 'process raft request' (duration: 110.769905ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:48.579582Z","caller":"traceutil/trace.go:171","msg":"trace[1559737607] linearizableReadLoop","detail":"{readStateIndex:26969; appliedIndex:26967; }","duration":"110.340087ms","start":"2026-05-09T17:45:48.468738Z","end":"2026-05-09T17:45:48.579078Z","steps":["trace[1559737607] 'read index received' (duration: 107.318237ms)","trace[1559737607] 'applied index is now lower than readState.Index' (duration: 3.02093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:48.579972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.070743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T17:45:48.580068Z","caller":"traceutil/trace.go:171","msg":"trace[96987003] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26109; }","duration":"111.332868ms","start":"2026-05-09T17:45:48.468714Z","end":"2026-05-09T17:45:48.580047Z","steps":["trace[96987003] 'agreement among raft nodes before linearized reading' (duration: 110.901809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:48.859227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.407266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:48.859369Z","caller":"traceutil/trace.go:171","msg":"trace[548100879] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"253.879497ms","start":"2026-05-09T17:45:48.605465Z","end":"2026-05-09T17:45:48.859344Z","steps":["trace[548100879] 'process raft request' (duration: 112.246966ms)","trace[548100879] 'compare' (duration: 141.253073ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:48.861471Z","caller":"traceutil/trace.go:171","msg":"trace[913281513] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"252.030851ms","start":"2026-05-09T17:45:48.609395Z","end":"2026-05-09T17:45:48.861426Z","steps":["trace[913281513] 'process raft request' (duration: 251.819066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:49.140436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.133333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:49.140614Z","caller":"traceutil/trace.go:171","msg":"trace[878272585] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"203.266189ms","start":"2026-05-09T17:45:48.937316Z","end":"2026-05-09T17:45:49.140582Z","steps":["trace[878272585] 'process raft request' (duration: 72.595796ms)","trace[878272585] 'compare' (duration: 129.942228ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:49.532301Z","caller":"traceutil/trace.go:171","msg":"trace[1155097033] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"153.117191ms","start":"2026-05-09T17:45:49.379152Z","end":"2026-05-09T17:45:49.532269Z","steps":["trace[1155097033] 'process raft request' (duration: 152.938166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:49.851316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.20919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:49.851554Z","caller":"traceutil/trace.go:171","msg":"trace[1642987086] linearizableReadLoop","detail":"{readStateIndex:26975; appliedIndex:26973; }","duration":"180.644219ms","start":"2026-05-09T17:45:49.67089Z","end":"2026-05-09T17:45:49.851534Z","steps":["trace[1642987086] 'read index received' (duration: 7.182583ms)","trace[1642987086] 'applied index is now lower than readState.Index' (duration: 173.460335ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:49.851537Z","caller":"traceutil/trace.go:171","msg":"trace[2130661066] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"192.716558ms","start":"2026-05-09T17:45:49.658783Z","end":"2026-05-09T17:45:49.851499Z","steps":["trace[2130661066] 'process raft request' (duration: 19.219573ms)","trace[2130661066] 'compare' (duration: 173.029806ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:49.852019Z","caller":"traceutil/trace.go:171","msg":"trace[1894892194] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"189.27721ms","start":"2026-05-09T17:45:49.662705Z","end":"2026-05-09T17:45:49.851982Z","steps":["trace[1894892194] 'process raft request' (duration: 188.736979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:49.852104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.19149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:45:49.852152Z","caller":"traceutil/trace.go:171","msg":"trace[2070176331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26115; }","duration":"181.257301ms","start":"2026-05-09T17:45:49.670883Z","end":"2026-05-09T17:45:49.85214Z","steps":["trace[2070176331] 'agreement among raft nodes before linearized reading' (duration: 181.077848ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:50.071921Z","caller":"traceutil/trace.go:171","msg":"trace[1441298577] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"214.257728ms","start":"2026-05-09T17:45:49.857617Z","end":"2026-05-09T17:45:50.071875Z","steps":["trace[1441298577] 'process raft request' (duration: 192.352251ms)","trace[1441298577] 'compare' (duration: 21.724593ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:50.071994Z","caller":"traceutil/trace.go:171","msg":"trace[268290204] linearizableReadLoop","detail":"{readStateIndex:26976; appliedIndex:26975; }","duration":"195.835091ms","start":"2026-05-09T17:45:49.876106Z","end":"2026-05-09T17:45:50.071941Z","steps":["trace[268290204] 'read index received' (duration: 173.864603ms)","trace[268290204] 'applied index is now lower than readState.Index' (duration: 21.967008ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:50.071967Z","caller":"traceutil/trace.go:171","msg":"trace[963780002] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"160.806993ms","start":"2026-05-09T17:45:49.911132Z","end":"2026-05-09T17:45:50.071939Z","steps":["trace[963780002] 'process raft request' (duration: 160.743612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:50.072169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.044235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:50.072262Z","caller":"traceutil/trace.go:171","msg":"trace[582974224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26117; }","duration":"196.184758ms","start":"2026-05-09T17:45:49.876056Z","end":"2026-05-09T17:45:50.072241Z","steps":["trace[582974224] 'agreement among raft nodes before linearized reading' (duration: 196.015604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:50.072263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.141163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:45:50.072317Z","caller":"traceutil/trace.go:171","msg":"trace[1441318193] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:26117; }","duration":"123.283156ms","start":"2026-05-09T17:45:49.94902Z","end":"2026-05-09T17:45:50.072303Z","steps":["trace[1441318193] 'agreement among raft nodes before linearized reading' (duration: 123.136513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:45:50.320707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.892239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:45:50.320902Z","caller":"traceutil/trace.go:171","msg":"trace[528922040] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"161.856424ms","start":"2026-05-09T17:45:50.159023Z","end":"2026-05-09T17:45:50.320879Z","steps":["trace[528922040] 'process raft request' (duration: 161.793863ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:50.321119Z","caller":"traceutil/trace.go:171","msg":"trace[1618274635] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"162.698901ms","start":"2026-05-09T17:45:50.158377Z","end":"2026-05-09T17:45:50.321076Z","steps":["trace[1618274635] 'process raft request' (duration: 57.337693ms)","trace[1618274635] 'compare' (duration: 104.718325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:45:55.720131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.610393ms","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-05-09T17:45:55.720269Z","caller":"traceutil/trace.go:171","msg":"trace[1609330853] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26168; }","duration":"106.797108ms","start":"2026-05-09T17:45:55.613436Z","end":"2026-05-09T17:45:55.720233Z","steps":["trace[1609330853] 'range keys from in-memory index tree' (duration: 106.528342ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:55.997738Z","caller":"traceutil/trace.go:171","msg":"trace[1319152307] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"125.386836ms","start":"2026-05-09T17:45:55.872311Z","end":"2026-05-09T17:45:55.997698Z","steps":["trace[1319152307] 'process raft request' (duration: 125.153792ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:56.099254Z","caller":"traceutil/trace.go:171","msg":"trace[995644188] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"226.37262ms","start":"2026-05-09T17:45:55.872855Z","end":"2026-05-09T17:45:56.099228Z","steps":["trace[995644188] 'process raft request' (duration: 202.670217ms)","trace[995644188] 'compare' (duration: 23.53764ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:45:56.473913Z","caller":"traceutil/trace.go:171","msg":"trace[838101947] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"130.490948ms","start":"2026-05-09T17:45:56.343381Z","end":"2026-05-09T17:45:56.473872Z","steps":["trace[838101947] 'process raft request' (duration: 130.109741ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:45:56.707291Z","caller":"traceutil/trace.go:171","msg":"trace[2085271584] transaction","detail":"{read_only:false; response_revision:26177; number_of_response:1; }","duration":"209.731938ms","start":"2026-05-09T17:45:56.497486Z","end":"2026-05-09T17:45:56.707218Z","steps":["trace[2085271584] 'process raft request' (duration: 139.966409ms)","trace[2085271584] 'compare' (duration: 69.480644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:46:01.928881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.653673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:46:01.929002Z","caller":"traceutil/trace.go:171","msg":"trace[110070778] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26212; }","duration":"248.831947ms","start":"2026-05-09T17:46:01.680141Z","end":"2026-05-09T17:46:01.928973Z","steps":["trace[110070778] 'count revisions from in-memory index tree' (duration: 248.512031ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:46:01.971162Z","caller":"traceutil/trace.go:171","msg":"trace[1966416021] linearizableReadLoop","detail":"{readStateIndex:27075; appliedIndex:27074; }","duration":"116.487081ms","start":"2026-05-09T17:46:01.854656Z","end":"2026-05-09T17:46:01.971143Z","steps":["trace[1966416021] 'read index received' (duration: 116.315678ms)","trace[1966416021] 'applied index is now lower than readState.Index' (duration: 170.533µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:46:01.971225Z","caller":"traceutil/trace.go:171","msg":"trace[762468223] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"152.177801ms","start":"2026-05-09T17:46:01.819017Z","end":"2026-05-09T17:46:01.971195Z","steps":["trace[762468223] 'process raft request' (duration: 151.936446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:46:01.971342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.670684ms","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-05-09T17:46:01.971374Z","caller":"traceutil/trace.go:171","msg":"trace[1029599181] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26213; }","duration":"116.786186ms","start":"2026-05-09T17:46:01.854577Z","end":"2026-05-09T17:46:01.971363Z","steps":["trace[1029599181] 'agreement among raft nodes before linearized reading' (duration: 116.648153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:46:02.335027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.422195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:46:02.335286Z","caller":"traceutil/trace.go:171","msg":"trace[136559386] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"310.843832ms","start":"2026-05-09T17:46:02.024413Z","end":"2026-05-09T17:46:02.335257Z","steps":["trace[136559386] 'process raft request' (duration: 125.06997ms)","trace[136559386] 'compare' (duration: 184.841112ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:46:02.335336Z","caller":"traceutil/trace.go:171","msg":"trace[331761530] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"209.603755ms","start":"2026-05-09T17:46:02.125711Z","end":"2026-05-09T17:46:02.335315Z","steps":["trace[331761530] 'process raft request' (duration: 209.444282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:46:02.335441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:46:02.024373Z","time spent":"310.980634ms","remote":"127.0.0.1:41100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:46:02.335585Z","caller":"traceutil/trace.go:171","msg":"trace[1278267399] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"146.344745ms","start":"2026-05-09T17:46:02.189199Z","end":"2026-05-09T17:46:02.335544Z","steps":["trace[1278267399] 'process raft request' (duration: 146.06868ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:46:02.335585Z","caller":"traceutil/trace.go:171","msg":"trace[1741610335] linearizableReadLoop","detail":"{readStateIndex:27078; appliedIndex:27075; }","duration":"125.164873ms","start":"2026-05-09T17:46:02.210374Z","end":"2026-05-09T17:46:02.335538Z","steps":["trace[1741610335] 'read index received' (duration: 106.191405ms)","trace[1741610335] 'applied index is now lower than readState.Index' (duration: 18.960098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:46:02.335746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.360997ms","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-05-09T17:46:02.335804Z","caller":"traceutil/trace.go:171","msg":"trace[526098350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26216; }","duration":"125.454539ms","start":"2026-05-09T17:46:02.210332Z","end":"2026-05-09T17:46:02.335786Z","steps":["trace[526098350] 'agreement among raft nodes before linearized reading' (duration: 125.280596ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:46:03.981338Z","caller":"traceutil/trace.go:171","msg":"trace[1233909376] linearizableReadLoop","detail":"{readStateIndex:27090; appliedIndex:27089; }","duration":"116.643113ms","start":"2026-05-09T17:46:03.864652Z","end":"2026-05-09T17:46:03.981295Z","steps":["trace[1233909376] 'read index received' (duration: 60.064726ms)","trace[1233909376] 'applied index is now lower than readState.Index' (duration: 56.576537ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:46:03.981415Z","caller":"traceutil/trace.go:171","msg":"trace[578108324] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"292.361193ms","start":"2026-05-09T17:46:03.688989Z","end":"2026-05-09T17:46:03.98135Z","steps":["trace[578108324] 'process raft request' (duration: 235.827447ms)","trace[578108324] 'compare' (duration: 56.23135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:46:03.98158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.917388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-09T17:46:03.98162Z","caller":"traceutil/trace.go:171","msg":"trace[146933345] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26227; }","duration":"117.031691ms","start":"2026-05-09T17:46:03.864577Z","end":"2026-05-09T17:46:03.981608Z","steps":["trace[146933345] 'agreement among raft nodes before linearized reading' (duration: 116.827716ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:46:08.236799Z","caller":"traceutil/trace.go:171","msg":"trace[366137969] linearizableReadLoop","detail":"{readStateIndex:27119; appliedIndex:27118; }","duration":"150.146021ms","start":"2026-05-09T17:46:08.086615Z","end":"2026-05-09T17:46:08.236761Z","steps":["trace[366137969] 'read index received' (duration: 149.855375ms)","trace[366137969] 'applied index is now lower than readState.Index' (duration: 288.416µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:46:08.237112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.460248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-09T17:46:08.237243Z","caller":"traceutil/trace.go:171","msg":"trace[509940687] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26255; }","duration":"150.635331ms","start":"2026-05-09T17:46:08.086583Z","end":"2026-05-09T17:46:08.237218Z","steps":["trace[509940687] 'agreement among raft nodes before linearized reading' (duration: 150.288794ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:46:08.625718Z","caller":"traceutil/trace.go:171","msg":"trace[379286824] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"100.214305ms","start":"2026-05-09T17:46:08.525468Z","end":"2026-05-09T17:46:08.625682Z","steps":["trace[379286824] 'process raft request' (duration: 27.421205ms)","trace[379286824] 'compare' (duration: 72.535255ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:46:12.972437Z","caller":"traceutil/trace.go:171","msg":"trace[915906983] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"110.523172ms","start":"2026-05-09T17:46:12.861886Z","end":"2026-05-09T17:46:12.972409Z","steps":["trace[915906983] 'process raft request' (duration: 106.465961ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:46:12.972651Z","caller":"traceutil/trace.go:171","msg":"trace[1770573238] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"108.128634ms","start":"2026-05-09T17:46:12.864484Z","end":"2026-05-09T17:46:12.972613Z","steps":["trace[1770573238] 'process raft request' (duration: 107.869829ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:46:33.163019Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006af6.wal"} {"level":"info","ts":"2026-05-09T17:49:24.916993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25255} {"level":"info","ts":"2026-05-09T17:49:25.057958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25255,"took":"135.514639ms","hash":2952536982,"current-db-size-bytes":62287872,"current-db-size":"62 MB","current-db-size-in-use-bytes":38096896,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-09T17:49:25.058026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2952536982,"revision":25255,"compact-revision":22006} {"level":"info","ts":"2026-05-09T17:50:58.024972Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:50:58.03122Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T17:50:58.031452Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T17:54:24.924475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28186} {"level":"info","ts":"2026-05-09T17:54:25.068796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28186,"took":"138.751667ms","hash":109198409,"current-db-size-bytes":62287872,"current-db-size":"62 MB","current-db-size-in-use-bytes":32260096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T17:54:25.068885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":109198409,"revision":28186,"compact-revision":25255} {"level":"info","ts":"2026-05-09T17:59:24.932319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30579} {"level":"info","ts":"2026-05-09T17:59:25.075311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30579,"took":"137.484811ms","hash":11554859,"current-db-size-bytes":62287872,"current-db-size":"62 MB","current-db-size-in-use-bytes":30150656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T17:59:25.075407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":11554859,"revision":30579,"compact-revision":28186} {"level":"info","ts":"2026-05-09T18:04:24.953764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32831} {"level":"info","ts":"2026-05-09T18:04:25.104682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32831,"took":"146.509438ms","hash":156970246,"current-db-size-bytes":63332352,"current-db-size":"63 MB","current-db-size-in-use-bytes":30412800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T18:04:25.104762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":156970246,"revision":32831,"compact-revision":30579} {"level":"info","ts":"2026-05-09T18:09:24.966069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35161} {"level":"info","ts":"2026-05-09T18:09:25.112502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35161,"took":"139.260704ms","hash":947095605,"current-db-size-bytes":63332352,"current-db-size":"63 MB","current-db-size-in-use-bytes":31944704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T18:09:25.11258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":947095605,"revision":35161,"compact-revision":32831} {"level":"info","ts":"2026-05-09T18:12:16.820351Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T18:12:16.825499Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-09T18:12:16.825712Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-09T18:14:24.974621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37454} {"level":"info","ts":"2026-05-09T18:14:25.107102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37454,"took":"125.284284ms","hash":1707869314,"current-db-size-bytes":64176128,"current-db-size":"64 MB","current-db-size-in-use-bytes":31744000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T18:14:25.107172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1707869314,"revision":37454,"compact-revision":35161}