{"level":"warn","ts":"2026-04-27T16:00:11.87153Z","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-27T16:00:11.871628Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11: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.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11: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-27T16:00:11.871697Z","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-27T16:00:11.871705Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-04-27T16:00:11.871723Z","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-27T16:00:11.876767Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-04-27T16:00:11.876924Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11: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-27T16:00:11.910305Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"33.08985ms"} {"level":"info","ts":"2026-04-27T16:00:11.956825Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-04-27T16:00:11.956941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T16:00:11.96201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-04-27T16:00:11.962365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T16:00:11.96239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-04-27T16:00:11.963006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-04-27T16:00:11.994563Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T16:00:12.005515Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T16:00:12.015606Z","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-27T16:00:12.020029Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T16:00:12.020322Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T16:00:12.020531Z","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-27T16:00:12.020668Z","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-27T16:00:12.020707Z","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-27T16:00:12.02359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-04-27T16:00:12.024098Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-04-27T16:00:12.028027Z","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-27T16:00:12.028141Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-04-27T16:00:12.028182Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-04-27T16:00:12.028491Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T16:00:12.028538Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T16:00:12.16378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T16:00:12.163859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T16:00:12.163908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-04-27T16:00:12.16393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-04-27T16:00:12.163942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-04-27T16:00:12.163958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-04-27T16:00:12.163973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-04-27T16:00:12.166625Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:00:12.166659Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:00:12.166848Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:00:12.166595Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T16:00:12.167898Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:00:12.168057Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:00:12.168129Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:00:12.168392Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T16:00:12.168499Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T16:00:12.17041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"info","ts":"2026-04-27T16:00:12.17161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T16:00:44.461763Z","caller":"traceutil/trace.go:171","msg":"trace[1408463093] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"103.291242ms","start":"2026-04-27T16:00:44.358448Z","end":"2026-04-27T16:00:44.461739Z","steps":["trace[1408463093] 'process raft request' (duration: 99.976712ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.461978Z","caller":"traceutil/trace.go:171","msg":"trace[1882449884] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"103.331797ms","start":"2026-04-27T16:00:44.358636Z","end":"2026-04-27T16:00:44.461967Z","steps":["trace[1882449884] 'process raft request' (duration: 102.544943ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.471887Z","caller":"traceutil/trace.go:171","msg":"trace[1891212771] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"100.989456ms","start":"2026-04-27T16:00:44.370648Z","end":"2026-04-27T16:00:44.471637Z","steps":["trace[1891212771] 'process raft request' (duration: 98.782236ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.472535Z","caller":"traceutil/trace.go:171","msg":"trace[719202827] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"101.767819ms","start":"2026-04-27T16:00:44.370655Z","end":"2026-04-27T16:00:44.472423Z","steps":["trace[719202827] 'process raft request' (duration: 98.829479ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.473433Z","caller":"traceutil/trace.go:171","msg":"trace[230771186] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"102.536895ms","start":"2026-04-27T16:00:44.370582Z","end":"2026-04-27T16:00:44.473119Z","steps":["trace[230771186] 'process raft request' (duration: 98.761468ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.602651Z","caller":"traceutil/trace.go:171","msg":"trace[186590878] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"117.827805ms","start":"2026-04-27T16:00:44.484801Z","end":"2026-04-27T16:00:44.602629Z","steps":["trace[186590878] 'process raft request' (duration: 117.776802ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.602874Z","caller":"traceutil/trace.go:171","msg":"trace[1173542222] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"118.040926ms","start":"2026-04-27T16:00:44.4848Z","end":"2026-04-27T16:00:44.602841Z","steps":["trace[1173542222] 'process raft request' (duration: 109.840191ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.603349Z","caller":"traceutil/trace.go:171","msg":"trace[141068640] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"118.194375ms","start":"2026-04-27T16:00:44.485139Z","end":"2026-04-27T16:00:44.603333Z","steps":["trace[141068640] 'process raft request' (duration: 118.021888ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.603612Z","caller":"traceutil/trace.go:171","msg":"trace[1833762997] transaction","detail":"{read_only:false; number_of_response:1; response_revision:493; }","duration":"118.545718ms","start":"2026-04-27T16:00:44.485053Z","end":"2026-04-27T16:00:44.603598Z","steps":["trace[1833762997] 'process raft request' (duration: 117.911444ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.604148Z","caller":"traceutil/trace.go:171","msg":"trace[803207320] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"115.985016ms","start":"2026-04-27T16:00:44.488138Z","end":"2026-04-27T16:00:44.604123Z","steps":["trace[803207320] 'process raft request' (duration: 115.102796ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.604426Z","caller":"traceutil/trace.go:171","msg":"trace[342858591] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"108.52821ms","start":"2026-04-27T16:00:44.495868Z","end":"2026-04-27T16:00:44.604396Z","steps":["trace[342858591] 'process raft request' (duration: 107.508008ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.604581Z","caller":"traceutil/trace.go:171","msg":"trace[1985007613] transaction","detail":"{read_only:false; number_of_response:1; response_revision:495; }","duration":"115.932364ms","start":"2026-04-27T16:00:44.48864Z","end":"2026-04-27T16:00:44.604573Z","steps":["trace[1985007613] 'process raft request' (duration: 114.63522ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.604676Z","caller":"traceutil/trace.go:171","msg":"trace[135107143] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"112.310276ms","start":"2026-04-27T16:00:44.492358Z","end":"2026-04-27T16:00:44.604668Z","steps":["trace[135107143] 'process raft request' (duration: 110.959299ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:44.604746Z","caller":"traceutil/trace.go:171","msg":"trace[237570602] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"105.818859ms","start":"2026-04-27T16:00:44.49892Z","end":"2026-04-27T16:00:44.604739Z","steps":["trace[237570602] 'process raft request' (duration: 104.70675ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:45.675856Z","caller":"traceutil/trace.go:171","msg":"trace[2012632159] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"179.12019ms","start":"2026-04-27T16:00:45.49668Z","end":"2026-04-27T16:00:45.6758Z","steps":["trace[2012632159] 'process raft request' (duration: 174.699337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:00:45.675953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.845481ms","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-27T16:00:45.675886Z","caller":"traceutil/trace.go:171","msg":"trace[1060164653] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"177.749184ms","start":"2026-04-27T16:00:45.498065Z","end":"2026-04-27T16:00:45.675814Z","steps":["trace[1060164653] 'read index received' (duration: 172.517892ms)","trace[1060164653] 'applied index is now lower than readState.Index' (duration: 5.226802ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:00:45.676079Z","caller":"traceutil/trace.go:171","msg":"trace[906573454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"178.019917ms","start":"2026-04-27T16:00:45.498042Z","end":"2026-04-27T16:00:45.676062Z","steps":["trace[906573454] 'agreement among raft nodes before linearized reading' (duration: 177.803616ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:45.68566Z","caller":"traceutil/trace.go:171","msg":"trace[776131387] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"145.19294ms","start":"2026-04-27T16:00:45.540455Z","end":"2026-04-27T16:00:45.685648Z","steps":["trace[776131387] 'process raft request' (duration: 144.322248ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:45.685222Z","caller":"traceutil/trace.go:171","msg":"trace[982975308] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"187.040807ms","start":"2026-04-27T16:00:45.49815Z","end":"2026-04-27T16:00:45.685191Z","steps":["trace[982975308] 'process raft request' (duration: 186.411212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:00:50.848772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.60832ms","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-27T16:00:50.848869Z","caller":"traceutil/trace.go:171","msg":"trace[902782077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"144.771928ms","start":"2026-04-27T16:00:50.704075Z","end":"2026-04-27T16:00:50.848847Z","steps":["trace[902782077] 'range keys from in-memory index tree' (duration: 144.397738ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:50.892283Z","caller":"traceutil/trace.go:171","msg":"trace[15850977] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"173.151293ms","start":"2026-04-27T16:00:50.718828Z","end":"2026-04-27T16:00:50.891979Z","steps":["trace[15850977] 'process raft request' (duration: 172.921663ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:50.929063Z","caller":"traceutil/trace.go:171","msg":"trace[634405427] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"186.527594ms","start":"2026-04-27T16:00:50.742501Z","end":"2026-04-27T16:00:50.929029Z","steps":["trace[634405427] 'process raft request' (duration: 186.122517ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:51.305318Z","caller":"traceutil/trace.go:171","msg":"trace[1530282312] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"229.248668ms","start":"2026-04-27T16:00:51.076053Z","end":"2026-04-27T16:00:51.305302Z","steps":["trace[1530282312] 'read index received' (duration: 224.233269ms)","trace[1530282312] 'applied index is now lower than readState.Index' (duration: 5.01436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:00:51.305419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.356195ms","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-27T16:00:51.305438Z","caller":"traceutil/trace.go:171","msg":"trace[1174399113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"229.416317ms","start":"2026-04-27T16:00:51.076016Z","end":"2026-04-27T16:00:51.305433Z","steps":["trace[1174399113] 'agreement among raft nodes before linearized reading' (duration: 229.349016ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:00:51.464069Z","caller":"traceutil/trace.go:171","msg":"trace[1775440092] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"119.544631ms","start":"2026-04-27T16:00:51.344484Z","end":"2026-04-27T16:00:51.464028Z","steps":["trace[1775440092] 'process raft request' (duration: 118.652408ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:02:03.220797Z","caller":"traceutil/trace.go:171","msg":"trace[354358218] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"208.747275ms","start":"2026-04-27T16:02:03.012027Z","end":"2026-04-27T16:02:03.220774Z","steps":["trace[354358218] 'read index received' (duration: 208.575542ms)","trace[354358218] 'applied index is now lower than readState.Index' (duration: 170.753µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:02:03.220914Z","caller":"traceutil/trace.go:171","msg":"trace[1475039854] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"209.134873ms","start":"2026-04-27T16:02:03.011732Z","end":"2026-04-27T16:02:03.220867Z","steps":["trace[1475039854] 'process raft request' (duration: 208.778186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:02:03.221017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.97101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2897"} {"level":"info","ts":"2026-04-27T16:02:03.221054Z","caller":"traceutil/trace.go:171","msg":"trace[249444605] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1226; }","duration":"209.036121ms","start":"2026-04-27T16:02:03.012007Z","end":"2026-04-27T16:02:03.221043Z","steps":["trace[249444605] 'agreement among raft nodes before linearized reading' (duration: 208.886578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:02:49.47391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.207188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65a3a995-ff85-4559-af10-71dc60fa23cc\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-27T16:02:49.473877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.646472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-27T16:02:49.474018Z","caller":"traceutil/trace.go:171","msg":"trace[551868785] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1886; }","duration":"283.850085ms","start":"2026-04-27T16:02:49.190148Z","end":"2026-04-27T16:02:49.473998Z","steps":["trace[551868785] 'range keys from in-memory index tree' (duration: 283.422379ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:02:49.474515Z","caller":"traceutil/trace.go:171","msg":"trace[912275231] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-65a3a995-ff85-4559-af10-71dc60fa23cc; range_end:; response_count:1; response_revision:1886; }","duration":"234.342393ms","start":"2026-04-27T16:02:49.239643Z","end":"2026-04-27T16:02:49.473986Z","steps":["trace[912275231] 'range keys from in-memory index tree' (duration: 234.051032ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:02:49.523784Z","caller":"traceutil/trace.go:171","msg":"trace[2127404618] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"129.988583ms","start":"2026-04-27T16:02:49.393769Z","end":"2026-04-27T16:02:49.523758Z","steps":["trace[2127404618] 'process raft request' (duration: 129.78058ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:02:49.686329Z","caller":"traceutil/trace.go:171","msg":"trace[1633204955] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"206.792603ms","start":"2026-04-27T16:02:49.479493Z","end":"2026-04-27T16:02:49.686286Z","steps":["trace[1633204955] 'process raft request' (duration: 206.451694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:02:49.686597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.204471ms","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-27T16:02:49.686509Z","caller":"traceutil/trace.go:171","msg":"trace[92462537] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"142.871391ms","start":"2026-04-27T16:02:49.543378Z","end":"2026-04-27T16:02:49.686249Z","steps":["trace[92462537] 'read index received' (duration: 77.640492ms)","trace[92462537] 'applied index is now lower than readState.Index' (duration: 65.229529ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:02:49.686669Z","caller":"traceutil/trace.go:171","msg":"trace[390336573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1889; }","duration":"143.322287ms","start":"2026-04-27T16:02:49.543332Z","end":"2026-04-27T16:02:49.686655Z","steps":["trace[390336573] 'agreement among raft nodes before linearized reading' (duration: 143.120294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:02:49.686857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.311117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:02:49.6869Z","caller":"traceutil/trace.go:171","msg":"trace[1368437562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1889; }","duration":"143.362365ms","start":"2026-04-27T16:02:49.543527Z","end":"2026-04-27T16:02:49.68689Z","steps":["trace[1368437562] 'agreement among raft nodes before linearized reading' (duration: 143.235479ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:02:49.686875Z","caller":"traceutil/trace.go:171","msg":"trace[558295195] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"208.227996ms","start":"2026-04-27T16:02:49.478145Z","end":"2026-04-27T16:02:49.686373Z","steps":["trace[558295195] 'process raft request' (duration: 142.856122ms)","trace[558295195] 'compare' (duration: 64.616318ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:02:49.922775Z","caller":"traceutil/trace.go:171","msg":"trace[339777353] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"104.716378ms","start":"2026-04-27T16:02:49.818039Z","end":"2026-04-27T16:02:49.922756Z","steps":["trace[339777353] 'read index received' (duration: 104.599862ms)","trace[339777353] 'applied index is now lower than readState.Index' (duration: 115.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:02:49.922825Z","caller":"traceutil/trace.go:171","msg":"trace[1739262248] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"147.092383ms","start":"2026-04-27T16:02:49.775684Z","end":"2026-04-27T16:02:49.922777Z","steps":["trace[1739262248] 'process raft request' (duration: 146.974037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:02:49.922967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.887393ms","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-27T16:02:49.922995Z","caller":"traceutil/trace.go:171","msg":"trace[245977019] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1892; }","duration":"104.984349ms","start":"2026-04-27T16:02:49.818004Z","end":"2026-04-27T16:02:49.922988Z","steps":["trace[245977019] 'agreement among raft nodes before linearized reading' (duration: 104.839834ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:03:23.147659Z","caller":"traceutil/trace.go:171","msg":"trace[42134876] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2217; }","duration":"133.062538ms","start":"2026-04-27T16:03:23.014564Z","end":"2026-04-27T16:03:23.147627Z","steps":["trace[42134876] 'read index received' (duration: 132.820861ms)","trace[42134876] 'applied index is now lower than readState.Index' (duration: 240.408µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:03:23.147775Z","caller":"traceutil/trace.go:171","msg":"trace[1424725236] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"175.380216ms","start":"2026-04-27T16:03:22.972346Z","end":"2026-04-27T16:03:23.147726Z","steps":["trace[1424725236] 'process raft request' (duration: 175.016946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:03:23.14813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.542913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12381"} {"level":"info","ts":"2026-04-27T16:03:23.148218Z","caller":"traceutil/trace.go:171","msg":"trace[610951366] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2132; }","duration":"133.692715ms","start":"2026-04-27T16:03:23.014505Z","end":"2026-04-27T16:03:23.148198Z","steps":["trace[610951366] 'agreement among raft nodes before linearized reading' (duration: 133.249249ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:03:23.955468Z","caller":"traceutil/trace.go:171","msg":"trace[1651440286] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"190.653636ms","start":"2026-04-27T16:03:23.764779Z","end":"2026-04-27T16:03:23.955433Z","steps":["trace[1651440286] 'process raft request' (duration: 184.409746ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:03:24.110964Z","caller":"traceutil/trace.go:171","msg":"trace[1819066355] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"149.401847ms","start":"2026-04-27T16:03:23.961505Z","end":"2026-04-27T16:03:24.110907Z","steps":["trace[1819066355] 'process raft request' (duration: 149.128062ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:03:24.111722Z","caller":"traceutil/trace.go:171","msg":"trace[229530756] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"149.308082ms","start":"2026-04-27T16:03:23.962393Z","end":"2026-04-27T16:03:24.111701Z","steps":["trace[229530756] 'process raft request' (duration: 148.426549ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:04:20.542656Z","caller":"traceutil/trace.go:171","msg":"trace[1836744278] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"109.184879ms","start":"2026-04-27T16:04:20.433444Z","end":"2026-04-27T16:04:20.542629Z","steps":["trace[1836744278] 'process raft request' (duration: 109.056258ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:04:28.99001Z","caller":"traceutil/trace.go:171","msg":"trace[549482280] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"105.170794ms","start":"2026-04-27T16:04:28.884812Z","end":"2026-04-27T16:04:28.989983Z","steps":["trace[549482280] 'process raft request' (duration: 105.051643ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:04:28.9914Z","caller":"traceutil/trace.go:171","msg":"trace[31808902] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"104.775732ms","start":"2026-04-27T16:04:28.886602Z","end":"2026-04-27T16:04:28.991378Z","steps":["trace[31808902] 'process raft request' (duration: 104.66777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:02.488112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.256513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:05:02.488298Z","caller":"traceutil/trace.go:171","msg":"trace[1468289548] linearizableReadLoop","detail":"{readStateIndex:2881; appliedIndex:2880; }","duration":"154.739865ms","start":"2026-04-27T16:05:02.333532Z","end":"2026-04-27T16:05:02.488215Z","steps":["trace[1468289548] 'read index received' (duration: 53.746µs)","trace[1468289548] 'applied index is now lower than readState.Index' (duration: 154.627724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:05:02.488477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.93655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:02.488564Z","caller":"traceutil/trace.go:171","msg":"trace[723385808] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"299.812008ms","start":"2026-04-27T16:05:02.188723Z","end":"2026-04-27T16:05:02.488535Z","steps":["trace[723385808] 'process raft request' (duration: 118.42227ms)","trace[723385808] 'compare' (duration: 180.067147ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:02.488595Z","caller":"traceutil/trace.go:171","msg":"trace[1391268485] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2772; }","duration":"155.058161ms","start":"2026-04-27T16:05:02.333491Z","end":"2026-04-27T16:05:02.488549Z","steps":["trace[1391268485] 'agreement among raft nodes before linearized reading' (duration: 154.898914ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:54.14275Z","caller":"traceutil/trace.go:171","msg":"trace[296315556] linearizableReadLoop","detail":"{readStateIndex:4767; appliedIndex:4766; }","duration":"101.560658ms","start":"2026-04-27T16:08:54.041157Z","end":"2026-04-27T16:08:54.142718Z","steps":["trace[296315556] 'read index received' (duration: 89.317252ms)","trace[296315556] 'applied index is now lower than readState.Index' (duration: 12.241935ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:54.143356Z","caller":"traceutil/trace.go:171","msg":"trace[1932544935] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"113.128113ms","start":"2026-04-27T16:08:54.030211Z","end":"2026-04-27T16:08:54.143339Z","steps":["trace[1932544935] 'process raft request' (duration: 101.089756ms)","trace[1932544935] 'compare' (duration: 11.252753ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:54.143684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.485207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-9vwqz\" ","response":"range_response_count:1 size:4819"} {"level":"info","ts":"2026-04-27T16:08:54.143792Z","caller":"traceutil/trace.go:171","msg":"trace[40300851] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-9vwqz; range_end:; response_count:1; response_revision:4590; }","duration":"102.651803ms","start":"2026-04-27T16:08:54.041114Z","end":"2026-04-27T16:08:54.143766Z","steps":["trace[40300851] 'agreement among raft nodes before linearized reading' (duration: 102.33276ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.51354Z","caller":"traceutil/trace.go:171","msg":"trace[1476657313] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"144.969992ms","start":"2026-04-27T16:08:59.368544Z","end":"2026-04-27T16:08:59.513514Z","steps":["trace[1476657313] 'process raft request' (duration: 144.753164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.535235Z","caller":"traceutil/trace.go:171","msg":"trace[1620404388] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"165.243551ms","start":"2026-04-27T16:08:59.36997Z","end":"2026-04-27T16:08:59.535214Z","steps":["trace[1620404388] 'process raft request' (duration: 165.146817ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.535229Z","caller":"traceutil/trace.go:171","msg":"trace[2136505032] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"166.036184ms","start":"2026-04-27T16:08:59.369159Z","end":"2026-04-27T16:08:59.535195Z","steps":["trace[2136505032] 'process raft request' (duration: 165.912319ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.535315Z","caller":"traceutil/trace.go:171","msg":"trace[1796992678] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"157.500234ms","start":"2026-04-27T16:08:59.3778Z","end":"2026-04-27T16:08:59.5353Z","steps":["trace[1796992678] 'process raft request' (duration: 157.366628ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.535267Z","caller":"traceutil/trace.go:171","msg":"trace[1376598213] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"165.441499ms","start":"2026-04-27T16:08:59.369802Z","end":"2026-04-27T16:08:59.535244Z","steps":["trace[1376598213] 'process raft request' (duration: 165.295253ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.535531Z","caller":"traceutil/trace.go:171","msg":"trace[1574613925] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"116.755148ms","start":"2026-04-27T16:08:59.41876Z","end":"2026-04-27T16:08:59.535515Z","steps":["trace[1574613925] 'process raft request' (duration: 116.444114ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.535876Z","caller":"traceutil/trace.go:171","msg":"trace[1780232727] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4875; }","duration":"166.595227ms","start":"2026-04-27T16:08:59.369269Z","end":"2026-04-27T16:08:59.535865Z","steps":["trace[1780232727] 'read index received' (duration: 143.988072ms)","trace[1780232727] 'applied index is now lower than readState.Index' (duration: 22.605725ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:59.535504Z","caller":"traceutil/trace.go:171","msg":"trace[2105785120] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"166.390148ms","start":"2026-04-27T16:08:59.369016Z","end":"2026-04-27T16:08:59.535406Z","steps":["trace[2105785120] 'process raft request' (duration: 165.96969ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.535544Z","caller":"traceutil/trace.go:171","msg":"trace[1910968375] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"165.036442ms","start":"2026-04-27T16:08:59.3705Z","end":"2026-04-27T16:08:59.535536Z","steps":["trace[1910968375] 'process raft request' (duration: 164.641305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:59.53607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.778654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-27T16:08:59.537574Z","caller":"traceutil/trace.go:171","msg":"trace[513503408] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4704; }","duration":"168.312946ms","start":"2026-04-27T16:08:59.369244Z","end":"2026-04-27T16:08:59.537557Z","steps":["trace[513503408] 'agreement among raft nodes before linearized reading' (duration: 166.67576ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.804233Z","caller":"traceutil/trace.go:171","msg":"trace[398334302] linearizableReadLoop","detail":"{readStateIndex:4891; appliedIndex:4890; }","duration":"115.274067ms","start":"2026-04-27T16:08:59.688931Z","end":"2026-04-27T16:08:59.804205Z","steps":["trace[398334302] 'read index received' (duration: 114.966844ms)","trace[398334302] 'applied index is now lower than readState.Index' (duration: 258.991µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:59.804499Z","caller":"traceutil/trace.go:171","msg":"trace[1823897871] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"119.959019ms","start":"2026-04-27T16:08:59.684483Z","end":"2026-04-27T16:08:59.804442Z","steps":["trace[1823897871] 'process raft request' (duration: 119.444307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:59.804598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.656253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60246"} {"level":"info","ts":"2026-04-27T16:08:59.804647Z","caller":"traceutil/trace.go:171","msg":"trace[569451637] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4711; }","duration":"115.728076ms","start":"2026-04-27T16:08:59.688908Z","end":"2026-04-27T16:08:59.804636Z","steps":["trace[569451637] 'agreement among raft nodes before linearized reading' (duration: 115.425814ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:59.829339Z","caller":"traceutil/trace.go:171","msg":"trace[1036139637] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"136.027815ms","start":"2026-04-27T16:08:59.693285Z","end":"2026-04-27T16:08:59.829313Z","steps":["trace[1036139637] 'process raft request' (duration: 135.876209ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:04.938455Z","caller":"traceutil/trace.go:171","msg":"trace[677171520] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"189.598171ms","start":"2026-04-27T16:09:04.748819Z","end":"2026-04-27T16:09:04.938417Z","steps":["trace[677171520] 'process raft request' (duration: 93.724153ms)","trace[677171520] 'compare' (duration: 95.249773ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:05.346607Z","caller":"traceutil/trace.go:171","msg":"trace[1530396119] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"114.923422ms","start":"2026-04-27T16:09:05.231659Z","end":"2026-04-27T16:09:05.346583Z","steps":["trace[1530396119] 'process raft request' (duration: 45.456581ms)","trace[1530396119] 'compare' (duration: 69.299874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:18.716893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.122866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:18.717033Z","caller":"traceutil/trace.go:171","msg":"trace[866465122] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5165; }","duration":"421.305217ms","start":"2026-04-27T16:09:18.295711Z","end":"2026-04-27T16:09:18.717016Z","steps":["trace[866465122] 'read index received' (duration: 75.953524ms)","trace[866465122] 'applied index is now lower than readState.Index' (duration: 345.350213ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:18.717228Z","caller":"traceutil/trace.go:171","msg":"trace[302811993] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"447.830236ms","start":"2026-04-27T16:09:18.269379Z","end":"2026-04-27T16:09:18.717209Z","steps":["trace[302811993] 'process raft request' (duration: 102.278605ms)","trace[302811993] 'compare' (duration: 344.505354ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:18.717317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:18.269349Z","time spent":"447.924339ms","remote":"127.0.0.1:57242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:18.717582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.867006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-04-27T16:09:18.717615Z","caller":"traceutil/trace.go:171","msg":"trace[844926793] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4978; }","duration":"421.949099ms","start":"2026-04-27T16:09:18.295655Z","end":"2026-04-27T16:09:18.717604Z","steps":["trace[844926793] 'agreement among raft nodes before linearized reading' (duration: 421.805504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:18.717642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:18.295631Z","time spent":"422.005211ms","remote":"127.0.0.1:57158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10417,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-27T16:09:18.72534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.758882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:18.72547Z","caller":"traceutil/trace.go:171","msg":"trace[589850540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4979; }","duration":"104.948119ms","start":"2026-04-27T16:09:18.620505Z","end":"2026-04-27T16:09:18.725453Z","steps":["trace[589850540] 'agreement among raft nodes before linearized reading' (duration: 104.757122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:18.725535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.673897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-27T16:09:18.72555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.206682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-27T16:09:18.725522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.49848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:18.725603Z","caller":"traceutil/trace.go:171","msg":"trace[883052988] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4979; }","duration":"361.321006ms","start":"2026-04-27T16:09:18.36426Z","end":"2026-04-27T16:09:18.725581Z","steps":["trace[883052988] 'agreement among raft nodes before linearized reading' (duration: 361.183451ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:18.72562Z","caller":"traceutil/trace.go:171","msg":"trace[1514004089] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4979; }","duration":"324.644236ms","start":"2026-04-27T16:09:18.400957Z","end":"2026-04-27T16:09:18.725601Z","steps":["trace[1514004089] 'agreement among raft nodes before linearized reading' (duration: 324.435169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:18.725634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:18.364239Z","time spent":"361.384748ms","remote":"127.0.0.1:57128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-27T16:09:18.725644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.945857ms","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-04-27T16:09:18.725674Z","caller":"traceutil/trace.go:171","msg":"trace[915237457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4979; }","duration":"171.03408ms","start":"2026-04-27T16:09:18.554633Z","end":"2026-04-27T16:09:18.725667Z","steps":["trace[915237457] 'agreement among raft nodes before linearized reading' (duration: 170.964498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:18.725676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:18.400952Z","time spent":"324.703228ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:09:18.725572Z","caller":"traceutil/trace.go:171","msg":"trace[1571510556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4979; }","duration":"170.75209ms","start":"2026-04-27T16:09:18.554809Z","end":"2026-04-27T16:09:18.725561Z","steps":["trace[1571510556] 'agreement among raft nodes before linearized reading' (duration: 170.601805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:18.726254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.543377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-27T16:09:18.726295Z","caller":"traceutil/trace.go:171","msg":"trace[1837656323] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4979; }","duration":"338.594169ms","start":"2026-04-27T16:09:18.387689Z","end":"2026-04-27T16:09:18.726283Z","steps":["trace[1837656323] 'agreement among raft nodes before linearized reading' (duration: 338.477765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:18.726314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:18.387684Z","time spent":"338.62534ms","remote":"127.0.0.1:34290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-27T16:10:12.496871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2825} {"level":"info","ts":"2026-04-27T16:10:12.575901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2825,"took":"77.701877ms","hash":2128107364,"current-db-size-bytes":53252096,"current-db-size":"53 MB","current-db-size-in-use-bytes":28360704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-27T16:10:12.576057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2128107364,"revision":2825,"compact-revision":-1} {"level":"info","ts":"2026-04-27T16:15:12.503666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5438} {"level":"info","ts":"2026-04-27T16:15:12.584337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5438,"took":"78.879987ms","hash":318360401,"current-db-size-bytes":53252096,"current-db-size":"53 MB","current-db-size-in-use-bytes":18530304,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-27T16:15:12.5844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":318360401,"revision":5438,"compact-revision":2825}