{"level":"warn","ts":"2026-04-22T16:29:53.164136Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:29:53.164385Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219: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-04-22T16:29:53.164531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:29:53.164547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-22T16:29:53.164572Z","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-04-22T16:29:53.165415Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-04-22T16:29:53.165506Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219: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-04-22T16:29:53.169298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.516356ms"} {"level":"info","ts":"2026-04-22T16:29:53.176525Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-04-22T16:29:53.17663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:29:53.176653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-04-22T16:29:53.176668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T16:29:53.176681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-04-22T16:29:53.176728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-04-22T16:29:53.187374Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:29:53.189817Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:29:53.190803Z","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-04-22T16:29:53.193674Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T16:29:53.193923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T16:29:53.194153Z","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-04-22T16:29:53.194292Z","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-04-22T16:29:53.194355Z","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-04-22T16:29:53.196155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-04-22T16:29:53.196458Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-22T16:29:53.199074Z","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-04-22T16:29:53.19923Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-22T16:29:53.199288Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-22T16:29:53.199435Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T16:29:53.199479Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:29:53.977594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:29:53.977722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:29:53.977756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-04-22T16:29:53.977781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:29:53.977793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-22T16:29:53.977828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-04-22T16:29:53.97785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-22T16:29:53.979138Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:29:53.979889Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T16:29:53.979936Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:29:53.980037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:29:53.980296Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:29:53.980378Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:29:53.980888Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:29:53.981068Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:29:53.981137Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:29:53.98375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-04-22T16:29:53.984015Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:30:44.45703Z","caller":"traceutil/trace.go:171","msg":"trace[894447451] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"269.105787ms","start":"2026-04-22T16:30:44.187886Z","end":"2026-04-22T16:30:44.456992Z","steps":["trace[894447451] 'read index received' (duration: 43.33631ms)","trace[894447451] 'applied index is now lower than readState.Index' (duration: 225.767967ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:44.457178Z","caller":"traceutil/trace.go:171","msg":"trace[1755771717] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"290.111177ms","start":"2026-04-22T16:30:44.167011Z","end":"2026-04-22T16:30:44.457122Z","steps":["trace[1755771717] 'process raft request' (duration: 289.33379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:44.457571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.655539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T16:30:44.457683Z","caller":"traceutil/trace.go:171","msg":"trace[1655816444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"269.839943ms","start":"2026-04-22T16:30:44.187826Z","end":"2026-04-22T16:30:44.457666Z","steps":["trace[1655816444] 'agreement among raft nodes before linearized reading' (duration: 269.400703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:44.457593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.316748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T16:30:44.457781Z","caller":"traceutil/trace.go:171","msg":"trace[922839383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"127.592634ms","start":"2026-04-22T16:30:44.330149Z","end":"2026-04-22T16:30:44.457742Z","steps":["trace[922839383] 'agreement among raft nodes before linearized reading' (duration: 127.170745ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:44.621867Z","caller":"traceutil/trace.go:171","msg":"trace[1666065450] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"159.38165ms","start":"2026-04-22T16:30:44.462413Z","end":"2026-04-22T16:30:44.621795Z","steps":["trace[1666065450] 'process raft request' (duration: 88.801288ms)","trace[1666065450] 'compare' (duration: 70.174613ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:44.622011Z","caller":"traceutil/trace.go:171","msg":"trace[347792160] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"103.573725ms","start":"2026-04-22T16:30:44.518401Z","end":"2026-04-22T16:30:44.621974Z","steps":["trace[347792160] 'process raft request' (duration: 103.296509ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:44.622011Z","caller":"traceutil/trace.go:171","msg":"trace[359029297] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"159.092824ms","start":"2026-04-22T16:30:44.462887Z","end":"2026-04-22T16:30:44.62198Z","steps":["trace[359029297] 'process raft request' (duration: 158.751926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:44.86633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.976903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:30:44.866449Z","caller":"traceutil/trace.go:171","msg":"trace[704166662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"128.171848ms","start":"2026-04-22T16:30:44.738252Z","end":"2026-04-22T16:30:44.866424Z","steps":["trace[704166662] 'range keys from in-memory index tree' (duration: 127.854651ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:45.187345Z","caller":"traceutil/trace.go:171","msg":"trace[16770535] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"216.694421ms","start":"2026-04-22T16:30:44.97061Z","end":"2026-04-22T16:30:45.187305Z","steps":["trace[16770535] 'read index received' (duration: 216.534467ms)","trace[16770535] 'applied index is now lower than readState.Index' (duration: 158.504µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:45.187392Z","caller":"traceutil/trace.go:171","msg":"trace[1716999192] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"315.294381ms","start":"2026-04-22T16:30:44.872052Z","end":"2026-04-22T16:30:45.187346Z","steps":["trace[1716999192] 'process raft request' (duration: 315.019704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:45.18767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.994557ms","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-04-22T16:30:45.187731Z","caller":"traceutil/trace.go:171","msg":"trace[645831468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"217.12155ms","start":"2026-04-22T16:30:44.970592Z","end":"2026-04-22T16:30:45.187713Z","steps":["trace[645831468] 'agreement among raft nodes before linearized reading' (duration: 216.855534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:45.188416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:44.872025Z","time spent":"315.459476ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:30:45.226476Z","caller":"traceutil/trace.go:171","msg":"trace[1083052151] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"195.395234ms","start":"2026-04-22T16:30:45.031049Z","end":"2026-04-22T16:30:45.226444Z","steps":["trace[1083052151] 'process raft request' (duration: 194.671856ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:01.445794Z","caller":"traceutil/trace.go:171","msg":"trace[49937995] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:849; }","duration":"176.968992ms","start":"2026-04-22T16:31:01.268787Z","end":"2026-04-22T16:31:01.445756Z","steps":["trace[49937995] 'read index received' (duration: 176.575853ms)","trace[49937995] 'applied index is now lower than readState.Index' (duration: 391.559µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:31:01.445839Z","caller":"traceutil/trace.go:171","msg":"trace[1484249582] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"188.183137ms","start":"2026-04-22T16:31:01.257608Z","end":"2026-04-22T16:31:01.445791Z","steps":["trace[1484249582] 'process raft request' (duration: 187.935502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:01.446147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.316009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:31:01.446224Z","caller":"traceutil/trace.go:171","msg":"trace[1033464046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"177.474503ms","start":"2026-04-22T16:31:01.268731Z","end":"2026-04-22T16:31:01.446206Z","steps":["trace[1033464046] 'agreement among raft nodes before linearized reading' (duration: 177.214977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:01.446278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.615842ms","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-04-22T16:31:01.446343Z","caller":"traceutil/trace.go:171","msg":"trace[1912096400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"126.770577ms","start":"2026-04-22T16:31:01.319559Z","end":"2026-04-22T16:31:01.44633Z","steps":["trace[1912096400] 'agreement among raft nodes before linearized reading' (duration: 126.465529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:34.904614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.828708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:31:34.904713Z","caller":"traceutil/trace.go:171","msg":"trace[1918751556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"166.002491ms","start":"2026-04-22T16:31:34.738692Z","end":"2026-04-22T16:31:34.904695Z","steps":["trace[1918751556] 'range keys from in-memory index tree' (duration: 165.669854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:57.250334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.646141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:31:57.250489Z","caller":"traceutil/trace.go:171","msg":"trace[500075856] linearizableReadLoop","detail":"{readStateIndex:1533; appliedIndex:1532; }","duration":"125.260197ms","start":"2026-04-22T16:31:57.125209Z","end":"2026-04-22T16:31:57.250469Z","steps":["trace[500075856] 'read index received' (duration: 14.150115ms)","trace[500075856] 'applied index is now lower than readState.Index' (duration: 111.108912ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:31:57.25058Z","caller":"traceutil/trace.go:171","msg":"trace[629373546] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"137.081988ms","start":"2026-04-22T16:31:57.113434Z","end":"2026-04-22T16:31:57.250516Z","steps":["trace[629373546] 'process raft request' (duration: 25.992796ms)","trace[629373546] 'compare' (duration: 110.449667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:31:57.250881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.624905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1823"} {"level":"info","ts":"2026-04-22T16:31:57.251001Z","caller":"traceutil/trace.go:171","msg":"trace[1302647384] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1486; }","duration":"125.825809ms","start":"2026-04-22T16:31:57.125153Z","end":"2026-04-22T16:31:57.250979Z","steps":["trace[1302647384] 'agreement among raft nodes before linearized reading' (duration: 125.398299ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:23.974331Z","caller":"traceutil/trace.go:171","msg":"trace[97440753] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"282.790454ms","start":"2026-04-22T16:32:23.691495Z","end":"2026-04-22T16:32:23.974286Z","steps":["trace[97440753] 'read index received' (duration: 191.505939ms)","trace[97440753] 'applied index is now lower than readState.Index' (duration: 91.283285ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:32:23.974547Z","caller":"traceutil/trace.go:171","msg":"trace[461390090] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"351.209935ms","start":"2026-04-22T16:32:23.623296Z","end":"2026-04-22T16:32:23.974506Z","steps":["trace[461390090] 'process raft request' (duration: 259.790497ms)","trace[461390090] 'compare' (duration: 91.013129ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:32:23.974712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:32:23.623268Z","time spent":"351.360669ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:32:23.974897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.346339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e01d3fbc-8a97-4319-9658-f3cc8d66fdef\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-22T16:32:23.974967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.433129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e01d3fbc-8a97-4319-9658-f3cc8d66fdef\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-22T16:32:23.975013Z","caller":"traceutil/trace.go:171","msg":"trace[936963428] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e01d3fbc-8a97-4319-9658-f3cc8d66fdef; range_end:; response_count:1; response_revision:1899; }","duration":"277.528633ms","start":"2026-04-22T16:32:23.697459Z","end":"2026-04-22T16:32:23.974987Z","steps":["trace[936963428] 'agreement among raft nodes before linearized reading' (duration: 277.233885ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:23.975022Z","caller":"traceutil/trace.go:171","msg":"trace[1915159689] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e01d3fbc-8a97-4319-9658-f3cc8d66fdef; range_end:; response_count:1; response_revision:1899; }","duration":"283.521221ms","start":"2026-04-22T16:32:23.691486Z","end":"2026-04-22T16:32:23.975007Z","steps":["trace[1915159689] 'agreement among raft nodes before linearized reading' (duration: 283.038889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:32:23.975013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.748555ms","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-04-22T16:32:23.975138Z","caller":"traceutil/trace.go:171","msg":"trace[567442542] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"168.882038ms","start":"2026-04-22T16:32:23.806222Z","end":"2026-04-22T16:32:23.975104Z","steps":["trace[567442542] 'agreement among raft nodes before linearized reading' (duration: 168.589682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:32:23.97505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.86205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:32:23.975312Z","caller":"traceutil/trace.go:171","msg":"trace[478890244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1899; }","duration":"237.165997ms","start":"2026-04-22T16:32:23.738132Z","end":"2026-04-22T16:32:23.975298Z","steps":["trace[478890244] 'agreement among raft nodes before linearized reading' (duration: 236.87765ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:24.196288Z","caller":"traceutil/trace.go:171","msg":"trace[59686913] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"173.122346ms","start":"2026-04-22T16:32:24.023126Z","end":"2026-04-22T16:32:24.196248Z","steps":["trace[59686913] 'process raft request' (duration: 172.90025ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:24.445024Z","caller":"traceutil/trace.go:171","msg":"trace[938303952] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"242.11792ms","start":"2026-04-22T16:32:24.202873Z","end":"2026-04-22T16:32:24.44499Z","steps":["trace[938303952] 'process raft request' (duration: 235.840066ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:24.452044Z","caller":"traceutil/trace.go:171","msg":"trace[1429330861] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"249.129221ms","start":"2026-04-22T16:32:24.202873Z","end":"2026-04-22T16:32:24.452002Z","steps":["trace[1429330861] 'process raft request' (duration: 248.692461ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:24.452445Z","caller":"traceutil/trace.go:171","msg":"trace[708220796] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"249.469789ms","start":"2026-04-22T16:32:24.202955Z","end":"2026-04-22T16:32:24.452425Z","steps":["trace[708220796] 'process raft request' (duration: 248.926776ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:24.452551Z","caller":"traceutil/trace.go:171","msg":"trace[1955370916] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"248.582339ms","start":"2026-04-22T16:32:24.203887Z","end":"2026-04-22T16:32:24.452469Z","steps":["trace[1955370916] 'process raft request' (duration: 248.305182ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:32:31.761786Z","caller":"traceutil/trace.go:171","msg":"trace[462686508] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"103.644231ms","start":"2026-04-22T16:32:31.658088Z","end":"2026-04-22T16:32:31.761732Z","steps":["trace[462686508] 'process raft request' (duration: 103.444827ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:33:14.455945Z","caller":"traceutil/trace.go:171","msg":"trace[194949182] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"102.103509ms","start":"2026-04-22T16:33:14.353806Z","end":"2026-04-22T16:33:14.45591Z","steps":["trace[194949182] 'process raft request' (duration: 101.848804ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:33:14.470458Z","caller":"traceutil/trace.go:171","msg":"trace[68886552] linearizableReadLoop","detail":"{readStateIndex:2312; appliedIndex:2310; }","duration":"112.982531ms","start":"2026-04-22T16:33:14.357443Z","end":"2026-04-22T16:33:14.470425Z","steps":["trace[68886552] 'read index received' (duration: 98.198264ms)","trace[68886552] 'applied index is now lower than readState.Index' (duration: 14.782907ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:33:14.470518Z","caller":"traceutil/trace.go:171","msg":"trace[1420902687] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"116.400365ms","start":"2026-04-22T16:33:14.354078Z","end":"2026-04-22T16:33:14.470479Z","steps":["trace[1420902687] 'process raft request' (duration: 116.16522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:33:14.470739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.265587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T16:33:14.470792Z","caller":"traceutil/trace.go:171","msg":"trace[1250488312] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2225; }","duration":"113.356399ms","start":"2026-04-22T16:33:14.357421Z","end":"2026-04-22T16:33:14.470777Z","steps":["trace[1250488312] 'agreement among raft nodes before linearized reading' (duration: 113.154975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:33:14.691354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.102101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:33:14.691703Z","caller":"traceutil/trace.go:171","msg":"trace[1747022634] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"215.59274ms","start":"2026-04-22T16:33:14.476058Z","end":"2026-04-22T16:33:14.69165Z","steps":["trace[1747022634] 'process raft request' (duration: 105.086672ms)","trace[1747022634] 'compare' (duration: 109.967387ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:34:45.630818Z","caller":"traceutil/trace.go:171","msg":"trace[337648319] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"179.675131ms","start":"2026-04-22T16:34:45.451094Z","end":"2026-04-22T16:34:45.630769Z","steps":["trace[337648319] 'process raft request' (duration: 91.602464ms)","trace[337648319] 'compare' (duration: 87.570757ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:34:51.579261Z","caller":"traceutil/trace.go:171","msg":"trace[1023847199] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"143.407281ms","start":"2026-04-22T16:34:51.435574Z","end":"2026-04-22T16:34:51.578981Z","steps":["trace[1023847199] 'process raft request' (duration: 143.214746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:35:28.889556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.409772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:35:28.889975Z","caller":"traceutil/trace.go:171","msg":"trace[439775101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3148; }","duration":"151.904123ms","start":"2026-04-22T16:35:28.738049Z","end":"2026-04-22T16:35:28.889953Z","steps":["trace[439775101] 'range keys from in-memory index tree' (duration: 151.315021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:35:48.061471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437967831544927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:35:48.098207Z","caller":"traceutil/trace.go:171","msg":"trace[852074599] transaction","detail":"{read_only:false; response_revision:3242; number_of_response:1; }","duration":"538.587689ms","start":"2026-04-22T16:35:47.559585Z","end":"2026-04-22T16:35:48.098173Z","steps":["trace[852074599] 'process raft request' (duration: 538.378995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:35:48.098415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:35:47.55956Z","time spent":"538.747482ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:35:48.127162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:35:47.559934Z","time spent":"567.218316ms","remote":"127.0.0.1:60946","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-22T16:35:48.127357Z","caller":"traceutil/trace.go:171","msg":"trace[1349893311] linearizableReadLoop","detail":"{readStateIndex:3373; appliedIndex:3367; }","duration":"565.905618ms","start":"2026-04-22T16:35:47.561414Z","end":"2026-04-22T16:35:48.12732Z","steps":["trace[1349893311] 'read index received' (duration: 536.675619ms)","trace[1349893311] 'applied index is now lower than readState.Index' (duration: 29.228879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:35:48.128054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.606484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-04-22T16:35:48.128108Z","caller":"traceutil/trace.go:171","msg":"trace[1952155922] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3246; }","duration":"566.693966ms","start":"2026-04-22T16:35:47.561399Z","end":"2026-04-22T16:35:48.128093Z","steps":["trace[1952155922] 'agreement among raft nodes before linearized reading' (duration: 566.506242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:35:48.128141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:35:47.561393Z","time spent":"566.738836ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-04-22T16:35:48.128147Z","caller":"traceutil/trace.go:171","msg":"trace[241488712] transaction","detail":"{read_only:false; response_revision:3243; number_of_response:1; }","duration":"565.748945ms","start":"2026-04-22T16:35:47.562349Z","end":"2026-04-22T16:35:48.128098Z","steps":["trace[241488712] 'process raft request' (duration: 564.678261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:35:48.128348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:35:47.562328Z","time spent":"565.924968ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:35:48.128442Z","caller":"traceutil/trace.go:171","msg":"trace[905271043] transaction","detail":"{read_only:false; response_revision:3244; number_of_response:1; }","duration":"565.563971ms","start":"2026-04-22T16:35:47.56283Z","end":"2026-04-22T16:35:48.128394Z","steps":["trace[905271043] 'process raft request' (duration: 564.374485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:35:48.128651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:35:47.562824Z","time spent":"565.728944ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:37:43.270717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.641839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:37:43.270843Z","caller":"traceutil/trace.go:171","msg":"trace[1571852045] linearizableReadLoop","detail":"{readStateIndex:4203; appliedIndex:4202; }","duration":"305.378768ms","start":"2026-04-22T16:37:42.965446Z","end":"2026-04-22T16:37:43.270825Z","steps":["trace[1571852045] 'read index received' (duration: 133.621937ms)","trace[1571852045] 'applied index is now lower than readState.Index' (duration: 171.755431ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:37:43.270883Z","caller":"traceutil/trace.go:171","msg":"trace[381115414] transaction","detail":"{read_only:false; response_revision:4047; number_of_response:1; }","duration":"318.305278ms","start":"2026-04-22T16:37:42.952531Z","end":"2026-04-22T16:37:43.270837Z","steps":["trace[381115414] 'process raft request' (duration: 146.437425ms)","trace[381115414] 'compare' (duration: 171.189519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:37:43.271031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.581602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T16:37:43.271059Z","caller":"traceutil/trace.go:171","msg":"trace[1117495750] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4047; }","duration":"305.655543ms","start":"2026-04-22T16:37:42.965394Z","end":"2026-04-22T16:37:43.27105Z","steps":["trace[1117495750] 'agreement among raft nodes before linearized reading' (duration: 305.49965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:43.271053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:37:42.952417Z","time spent":"318.551843ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:37:43.271085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:37:42.965371Z","time spent":"305.707425ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T16:37:43.384369Z","caller":"traceutil/trace.go:171","msg":"trace[355295973] transaction","detail":"{read_only:false; response_revision:4049; number_of_response:1; }","duration":"107.175795ms","start":"2026-04-22T16:37:43.277163Z","end":"2026-04-22T16:37:43.384339Z","steps":["trace[355295973] 'process raft request' (duration: 107.114644ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:43.384448Z","caller":"traceutil/trace.go:171","msg":"trace[212677355] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"194.840403ms","start":"2026-04-22T16:37:43.189547Z","end":"2026-04-22T16:37:43.384388Z","steps":["trace[212677355] 'process raft request' (duration: 186.318196ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:37:43.384448Z","caller":"traceutil/trace.go:171","msg":"trace[2044229193] linearizableReadLoop","detail":"{readStateIndex:4204; appliedIndex:4203; }","duration":"113.543688ms","start":"2026-04-22T16:37:43.270861Z","end":"2026-04-22T16:37:43.384405Z","steps":["trace[2044229193] 'read index received' (duration: 105.0214ms)","trace[2044229193] 'applied index is now lower than readState.Index' (duration: 8.518898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:37:43.384716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.441109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T16:37:43.384825Z","caller":"traceutil/trace.go:171","msg":"trace[1464482771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4049; }","duration":"141.641164ms","start":"2026-04-22T16:37:43.243155Z","end":"2026-04-22T16:37:43.384796Z","steps":["trace[1464482771] 'agreement among raft nodes before linearized reading' (duration: 141.325587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:37:43.384978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.082774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T16:37:43.385056Z","caller":"traceutil/trace.go:171","msg":"trace[731308579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4049; }","duration":"178.222427ms","start":"2026-04-22T16:37:43.206812Z","end":"2026-04-22T16:37:43.385034Z","steps":["trace[731308579] 'agreement among raft nodes before linearized reading' (duration: 177.763807ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:39:18.556355Z","caller":"traceutil/trace.go:171","msg":"trace[1007935121] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"193.583618ms","start":"2026-04-22T16:39:18.362746Z","end":"2026-04-22T16:39:18.55633Z","steps":["trace[1007935121] 'process raft request' (duration: 193.404275ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:39:37.42924Z","caller":"traceutil/trace.go:171","msg":"trace[1675028071] linearizableReadLoop","detail":"{readStateIndex:5861; appliedIndex:5860; }","duration":"208.339972ms","start":"2026-04-22T16:39:37.220871Z","end":"2026-04-22T16:39:37.429211Z","steps":["trace[1675028071] 'read index received' (duration: 208.073157ms)","trace[1675028071] 'applied index is now lower than readState.Index' (duration: 265.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:39:37.429327Z","caller":"traceutil/trace.go:171","msg":"trace[835151250] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"237.659217ms","start":"2026-04-22T16:39:37.191622Z","end":"2026-04-22T16:39:37.429281Z","steps":["trace[835151250] 'process raft request' (duration: 237.395471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:39:37.429601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.716381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80134"} {"level":"info","ts":"2026-04-22T16:39:37.429648Z","caller":"traceutil/trace.go:171","msg":"trace[1529021879] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5648; }","duration":"208.794412ms","start":"2026-04-22T16:39:37.220844Z","end":"2026-04-22T16:39:37.429639Z","steps":["trace[1529021879] 'agreement among raft nodes before linearized reading' (duration: 208.527777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:39:37.429623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.228208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:39:37.429703Z","caller":"traceutil/trace.go:171","msg":"trace[1735173440] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5648; }","duration":"112.33171ms","start":"2026-04-22T16:39:37.317356Z","end":"2026-04-22T16:39:37.429688Z","steps":["trace[1735173440] 'agreement among raft nodes before linearized reading' (duration: 112.123956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:39:37.429719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.522051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d294e3b-565a-4060-b4a7-94f3d2e85a05\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-22T16:39:37.429788Z","caller":"traceutil/trace.go:171","msg":"trace[663990286] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d294e3b-565a-4060-b4a7-94f3d2e85a05; range_end:; response_count:1; response_revision:5648; }","duration":"196.704545ms","start":"2026-04-22T16:39:37.233071Z","end":"2026-04-22T16:39:37.429776Z","steps":["trace[663990286] 'agreement among raft nodes before linearized reading' (duration: 196.318667ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:39:37.672483Z","caller":"traceutil/trace.go:171","msg":"trace[440464496] linearizableReadLoop","detail":"{readStateIndex:5862; appliedIndex:5861; }","duration":"101.924829ms","start":"2026-04-22T16:39:37.570528Z","end":"2026-04-22T16:39:37.672453Z","steps":["trace[440464496] 'read index received' (duration: 38.112641ms)","trace[440464496] 'applied index is now lower than readState.Index' (duration: 63.810768ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:39:37.672575Z","caller":"traceutil/trace.go:171","msg":"trace[202872134] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"235.054112ms","start":"2026-04-22T16:39:37.437471Z","end":"2026-04-22T16:39:37.672525Z","steps":["trace[202872134] 'process raft request' (duration: 171.04778ms)","trace[202872134] 'compare' (duration: 63.719736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:39:37.672872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.315037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T16:39:37.672988Z","caller":"traceutil/trace.go:171","msg":"trace[470361529] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:5649; }","duration":"102.509162ms","start":"2026-04-22T16:39:37.570454Z","end":"2026-04-22T16:39:37.672963Z","steps":["trace[470361529] 'agreement among raft nodes before linearized reading' (duration: 102.141534ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:39:37.683751Z","caller":"traceutil/trace.go:171","msg":"trace[1961411290] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"110.601664ms","start":"2026-04-22T16:39:37.573129Z","end":"2026-04-22T16:39:37.683731Z","steps":["trace[1961411290] 'process raft request' (duration: 110.353849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:39:37.948486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.133708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:39:37.948845Z","caller":"traceutil/trace.go:171","msg":"trace[1832289389] linearizableReadLoop","detail":"{readStateIndex:5865; appliedIndex:5863; }","duration":"210.754684ms","start":"2026-04-22T16:39:37.738023Z","end":"2026-04-22T16:39:37.948778Z","steps":["trace[1832289389] 'read index received' (duration: 20.921806ms)","trace[1832289389] 'applied index is now lower than readState.Index' (duration: 189.828578ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:39:37.948782Z","caller":"traceutil/trace.go:171","msg":"trace[725337515] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"260.103704ms","start":"2026-04-22T16:39:37.688586Z","end":"2026-04-22T16:39:37.94869Z","steps":["trace[725337515] 'process raft request' (duration: 114.524476ms)","trace[725337515] 'compare' (duration: 144.682999ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:39:37.948956Z","caller":"traceutil/trace.go:171","msg":"trace[105197312] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"187.982571ms","start":"2026-04-22T16:39:37.760924Z","end":"2026-04-22T16:39:37.948906Z","steps":["trace[105197312] 'process raft request' (duration: 187.763856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:39:37.949094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.046531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:39:37.9492Z","caller":"traceutil/trace.go:171","msg":"trace[412992910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5653; }","duration":"211.221585ms","start":"2026-04-22T16:39:37.737959Z","end":"2026-04-22T16:39:37.949181Z","steps":["trace[412992910] 'agreement among raft nodes before linearized reading' (duration: 210.96754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:39:37.949154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.978619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-22T16:39:37.949549Z","caller":"traceutil/trace.go:171","msg":"trace[1572695899] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5653; }","duration":"101.421838ms","start":"2026-04-22T16:39:37.848105Z","end":"2026-04-22T16:39:37.949527Z","steps":["trace[1572695899] 'agreement among raft nodes before linearized reading' (duration: 100.894307ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:39:54.010617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-04-22T16:39:54.081468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"69.152521ms","hash":2926066447,"current-db-size-bytes":55201792,"current-db-size":"55 MB","current-db-size-in-use-bytes":31641600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-22T16:39:54.081737Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2926066447,"revision":2956,"compact-revision":-1} {"level":"info","ts":"2026-04-22T16:44:15.28854Z","caller":"traceutil/trace.go:171","msg":"trace[1189212048] linearizableReadLoop","detail":"{readStateIndex:8021; appliedIndex:8020; }","duration":"108.801801ms","start":"2026-04-22T16:44:15.179716Z","end":"2026-04-22T16:44:15.288518Z","steps":["trace[1189212048] 'read index received' (duration: 108.629307ms)","trace[1189212048] 'applied index is now lower than readState.Index' (duration: 171.984µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:44:15.288625Z","caller":"traceutil/trace.go:171","msg":"trace[343700068] transaction","detail":"{read_only:false; response_revision:7740; number_of_response:1; }","duration":"124.26429ms","start":"2026-04-22T16:44:15.164313Z","end":"2026-04-22T16:44:15.288578Z","steps":["trace[343700068] 'process raft request' (duration: 124.084056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:44:15.288705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.972074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r5449\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-22T16:44:15.288728Z","caller":"traceutil/trace.go:171","msg":"trace[917266718] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r5449; range_end:; response_count:1; response_revision:7740; }","duration":"109.036806ms","start":"2026-04-22T16:44:15.179684Z","end":"2026-04-22T16:44:15.288721Z","steps":["trace[917266718] 'agreement among raft nodes before linearized reading' (duration: 108.926504ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:44:54.058754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5843} {"level":"warn","ts":"2026-04-22T16:44:54.426991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.491128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:44:54.427122Z","caller":"traceutil/trace.go:171","msg":"trace[540822199] transaction","detail":"{read_only:false; response_revision:8020; number_of_response:1; }","duration":"164.10416ms","start":"2026-04-22T16:44:54.26299Z","end":"2026-04-22T16:44:54.427094Z","steps":["trace[540822199] 'process raft request' (duration: 14.429218ms)","trace[540822199] 'compare' (duration: 114.771008ms)","trace[540822199] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/openstack/barbican-ks-endpoints-b9rw4.18a8bb934933f229; req_size:770; } (duration: 34.400193ms)"],"step_count":3} {"level":"info","ts":"2026-04-22T16:44:54.485072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5843,"took":"424.455124ms","hash":606007453,"current-db-size-bytes":55201792,"current-db-size":"55 MB","current-db-size-in-use-bytes":26284032,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-22T16:44:54.48516Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":606007453,"revision":5843,"compact-revision":2956} {"level":"info","ts":"2026-04-22T16:45:55.865251Z","caller":"traceutil/trace.go:171","msg":"trace[1562811339] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"103.820014ms","start":"2026-04-22T16:45:55.761393Z","end":"2026-04-22T16:45:55.865213Z","steps":["trace[1562811339] 'process raft request' (duration: 103.487487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:06.850059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.072035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:46:06.859928Z","caller":"traceutil/trace.go:171","msg":"trace[1828345555] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"451.575552ms","start":"2026-04-22T16:46:06.408303Z","end":"2026-04-22T16:46:06.859879Z","steps":["trace[1828345555] 'process raft request' (duration: 303.542845ms)","trace[1828345555] 'compare' (duration: 137.801549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:46:06.860122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.408281Z","time spent":"451.735625ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:46:06.860118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.664183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-22T16:46:06.860195Z","caller":"traceutil/trace.go:171","msg":"trace[1790687449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8606; }","duration":"443.801146ms","start":"2026-04-22T16:46:06.416378Z","end":"2026-04-22T16:46:06.860179Z","steps":["trace[1790687449] 'agreement among raft nodes before linearized reading' (duration: 443.575911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:06.860242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.416352Z","time spent":"443.867528ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:46:06.86102Z","caller":"traceutil/trace.go:171","msg":"trace[1247136089] linearizableReadLoop","detail":"{readStateIndex:8915; appliedIndex:8914; }","duration":"443.395237ms","start":"2026-04-22T16:46:06.41643Z","end":"2026-04-22T16:46:06.859825Z","steps":["trace[1247136089] 'read index received' (duration: 295.386941ms)","trace[1247136089] 'applied index is now lower than readState.Index' (duration: 148.005256ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:46:07.209325Z","caller":"traceutil/trace.go:171","msg":"trace[1396614048] linearizableReadLoop","detail":"{readStateIndex:8916; appliedIndex:8915; }","duration":"348.217357ms","start":"2026-04-22T16:46:06.861063Z","end":"2026-04-22T16:46:07.20928Z","steps":["trace[1396614048] 'read index received' (duration: 324.744607ms)","trace[1396614048] 'applied index is now lower than readState.Index' (duration: 23.47175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:46:07.209464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.070056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:46:07.209571Z","caller":"traceutil/trace.go:171","msg":"trace[883310294] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:8607; }","duration":"447.246649ms","start":"2026-04-22T16:46:06.762301Z","end":"2026-04-22T16:46:07.209547Z","steps":["trace[883310294] 'agreement among raft nodes before linearized reading' (duration: 446.998624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:07.209588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.730362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T16:46:07.209552Z","caller":"traceutil/trace.go:171","msg":"trace[801473885] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"351.616559ms","start":"2026-04-22T16:46:06.857895Z","end":"2026-04-22T16:46:07.209511Z","steps":["trace[801473885] 'process raft request' (duration: 327.72959ms)","trace[801473885] 'compare' (duration: 22.51096ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:46:07.209632Z","caller":"traceutil/trace.go:171","msg":"trace[1422247448] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8607; }","duration":"479.817404ms","start":"2026-04-22T16:46:06.729803Z","end":"2026-04-22T16:46:07.20962Z","steps":["trace[1422247448] 'agreement among raft nodes before linearized reading' (duration: 479.61781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:07.209655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.762276Z","time spent":"447.361381ms","remote":"127.0.0.1:38020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:46:07.20967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.72978Z","time spent":"479.881865ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T16:46:07.209602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.174328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-22T16:46:07.20974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.857862Z","time spent":"351.796743ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:46:07.209797Z","caller":"traceutil/trace.go:171","msg":"trace[1684293017] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8607; }","duration":"552.394592ms","start":"2026-04-22T16:46:06.657374Z","end":"2026-04-22T16:46:07.209768Z","steps":["trace[1684293017] 'agreement among raft nodes before linearized reading' (duration: 552.060185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:07.209611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.246136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-22T16:46:07.209866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.657355Z","time spent":"552.492754ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-22T16:46:07.209868Z","caller":"traceutil/trace.go:171","msg":"trace[1234780726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8607; }","duration":"782.503751ms","start":"2026-04-22T16:46:06.427353Z","end":"2026-04-22T16:46:07.209857Z","steps":["trace[1234780726] 'agreement among raft nodes before linearized reading' (duration: 782.161804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:07.209939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.427325Z","time spent":"782.603302ms","remote":"127.0.0.1:32958","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-04-22T16:46:07.228151Z","caller":"traceutil/trace.go:171","msg":"trace[1493571988] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"362.319418ms","start":"2026-04-22T16:46:06.865806Z","end":"2026-04-22T16:46:07.228125Z","steps":["trace[1493571988] 'process raft request' (duration: 362.257677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:07.228284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.865784Z","time spent":"362.42545ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:46:07.22843Z","caller":"traceutil/trace.go:171","msg":"trace[2029576237] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"362.600194ms","start":"2026-04-22T16:46:06.865806Z","end":"2026-04-22T16:46:07.228406Z","steps":["trace[2029576237] 'process raft request' (duration: 362.120614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:07.228655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:06.865783Z","time spent":"362.740957ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:46:07.937059Z","caller":"traceutil/trace.go:171","msg":"trace[1905850301] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"195.280815ms","start":"2026-04-22T16:46:07.741756Z","end":"2026-04-22T16:46:07.937037Z","steps":["trace[1905850301] 'process raft request' (duration: 195.122222ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:46:08.133493Z","caller":"traceutil/trace.go:171","msg":"trace[257859456] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8924; }","duration":"134.747804ms","start":"2026-04-22T16:46:07.998717Z","end":"2026-04-22T16:46:08.133465Z","steps":["trace[257859456] 'read index received' (duration: 113.048171ms)","trace[257859456] 'applied index is now lower than readState.Index' (duration: 21.698403ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:46:08.133552Z","caller":"traceutil/trace.go:171","msg":"trace[1442947159] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"206.231999ms","start":"2026-04-22T16:46:07.927289Z","end":"2026-04-22T16:46:08.133521Z","steps":["trace[1442947159] 'process raft request' (duration: 183.715229ms)","trace[1442947159] 'compare' (duration: 22.353887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:46:08.1337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.981559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r5449\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-22T16:46:08.133736Z","caller":"traceutil/trace.go:171","msg":"trace[187174198] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r5449; range_end:; response_count:1; response_revision:8615; }","duration":"135.03267ms","start":"2026-04-22T16:46:07.998692Z","end":"2026-04-22T16:46:08.133724Z","steps":["trace[187174198] 'agreement among raft nodes before linearized reading' (duration: 134.879137ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:46:08.443254Z","caller":"traceutil/trace.go:171","msg":"trace[1239879101] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"104.903748ms","start":"2026-04-22T16:46:08.338309Z","end":"2026-04-22T16:46:08.443213Z","steps":["trace[1239879101] 'process raft request' (duration: 33.182238ms)","trace[1239879101] 'compare' (duration: 71.525966ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:46:09.007619Z","caller":"traceutil/trace.go:171","msg":"trace[127588246] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"138.75713ms","start":"2026-04-22T16:46:08.868824Z","end":"2026-04-22T16:46:09.007581Z","steps":["trace[127588246] 'process raft request' (duration: 138.614467ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:46:34.190354Z","caller":"traceutil/trace.go:171","msg":"trace[1299324658] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"145.825579ms","start":"2026-04-22T16:46:34.044501Z","end":"2026-04-22T16:46:34.190326Z","steps":["trace[1299324658] 'process raft request' (duration: 145.660165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:37.928068Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630437967831559264,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:46:37.943225Z","caller":"traceutil/trace.go:171","msg":"trace[1741106331] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"524.883214ms","start":"2026-04-22T16:46:37.418005Z","end":"2026-04-22T16:46:37.942888Z","steps":["trace[1741106331] 'process raft request' (duration: 524.661709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:37.943734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:37.417995Z","time spent":"525.317064ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:46:38.008734Z","caller":"traceutil/trace.go:171","msg":"trace[1797708832] linearizableReadLoop","detail":"{readStateIndex:9143; appliedIndex:9141; }","duration":"581.178268ms","start":"2026-04-22T16:46:37.427521Z","end":"2026-04-22T16:46:38.0087Z","steps":["trace[1797708832] 'read index received' (duration: 514.990483ms)","trace[1797708832] 'applied index is now lower than readState.Index' (duration: 66.185825ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:46:38.008823Z","caller":"traceutil/trace.go:171","msg":"trace[1791828568] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"124.446772ms","start":"2026-04-22T16:46:37.884335Z","end":"2026-04-22T16:46:38.008781Z","steps":["trace[1791828568] 'process raft request' (duration: 119.644169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:38.009066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.517025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-22T16:46:38.009123Z","caller":"traceutil/trace.go:171","msg":"trace[1290549739] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8826; }","duration":"581.604458ms","start":"2026-04-22T16:46:37.427501Z","end":"2026-04-22T16:46:38.009106Z","steps":["trace[1290549739] 'agreement among raft nodes before linearized reading' (duration: 581.378943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:38.009172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:37.427492Z","time spent":"581.670929ms","remote":"127.0.0.1:32958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T16:46:38.009387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.875551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:46:38.009449Z","caller":"traceutil/trace.go:171","msg":"trace[512167791] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8826; }","duration":"449.997473ms","start":"2026-04-22T16:46:37.559436Z","end":"2026-04-22T16:46:38.009434Z","steps":["trace[512167791] 'agreement among raft nodes before linearized reading' (duration: 449.84268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:38.009494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:37.559406Z","time spent":"450.077035ms","remote":"127.0.0.1:37842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:46:38.009606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.479192ms","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-04-22T16:46:38.009655Z","caller":"traceutil/trace.go:171","msg":"trace[1390542543] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8826; }","duration":"138.569253ms","start":"2026-04-22T16:46:37.871075Z","end":"2026-04-22T16:46:38.009644Z","steps":["trace[1390542543] 'agreement among raft nodes before linearized reading' (duration: 138.447711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:38.009691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.540098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:46:38.009737Z","caller":"traceutil/trace.go:171","msg":"trace[1011542533] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8826; }","duration":"301.62436ms","start":"2026-04-22T16:46:37.7081Z","end":"2026-04-22T16:46:38.009724Z","steps":["trace[1011542533] 'agreement among raft nodes before linearized reading' (duration: 301.555569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:38.009787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:37.708076Z","time spent":"301.697462ms","remote":"127.0.0.1:39574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:46:38.010036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.744269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T16:46:38.010075Z","caller":"traceutil/trace.go:171","msg":"trace[784320047] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:8826; }","duration":"400.838921ms","start":"2026-04-22T16:46:37.609227Z","end":"2026-04-22T16:46:38.010066Z","steps":["trace[784320047] 'agreement among raft nodes before linearized reading' (duration: 400.715639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:38.01011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:46:37.609194Z","time spent":"400.909703ms","remote":"127.0.0.1:60946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.219\" "} {"level":"warn","ts":"2026-04-22T16:46:41.661522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.976282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:46:41.661663Z","caller":"traceutil/trace.go:171","msg":"trace[864900231] linearizableReadLoop","detail":"{readStateIndex:9164; appliedIndex:9163; }","duration":"150.271634ms","start":"2026-04-22T16:46:41.511371Z","end":"2026-04-22T16:46:41.661642Z","steps":["trace[864900231] 'read index received' (duration: 24.953084ms)","trace[864900231] 'applied index is now lower than readState.Index' (duration: 125.31693ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:46:41.661764Z","caller":"traceutil/trace.go:171","msg":"trace[748302836] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"250.311193ms","start":"2026-04-22T16:46:41.411441Z","end":"2026-04-22T16:46:41.661752Z","steps":["trace[748302836] 'process raft request' (duration: 124.991023ms)","trace[748302836] 'compare' (duration: 124.37089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:46:41.66211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.706063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:46:41.662245Z","caller":"traceutil/trace.go:171","msg":"trace[875917065] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8846; }","duration":"150.932398ms","start":"2026-04-22T16:46:41.511277Z","end":"2026-04-22T16:46:41.662209Z","steps":["trace[875917065] 'agreement among raft nodes before linearized reading' (duration: 150.713563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:41.662772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.958134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:46:41.662823Z","caller":"traceutil/trace.go:171","msg":"trace[1586405486] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8846; }","duration":"148.063697ms","start":"2026-04-22T16:46:41.514743Z","end":"2026-04-22T16:46:41.662807Z","steps":["trace[1586405486] 'agreement among raft nodes before linearized reading' (duration: 147.942974ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:46:41.867793Z","caller":"traceutil/trace.go:171","msg":"trace[644620064] linearizableReadLoop","detail":"{readStateIndex:9165; appliedIndex:9164; }","duration":"127.952146ms","start":"2026-04-22T16:46:41.739799Z","end":"2026-04-22T16:46:41.867751Z","steps":["trace[644620064] 'read index received' (duration: 64.598772ms)","trace[644620064] 'applied index is now lower than readState.Index' (duration: 63.351524ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:46:41.867855Z","caller":"traceutil/trace.go:171","msg":"trace[728983251] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"170.585018ms","start":"2026-04-22T16:46:41.697233Z","end":"2026-04-22T16:46:41.867818Z","steps":["trace[728983251] 'process raft request' (duration: 107.273534ms)","trace[728983251] 'compare' (duration: 63.002357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:46:41.868017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.189302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:46:41.868079Z","caller":"traceutil/trace.go:171","msg":"trace[1083459971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8847; }","duration":"128.350835ms","start":"2026-04-22T16:46:41.73971Z","end":"2026-04-22T16:46:41.868061Z","steps":["trace[1083459971] 'agreement among raft nodes before linearized reading' (duration: 128.209102ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:46:52.564127Z","caller":"traceutil/trace.go:171","msg":"trace[1785820954] linearizableReadLoop","detail":"{readStateIndex:9267; appliedIndex:9266; }","duration":"124.92668ms","start":"2026-04-22T16:46:52.439179Z","end":"2026-04-22T16:46:52.564105Z","steps":["trace[1785820954] 'read index received' (duration: 124.710526ms)","trace[1785820954] 'applied index is now lower than readState.Index' (duration: 215.334µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:46:52.564387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.170946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r5449\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-22T16:46:52.564424Z","caller":"traceutil/trace.go:171","msg":"trace[1998636922] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r5449; range_end:; response_count:1; response_revision:8944; }","duration":"125.289359ms","start":"2026-04-22T16:46:52.439126Z","end":"2026-04-22T16:46:52.564415Z","steps":["trace[1998636922] 'agreement among raft nodes before linearized reading' (duration: 125.084915ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:46:52.564833Z","caller":"traceutil/trace.go:171","msg":"trace[888679766] transaction","detail":"{read_only:false; response_revision:8944; number_of_response:1; }","duration":"160.356669ms","start":"2026-04-22T16:46:52.404465Z","end":"2026-04-22T16:46:52.564822Z","steps":["trace[888679766] 'process raft request' (duration: 159.49198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:53.263317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.374796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-04-22T16:46:53.263458Z","caller":"traceutil/trace.go:171","msg":"trace[1658618129] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8952; }","duration":"114.56575ms","start":"2026-04-22T16:46:53.148863Z","end":"2026-04-22T16:46:53.263428Z","steps":["trace[1658618129] 'range keys from in-memory index tree' (duration: 114.161992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:46:53.454075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.485381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-22T16:46:53.45427Z","caller":"traceutil/trace.go:171","msg":"trace[403341420] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:8954; }","duration":"124.692406ms","start":"2026-04-22T16:46:53.329547Z","end":"2026-04-22T16:46:53.45424Z","steps":["trace[403341420] 'agreement among raft nodes before linearized reading' (duration: 68.602856ms)","trace[403341420] 'range keys from in-memory index tree' (duration: 55.739632ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:47:06.11631Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024bb.wal"} {"level":"info","ts":"2026-04-22T16:48:03.081224Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7e142186203fa167","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T16:48:03.088787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T16:48:03.088957Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-22T16:48:12.519681Z","caller":"traceutil/trace.go:171","msg":"trace[1530209575] linearizableReadLoop","detail":"{readStateIndex:10058; appliedIndex:10057; }","duration":"146.235347ms","start":"2026-04-22T16:48:12.373413Z","end":"2026-04-22T16:48:12.519648Z","steps":["trace[1530209575] 'read index received' (duration: 139.050763ms)","trace[1530209575] 'applied index is now lower than readState.Index' (duration: 7.183214ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:48:12.519841Z","caller":"traceutil/trace.go:171","msg":"trace[1544074854] transaction","detail":"{read_only:false; response_revision:9713; number_of_response:1; }","duration":"281.378337ms","start":"2026-04-22T16:48:12.238447Z","end":"2026-04-22T16:48:12.519825Z","steps":["trace[1544074854] 'process raft request' (duration: 273.967058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:48:12.52023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.799939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:48:12.520321Z","caller":"traceutil/trace.go:171","msg":"trace[215676704] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:9713; }","duration":"146.937072ms","start":"2026-04-22T16:48:12.373363Z","end":"2026-04-22T16:48:12.5203Z","steps":["trace[215676704] 'agreement among raft nodes before linearized reading' (duration: 146.805629ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:49:54.065971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8016} {"level":"info","ts":"2026-04-22T16:49:54.137716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8016,"took":"69.547447ms","hash":1023351425,"current-db-size-bytes":55201792,"current-db-size":"55 MB","current-db-size-in-use-bytes":28303360,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-22T16:49:54.137903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1023351425,"revision":8016,"compact-revision":5843} {"level":"info","ts":"2026-04-22T16:54:54.071718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10419} {"level":"info","ts":"2026-04-22T16:54:54.152168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10419,"took":"78.228934ms","hash":1162924112,"current-db-size-bytes":55201792,"current-db-size":"55 MB","current-db-size-in-use-bytes":22540288,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-22T16:54:54.152246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1162924112,"revision":10419,"compact-revision":8016}