{"level":"warn","ts":"2026-04-07T17:51:40.526431Z","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-07T17:51:40.526512Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.61: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.61:2380","--initial-cluster=instance=https://199.19.213.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.61: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-07T17:51:40.52658Z","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-07T17:51:40.526593Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-04-07T17:51:40.526613Z","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-07T17:51:40.527379Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"]} {"level":"info","ts":"2026-04-07T17:51:40.527454Z","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.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.61: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-07T17:51:40.531954Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.30101ms"} {"level":"info","ts":"2026-04-07T17:51:40.538986Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1484a2210d3c2b8","cluster-id":"27fc2dec3fac595c"} {"level":"info","ts":"2026-04-07T17:51:40.539093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T17:51:40.539119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 0"} {"level":"info","ts":"2026-04-07T17:51:40.539134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1484a2210d3c2b8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T17:51:40.539143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 1"} {"level":"info","ts":"2026-04-07T17:51:40.53918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"warn","ts":"2026-04-07T17:51:40.546194Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T17:51:40.547939Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T17:51:40.549161Z","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-07T17:51:40.551845Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1484a2210d3c2b8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T17:51:40.552055Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1484a2210d3c2b8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T17:51:40.552085Z","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-07T17:51:40.552215Z","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-07T17:51:40.552242Z","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-07T17:51:40.553405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"info","ts":"2026-04-07T17:51:40.558154Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","added-peer-id":"d1484a2210d3c2b8","added-peer-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-04-07T17:51:40.559512Z","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-07T17:51:40.559625Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-04-07T17:51:40.55967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-04-07T17:51:40.559845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1484a2210d3c2b8","initial-advertise-peer-urls":["https://199.19.213.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T17:51:40.559913Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T17:51:40.739767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T17:51:40.739824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T17:51:40.739844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgPreVoteResp from d1484a2210d3c2b8 at term 1"} {"level":"info","ts":"2026-04-07T17:51:40.739937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became candidate at term 2"} {"level":"info","ts":"2026-04-07T17:51:40.739955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgVoteResp from d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-04-07T17:51:40.739967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became leader at term 2"} {"level":"info","ts":"2026-04-07T17:51:40.739976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1484a2210d3c2b8 elected leader d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-04-07T17:51:40.740897Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:40.741799Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1484a2210d3c2b8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.61:2379]}","request-path":"/0/members/d1484a2210d3c2b8/attributes","cluster-id":"27fc2dec3fac595c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T17:51:40.741811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:51:40.74192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T17:51:40.742164Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T17:51:40.742238Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T17:51:40.742451Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:40.742586Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:40.742669Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T17:51:40.74553Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T17:51:40.745916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.61:2379"} {"level":"info","ts":"2026-04-07T17:51:57.275874Z","caller":"traceutil/trace.go:171","msg":"trace[236484870] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"235.177118ms","start":"2026-04-07T17:51:57.040652Z","end":"2026-04-07T17:51:57.27583Z","steps":["trace[236484870] 'process raft request' (duration: 234.936981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:51:57.589931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.363209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T17:51:57.590019Z","caller":"traceutil/trace.go:171","msg":"trace[1991967281] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"305.18471ms","start":"2026-04-07T17:51:57.284823Z","end":"2026-04-07T17:51:57.590007Z","steps":["trace[1991967281] 'process raft request' (duration: 103.366949ms)","trace[1991967281] 'compare' (duration: 201.185484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:51:57.590087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:51:57.284791Z","time spent":"305.246682ms","remote":"127.0.0.1:60184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T17:51:57.745161Z","caller":"traceutil/trace.go:171","msg":"trace[360730541] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"150.090265ms","start":"2026-04-07T17:51:57.595038Z","end":"2026-04-07T17:51:57.745129Z","steps":["trace[360730541] 'process raft request' (duration: 149.950302ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:57.748985Z","caller":"traceutil/trace.go:171","msg":"trace[160319269] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"150.631781ms","start":"2026-04-07T17:51:57.59832Z","end":"2026-04-07T17:51:57.748952Z","steps":["trace[160319269] 'process raft request' (duration: 150.556499ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:51:57.749051Z","caller":"traceutil/trace.go:171","msg":"trace[687097] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"153.671756ms","start":"2026-04-07T17:51:57.595331Z","end":"2026-04-07T17:51:57.749003Z","steps":["trace[687097] 'process raft request' (duration: 153.395677ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:17.706355Z","caller":"traceutil/trace.go:171","msg":"trace[2047273377] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"133.551802ms","start":"2026-04-07T17:52:17.57272Z","end":"2026-04-07T17:52:17.706272Z","steps":["trace[2047273377] 'read index received' (duration: 127.594449ms)","trace[2047273377] 'applied index is now lower than readState.Index' (duration: 5.956463ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:17.706502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.754947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ngcdl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T17:52:17.706536Z","caller":"traceutil/trace.go:171","msg":"trace[1873194818] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ngcdl; range_end:; response_count:1; response_revision:575; }","duration":"133.849419ms","start":"2026-04-07T17:52:17.572674Z","end":"2026-04-07T17:52:17.706523Z","steps":["trace[1873194818] 'agreement among raft nodes before linearized reading' (duration: 133.748537ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:17.706629Z","caller":"traceutil/trace.go:171","msg":"trace[1983174235] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"196.828272ms","start":"2026-04-07T17:52:17.509749Z","end":"2026-04-07T17:52:17.706578Z","steps":["trace[1983174235] 'process raft request' (duration: 191.416064ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:17.9717Z","caller":"traceutil/trace.go:171","msg":"trace[1594481383] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"252.681388ms","start":"2026-04-07T17:52:17.718981Z","end":"2026-04-07T17:52:17.971662Z","steps":["trace[1594481383] 'process raft request' (duration: 252.455202ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:18.027749Z","caller":"traceutil/trace.go:171","msg":"trace[945965520] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"195.456043ms","start":"2026-04-07T17:52:17.832271Z","end":"2026-04-07T17:52:18.027727Z","steps":["trace[945965520] 'read index received' (duration: 139.180354ms)","trace[945965520] 'applied index is now lower than readState.Index' (duration: 56.275089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:52:18.027878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.595997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:18.027907Z","caller":"traceutil/trace.go:171","msg":"trace[2053566933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"195.695479ms","start":"2026-04-07T17:52:17.832203Z","end":"2026-04-07T17:52:18.027899Z","steps":["trace[2053566933] 'agreement among raft nodes before linearized reading' (duration: 195.604587ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:18.028045Z","caller":"traceutil/trace.go:171","msg":"trace[132708899] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"263.772273ms","start":"2026-04-07T17:52:17.76425Z","end":"2026-04-07T17:52:18.028022Z","steps":["trace[132708899] 'process raft request' (duration: 263.370551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:18.49768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.505976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:52:18.497786Z","caller":"traceutil/trace.go:171","msg":"trace[1834455696] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:577; }","duration":"458.666891ms","start":"2026-04-07T17:52:18.039095Z","end":"2026-04-07T17:52:18.497762Z","steps":["trace[1834455696] 'range keys from in-memory index tree' (duration: 458.429783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T17:52:18.498257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T17:52:18.039078Z","time spent":"459.096762ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-07T17:52:19.818836Z","caller":"traceutil/trace.go:171","msg":"trace[1034486958] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"106.674206ms","start":"2026-04-07T17:52:19.712125Z","end":"2026-04-07T17:52:19.8188Z","steps":["trace[1034486958] 'process raft request' (duration: 105.0063ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:19.82243Z","caller":"traceutil/trace.go:171","msg":"trace[1371274329] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"100.312312ms","start":"2026-04-07T17:52:19.722085Z","end":"2026-04-07T17:52:19.822398Z","steps":["trace[1371274329] 'process raft request' (duration: 100.23749ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:19.822761Z","caller":"traceutil/trace.go:171","msg":"trace[1466760267] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"101.240098ms","start":"2026-04-07T17:52:19.721486Z","end":"2026-04-07T17:52:19.822726Z","steps":["trace[1466760267] 'process raft request' (duration: 100.691423ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:52:33.82145Z","caller":"traceutil/trace.go:171","msg":"trace[2137085650] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"138.2456ms","start":"2026-04-07T17:52:33.683145Z","end":"2026-04-07T17:52:33.821391Z","steps":["trace[2137085650] 'process raft request' (duration: 138.033354ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:53:01.205287Z","caller":"traceutil/trace.go:171","msg":"trace[1529433250] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"152.673523ms","start":"2026-04-07T17:53:01.052527Z","end":"2026-04-07T17:53:01.205201Z","steps":["trace[1529433250] 'process raft request' (duration: 151.564503ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:54:03.399909Z","caller":"traceutil/trace.go:171","msg":"trace[879584605] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"108.973769ms","start":"2026-04-07T17:54:03.290908Z","end":"2026-04-07T17:54:03.399881Z","steps":["trace[879584605] 'process raft request' (duration: 84.981103ms)","trace[879584605] 'compare' (duration: 23.845172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T17:54:34.983185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.782128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T17:54:34.983338Z","caller":"traceutil/trace.go:171","msg":"trace[373660341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2098; }","duration":"149.982143ms","start":"2026-04-07T17:54:34.833325Z","end":"2026-04-07T17:54:34.983308Z","steps":["trace[373660341] 'range keys from in-memory index tree' (duration: 149.670536ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:56:02.256025Z","caller":"traceutil/trace.go:171","msg":"trace[1978531044] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"150.458104ms","start":"2026-04-07T17:56:02.105537Z","end":"2026-04-07T17:56:02.255995Z","steps":["trace[1978531044] 'process raft request' (duration: 143.625967ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T17:56:02.256422Z","caller":"traceutil/trace.go:171","msg":"trace[241357841] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"150.747213ms","start":"2026-04-07T17:56:02.105651Z","end":"2026-04-07T17:56:02.256398Z","steps":["trace[241357841] 'process raft request' (duration: 150.526076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:01.670814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.231395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T18:00:01.670923Z","caller":"traceutil/trace.go:171","msg":"trace[1711083044] linearizableReadLoop","detail":"{readStateIndex:5134; appliedIndex:5133; }","duration":"241.60987ms","start":"2026-04-07T18:00:01.429298Z","end":"2026-04-07T18:00:01.670908Z","steps":["trace[1711083044] 'read index received' (duration: 96.179199ms)","trace[1711083044] 'applied index is now lower than readState.Index' (duration: 145.429451ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:01.671016Z","caller":"traceutil/trace.go:171","msg":"trace[1752567161] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"274.16466ms","start":"2026-04-07T18:00:01.396803Z","end":"2026-04-07T18:00:01.670968Z","steps":["trace[1752567161] 'process raft request' (duration: 128.710279ms)","trace[1752567161] 'compare' (duration: 145.120422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T18:00:01.671112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.807395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T18:00:01.671162Z","caller":"traceutil/trace.go:171","msg":"trace[2062785520] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4943; }","duration":"241.901628ms","start":"2026-04-07T18:00:01.429249Z","end":"2026-04-07T18:00:01.671151Z","steps":["trace[2062785520] 'agreement among raft nodes before linearized reading' (duration: 241.777214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:01.67124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.245454ms","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-07T18:00:01.671271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.65693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T18:00:01.671328Z","caller":"traceutil/trace.go:171","msg":"trace[557088001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4943; }","duration":"146.377478ms","start":"2026-04-07T18:00:01.524932Z","end":"2026-04-07T18:00:01.671309Z","steps":["trace[557088001] 'agreement among raft nodes before linearized reading' (duration: 146.128981ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:01.671367Z","caller":"traceutil/trace.go:171","msg":"trace[794621421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4943; }","duration":"149.797694ms","start":"2026-04-07T18:00:01.521542Z","end":"2026-04-07T18:00:01.67134Z","steps":["trace[794621421] 'agreement among raft nodes before linearized reading' (duration: 149.494485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:01.671626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.012545ms","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-07T18:00:01.671682Z","caller":"traceutil/trace.go:171","msg":"trace[1573300490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4943; }","duration":"147.119688ms","start":"2026-04-07T18:00:01.524549Z","end":"2026-04-07T18:00:01.671669Z","steps":["trace[1573300490] 'agreement among raft nodes before linearized reading' (duration: 146.963854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:01.671691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.337104ms","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-07T18:00:01.671734Z","caller":"traceutil/trace.go:171","msg":"trace[879055113] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4943; }","duration":"124.409276ms","start":"2026-04-07T18:00:01.547312Z","end":"2026-04-07T18:00:01.671721Z","steps":["trace[879055113] 'agreement among raft nodes before linearized reading' (duration: 124.283283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:02.607998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.459517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T18:00:02.608165Z","caller":"traceutil/trace.go:171","msg":"trace[811590654] linearizableReadLoop","detail":"{readStateIndex:5140; appliedIndex:5139; }","duration":"712.847141ms","start":"2026-04-07T18:00:01.895296Z","end":"2026-04-07T18:00:02.608143Z","steps":["trace[811590654] 'read index received' (duration: 297.931492ms)","trace[811590654] 'applied index is now lower than readState.Index' (duration: 414.913709ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:02.608264Z","caller":"traceutil/trace.go:171","msg":"trace[400550925] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"721.777574ms","start":"2026-04-07T18:00:01.886473Z","end":"2026-04-07T18:00:02.608251Z","steps":["trace[400550925] 'process raft request' (duration: 306.700731ms)","trace[400550925] 'compare' (duration: 414.107435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T18:00:02.608337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:00:01.886451Z","time spent":"721.849835ms","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T18:00:02.608754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.420637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-2bbr8-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-04-07T18:00:02.608891Z","caller":"traceutil/trace.go:171","msg":"trace[1808702071] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-2bbr8-tls; range_end:; response_count:1; response_revision:4949; }","duration":"713.582642ms","start":"2026-04-07T18:00:01.895267Z","end":"2026-04-07T18:00:02.60885Z","steps":["trace[1808702071] 'agreement among raft nodes before linearized reading' (duration: 713.201661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:02.608959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:00:01.89525Z","time spent":"713.695365ms","remote":"127.0.0.1:43056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":1989,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-2bbr8-tls\" "} {"level":"warn","ts":"2026-04-07T18:00:02.624771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.627308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa906ce4-eb50-4482-9f3f-b7a55d45d497\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-07T18:00:02.624849Z","caller":"traceutil/trace.go:171","msg":"trace[2120017224] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa906ce4-eb50-4482-9f3f-b7a55d45d497; range_end:; response_count:1; response_revision:4951; }","duration":"647.754002ms","start":"2026-04-07T18:00:01.977077Z","end":"2026-04-07T18:00:02.624831Z","steps":["trace[2120017224] 'agreement among raft nodes before linearized reading' (duration: 647.511965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:02.624884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:00:01.977053Z","time spent":"647.823583ms","remote":"127.0.0.1:59862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa906ce4-eb50-4482-9f3f-b7a55d45d497\" "} {"level":"info","ts":"2026-04-07T18:00:13.898735Z","caller":"traceutil/trace.go:171","msg":"trace[993236019] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"126.574008ms","start":"2026-04-07T18:00:13.772136Z","end":"2026-04-07T18:00:13.89871Z","steps":["trace[993236019] 'process raft request' (duration: 102.724764ms)","trace[993236019] 'compare' (duration: 23.669789ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:13.898912Z","caller":"traceutil/trace.go:171","msg":"trace[1012818951] linearizableReadLoop","detail":"{readStateIndex:5351; appliedIndex:5348; }","duration":"123.996514ms","start":"2026-04-07T18:00:13.774887Z","end":"2026-04-07T18:00:13.898883Z","steps":["trace[1012818951] 'read index received' (duration: 43.756617ms)","trace[1012818951] 'applied index is now lower than readState.Index' (duration: 80.237677ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T18:00:13.899104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.200361ms","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-07T18:00:13.899146Z","caller":"traceutil/trace.go:171","msg":"trace[1403777343] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5152; }","duration":"124.268692ms","start":"2026-04-07T18:00:13.774865Z","end":"2026-04-07T18:00:13.899133Z","steps":["trace[1403777343] 'agreement among raft nodes before linearized reading' (duration: 124.136529ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:13.899074Z","caller":"traceutil/trace.go:171","msg":"trace[1944395871] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"126.068564ms","start":"2026-04-07T18:00:13.772941Z","end":"2026-04-07T18:00:13.89901Z","steps":["trace[1944395871] 'process raft request' (duration: 125.720594ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:16.060472Z","caller":"traceutil/trace.go:171","msg":"trace[1873914011] linearizableReadLoop","detail":"{readStateIndex:5387; appliedIndex:5385; }","duration":"120.340322ms","start":"2026-04-07T18:00:15.940101Z","end":"2026-04-07T18:00:16.060441Z","steps":["trace[1873914011] 'read index received' (duration: 309.689µs)","trace[1873914011] 'applied index is now lower than readState.Index' (duration: 120.029493ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:00:16.060622Z","caller":"traceutil/trace.go:171","msg":"trace[1072257211] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"139.274187ms","start":"2026-04-07T18:00:15.921307Z","end":"2026-04-07T18:00:16.060581Z","steps":["trace[1072257211] 'process raft request' (duration: 135.886802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:16.060757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.62214ms","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-07T18:00:16.060808Z","caller":"traceutil/trace.go:171","msg":"trace[876507406] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5187; }","duration":"120.730243ms","start":"2026-04-07T18:00:15.940061Z","end":"2026-04-07T18:00:16.060792Z","steps":["trace[876507406] 'agreement among raft nodes before linearized reading' (duration: 120.499957ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:22.370316Z","caller":"traceutil/trace.go:171","msg":"trace[983952343] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"172.532676ms","start":"2026-04-07T18:00:22.197746Z","end":"2026-04-07T18:00:22.370279Z","steps":["trace[983952343] 'process raft request' (duration: 172.352201ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:00:22.507453Z","caller":"traceutil/trace.go:171","msg":"trace[1013912665] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"125.351074ms","start":"2026-04-07T18:00:22.382065Z","end":"2026-04-07T18:00:22.507416Z","steps":["trace[1013912665] 'process raft request' (duration: 78.472409ms)","trace[1013912665] 'compare' (duration: 46.745901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T18:00:36.347266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.290049ms","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-07T18:00:36.347354Z","caller":"traceutil/trace.go:171","msg":"trace[252146029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5501; }","duration":"161.411492ms","start":"2026-04-07T18:00:36.185925Z","end":"2026-04-07T18:00:36.347336Z","steps":["trace[252146029] 'range keys from in-memory index tree' (duration: 161.036072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:36.347537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.928531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-07T18:00:36.347646Z","caller":"traceutil/trace.go:171","msg":"trace[855508160] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5501; }","duration":"362.066896ms","start":"2026-04-07T18:00:35.985553Z","end":"2026-04-07T18:00:36.34762Z","steps":["trace[855508160] 'range keys from in-memory index tree' (duration: 361.760817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:36.347796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:00:35.985526Z","time spent":"362.24893ms","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-07T18:00:36.347264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.886928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T18:00:36.348007Z","caller":"traceutil/trace.go:171","msg":"trace[1264393091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5501; }","duration":"305.687351ms","start":"2026-04-07T18:00:36.042299Z","end":"2026-04-07T18:00:36.347986Z","steps":["trace[1264393091] 'range keys from in-memory index tree' (duration: 304.647061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:00:36.348061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:00:36.042276Z","time spent":"305.768833ms","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T18:01:40.895455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-04-07T18:01:40.9685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"71.34004ms","hash":2977755126,"current-db-size-bytes":58023936,"current-db-size":"58 MB","current-db-size-in-use-bytes":33513472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T18:01:40.968571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2977755126,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-04-07T18:06:37.949673Z","caller":"traceutil/trace.go:171","msg":"trace[2048506993] linearizableReadLoop","detail":"{readStateIndex:8344; appliedIndex:8343; }","duration":"119.408655ms","start":"2026-04-07T18:06:37.830239Z","end":"2026-04-07T18:06:37.949647Z","steps":["trace[2048506993] 'read index received' (duration: 119.284721ms)","trace[2048506993] 'applied index is now lower than readState.Index' (duration: 122.414µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:06:37.949955Z","caller":"traceutil/trace.go:171","msg":"trace[164434137] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"325.843084ms","start":"2026-04-07T18:06:37.624102Z","end":"2026-04-07T18:06:37.949945Z","steps":["trace[164434137] 'process raft request' (duration: 325.302059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:37.950044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:37.624076Z","time spent":"325.906226ms","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7388,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T18:06:37.950226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.983771ms","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-07T18:06:37.950259Z","caller":"traceutil/trace.go:171","msg":"trace[141780687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8052; }","duration":"120.046082ms","start":"2026-04-07T18:06:37.830205Z","end":"2026-04-07T18:06:37.950251Z","steps":["trace[141780687] 'agreement among raft nodes before linearized reading' (duration: 119.937479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:37.950378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.395332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:06:37.950403Z","caller":"traceutil/trace.go:171","msg":"trace[173487108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8052; }","duration":"115.449473ms","start":"2026-04-07T18:06:37.834947Z","end":"2026-04-07T18:06:37.950397Z","steps":["trace[173487108] 'agreement among raft nodes before linearized reading' (duration: 115.414352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:41.874514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031137713677027332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T18:06:42.380828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031137713677027332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T18:06:42.434309Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.64265356s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T18:06:42.440578Z","caller":"traceutil/trace.go:171","msg":"trace[1586348403] linearizableReadLoop","detail":"{readStateIndex:8366; appliedIndex:8361; }","duration":"1.552223059s","start":"2026-04-07T18:06:40.888334Z","end":"2026-04-07T18:06:42.440557Z","steps":["trace[1586348403] 'read index received' (duration: 1.546326533s)","trace[1586348403] 'applied index is now lower than readState.Index' (duration: 5.895396ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:06:42.440685Z","caller":"traceutil/trace.go:171","msg":"trace[2142039705] transaction","detail":"{read_only:false; response_revision:8070; number_of_response:1; }","duration":"1.543520065s","start":"2026-04-07T18:06:40.897152Z","end":"2026-04-07T18:06:42.440673Z","steps":["trace[2142039705] 'process raft request' (duration: 1.543183646s)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:06:42.440699Z","caller":"traceutil/trace.go:171","msg":"trace[1181341382] transaction","detail":"{read_only:false; response_revision:8071; number_of_response:1; }","duration":"552.706427ms","start":"2026-04-07T18:06:41.887978Z","end":"2026-04-07T18:06:42.440684Z","steps":["trace[1181341382] 'process raft request' (duration: 552.440389ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:06:42.440743Z","caller":"traceutil/trace.go:171","msg":"trace[9747346] transaction","detail":"{read_only:false; response_revision:8073; number_of_response:1; }","duration":"337.385147ms","start":"2026-04-07T18:06:42.103328Z","end":"2026-04-07T18:06:42.440713Z","steps":["trace[9747346] 'process raft request' (duration: 337.187542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.440775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:40.897142Z","time spent":"1.543575387s","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":25,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T18:06:42.440747Z","caller":"traceutil/trace.go:171","msg":"trace[671795985] transaction","detail":"{read_only:false; response_revision:8072; number_of_response:1; }","duration":"370.508828ms","start":"2026-04-07T18:06:42.070204Z","end":"2026-04-07T18:06:42.440713Z","steps":["trace[671795985] 'process raft request' (duration: 370.265392ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:06:42.440794Z","caller":"traceutil/trace.go:171","msg":"trace[723326820] transaction","detail":"{read_only:false; response_revision:8069; number_of_response:1; }","duration":"1.649202784s","start":"2026-04-07T18:06:40.791565Z","end":"2026-04-07T18:06:42.440768Z","steps":["trace[723326820] 'process raft request' (duration: 1.643094412s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.440818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552464457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-07T18:06:42.440869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.777678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:06:42.440885Z","caller":"traceutil/trace.go:171","msg":"trace[461242576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8073; }","duration":"1.552562289s","start":"2026-04-07T18:06:40.888299Z","end":"2026-04-07T18:06:42.440861Z","steps":["trace[461242576] 'agreement among raft nodes before linearized reading' (duration: 1.552389534s)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:06:42.440904Z","caller":"traceutil/trace.go:171","msg":"trace[1875454681] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8073; }","duration":"552.83025ms","start":"2026-04-07T18:06:41.888063Z","end":"2026-04-07T18:06:42.440893Z","steps":["trace[1875454681] 'agreement among raft nodes before linearized reading' (duration: 552.741648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.4409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:42.10327Z","time spent":"337.544271ms","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T18:06:42.44092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:40.888284Z","time spent":"1.552629951s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T18:06:42.440905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547206638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T18:06:42.440839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:42.07018Z","time spent":"370.642221ms","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T18:06:42.440956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:40.791543Z","time spent":"1.649340728s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T18:06:42.440968Z","caller":"traceutil/trace.go:171","msg":"trace[1223238415] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8073; }","duration":"1.54728621s","start":"2026-04-07T18:06:40.893664Z","end":"2026-04-07T18:06:42.44095Z","steps":["trace[1223238415] 'agreement among raft nodes before linearized reading' (duration: 1.547106215s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.440784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.887969Z","time spent":"552.757228ms","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T18:06:42.440928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.88806Z","time spent":"552.861901ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T18:06:42.44096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.014645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xsf4f\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T18:06:42.441023Z","caller":"traceutil/trace.go:171","msg":"trace[603643606] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xsf4f; range_end:; response_count:1; response_revision:8073; }","duration":"553.086807ms","start":"2026-04-07T18:06:41.887928Z","end":"2026-04-07T18:06:42.441014Z","steps":["trace[603643606] 'agreement among raft nodes before linearized reading' (duration: 552.942973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.441048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.887922Z","time spent":"553.119049ms","remote":"127.0.0.1:59862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-xsf4f\" "} {"level":"warn","ts":"2026-04-07T18:06:42.441041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.999845ms","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-07T18:06:42.440999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:40.893659Z","time spent":"1.547334641s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T18:06:42.441087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.221961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:06:42.441086Z","caller":"traceutil/trace.go:171","msg":"trace[976786805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8073; }","duration":"553.058146ms","start":"2026-04-07T18:06:41.888017Z","end":"2026-04-07T18:06:42.441075Z","steps":["trace[976786805] 'agreement among raft nodes before linearized reading' (duration: 552.959634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.441105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443906515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T18:06:42.441118Z","caller":"traceutil/trace.go:171","msg":"trace[927968645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8073; }","duration":"553.280432ms","start":"2026-04-07T18:06:41.887827Z","end":"2026-04-07T18:06:42.441107Z","steps":["trace[927968645] 'agreement among raft nodes before linearized reading' (duration: 553.217931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:06:42.441128Z","caller":"traceutil/trace.go:171","msg":"trace[114052442] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8073; }","duration":"1.443969847s","start":"2026-04-07T18:06:40.997151Z","end":"2026-04-07T18:06:42.441121Z","steps":["trace[114052442] 'agreement among raft nodes before linearized reading' (duration: 1.443908395s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.441128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.888014Z","time spent":"553.102768ms","remote":"127.0.0.1:59960","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":"warn","ts":"2026-04-07T18:06:42.44114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.887813Z","time spent":"553.322184ms","remote":"127.0.0.1:59690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T18:06:42.441148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:40.997132Z","time spent":"1.444011538s","remote":"127.0.0.1:60146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":22,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-07T18:06:42.441167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.150939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T18:06:42.441188Z","caller":"traceutil/trace.go:171","msg":"trace[320597169] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8073; }","duration":"553.179269ms","start":"2026-04-07T18:06:41.888001Z","end":"2026-04-07T18:06:42.44118Z","steps":["trace[320597169] 'agreement among raft nodes before linearized reading' (duration: 553.143688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.441204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.887998Z","time spent":"553.201671ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T18:06:42.441353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.144912ms","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-07T18:06:42.441378Z","caller":"traceutil/trace.go:171","msg":"trace[1670524418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8073; }","duration":"468.195694ms","start":"2026-04-07T18:06:41.973174Z","end":"2026-04-07T18:06:42.44137Z","steps":["trace[1670524418] 'agreement among raft nodes before linearized reading' (duration: 468.070999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.441397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.973158Z","time spent":"468.234774ms","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T18:06:42.441681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.578662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T18:06:42.441704Z","caller":"traceutil/trace.go:171","msg":"trace[2036239360] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8073; }","duration":"553.611143ms","start":"2026-04-07T18:06:41.888087Z","end":"2026-04-07T18:06:42.441698Z","steps":["trace[2036239360] 'agreement among raft nodes before linearized reading' (duration: 553.132068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:42.441721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:41.888084Z","time spent":"553.632183ms","remote":"127.0.0.1:59744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":831,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-07T18:06:42.447066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6027} {"level":"info","ts":"2026-04-07T18:06:42.557611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6027,"took":"107.964924ms","hash":3255774444,"current-db-size-bytes":58023936,"current-db-size":"58 MB","current-db-size-in-use-bytes":21315584,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T18:06:42.557696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3255774444,"revision":6027,"compact-revision":2979} {"level":"warn","ts":"2026-04-07T18:06:44.957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031137713677027366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T18:06:45.457292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031137713677027366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T18:06:45.943983Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.494283574s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T18:06:45.957572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714669625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:06:45.957641Z","caller":"traceutil/trace.go:171","msg":"trace[157494541] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8082; }","duration":"1.714797618s","start":"2026-04-07T18:06:44.242825Z","end":"2026-04-07T18:06:45.957622Z","steps":["trace[157494541] 'range keys from in-memory index tree' (duration: 1.714555032s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.957679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.2428Z","time spent":"1.71486912s","remote":"127.0.0.1:60146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-07T18:06:45.957917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.124744917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:06:45.957948Z","caller":"traceutil/trace.go:171","msg":"trace[577122234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8082; }","duration":"2.124811209s","start":"2026-04-07T18:06:43.833128Z","end":"2026-04-07T18:06:45.957939Z","steps":["trace[577122234] 'range keys from in-memory index tree' (duration: 2.124670655s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.957974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:43.833106Z","time spent":"2.124861311s","remote":"127.0.0.1:59706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T18:06:45.958242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.212487Z","time spent":"1.745752978s","remote":"127.0.0.1:36296","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T18:06:45.958407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.01917378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.61\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T18:06:45.958475Z","caller":"traceutil/trace.go:171","msg":"trace[1593949740] range","detail":"{range_begin:/registry/masterleases/199.19.213.61; range_end:; response_count:1; response_revision:8082; }","duration":"2.019275373s","start":"2026-04-07T18:06:43.939184Z","end":"2026-04-07T18:06:45.958459Z","steps":["trace[1593949740] 'range keys from in-memory index tree' (duration: 2.019086018s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.95851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:43.939168Z","time spent":"2.019334626s","remote":"127.0.0.1:59718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.61\" "} {"level":"info","ts":"2026-04-07T18:06:45.958803Z","caller":"traceutil/trace.go:171","msg":"trace[738804150] transaction","detail":"{read_only:false; response_revision:8083; number_of_response:1; }","duration":"1.509208273s","start":"2026-04-07T18:06:44.449581Z","end":"2026-04-07T18:06:45.95879Z","steps":["trace[738804150] 'process raft request' (duration: 1.494833489s)","trace[738804150] 'compare' (duration: 13.319744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T18:06:45.958879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.449551Z","time spent":"1.509284535s","remote":"127.0.0.1:59960","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-07T18:06:45.960269Z","caller":"traceutil/trace.go:171","msg":"trace[759027364] linearizableReadLoop","detail":"{readStateIndex:8379; appliedIndex:8376; }","duration":"1.504103609s","start":"2026-04-07T18:06:44.456149Z","end":"2026-04-07T18:06:45.960253Z","steps":["trace[759027364] 'read index received' (duration: 1.488194592s)","trace[759027364] 'applied index is now lower than readState.Index' (duration: 15.908107ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:06:45.960598Z","caller":"traceutil/trace.go:171","msg":"trace[2112461948] transaction","detail":"{read_only:false; response_revision:8084; number_of_response:1; }","duration":"1.5045121s","start":"2026-04-07T18:06:44.45607Z","end":"2026-04-07T18:06:45.960582Z","steps":["trace[2112461948] 'process raft request' (duration: 1.503962175s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.960615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497822523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T18:06:45.960617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504461489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T18:06:45.960657Z","caller":"traceutil/trace.go:171","msg":"trace[1564192043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8086; }","duration":"1.50451994s","start":"2026-04-07T18:06:44.456126Z","end":"2026-04-07T18:06:45.960646Z","steps":["trace[1564192043] 'agreement among raft nodes before linearized reading' (duration: 1.504384096s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.960671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.456042Z","time spent":"1.504594493s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T18:06:45.960691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.45612Z","time spent":"1.504563182s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T18:06:45.961002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.23197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T18:06:45.961036Z","caller":"traceutil/trace.go:171","msg":"trace[409794799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8086; }","duration":"282.291392ms","start":"2026-04-07T18:06:45.678736Z","end":"2026-04-07T18:06:45.961027Z","steps":["trace[409794799] 'agreement among raft nodes before linearized reading' (duration: 282.174829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.961225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497965387s","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-07T18:06:45.961261Z","caller":"traceutil/trace.go:171","msg":"trace[1263668171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8086; }","duration":"1.498014638s","start":"2026-04-07T18:06:44.463235Z","end":"2026-04-07T18:06:45.96125Z","steps":["trace[1263668171] 'agreement among raft nodes before linearized reading' (duration: 1.497908345s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.961289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.463231Z","time spent":"1.498051559s","remote":"127.0.0.1:59960","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":"warn","ts":"2026-04-07T18:06:45.961307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498236414s","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-07T18:06:45.961321Z","caller":"traceutil/trace.go:171","msg":"trace[884750604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8086; }","duration":"1.189241312s","start":"2026-04-07T18:06:44.772071Z","end":"2026-04-07T18:06:45.961313Z","steps":["trace[884750604] 'agreement among raft nodes before linearized reading' (duration: 1.18919782s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.961349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.772058Z","time spent":"1.189284074s","remote":"127.0.0.1:59960","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-07T18:06:45.96135Z","caller":"traceutil/trace.go:171","msg":"trace[949743690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8086; }","duration":"1.498288815s","start":"2026-04-07T18:06:44.463047Z","end":"2026-04-07T18:06:45.961335Z","steps":["trace[949743690] 'agreement among raft nodes before linearized reading' (duration: 1.498142821s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.961397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.46304Z","time spent":"1.498348108s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T18:06:45.961413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.980463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T18:06:45.961456Z","caller":"traceutil/trace.go:171","msg":"trace[1969976201] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8086; }","duration":"119.036054ms","start":"2026-04-07T18:06:45.842413Z","end":"2026-04-07T18:06:45.961449Z","steps":["trace[1969976201] 'agreement among raft nodes before linearized reading' (duration: 118.978713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.961519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496724271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xsf4f\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T18:06:45.961556Z","caller":"traceutil/trace.go:171","msg":"trace[134239524] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xsf4f; range_end:; response_count:1; response_revision:8086; }","duration":"1.496773723s","start":"2026-04-07T18:06:44.464772Z","end":"2026-04-07T18:06:45.961545Z","steps":["trace[134239524] 'agreement among raft nodes before linearized reading' (duration: 1.49664804s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.96159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.464765Z","time spent":"1.496817154s","remote":"127.0.0.1:59862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-xsf4f\" "} {"level":"warn","ts":"2026-04-07T18:06:45.961593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246241184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:1 size:7403"} {"level":"info","ts":"2026-04-07T18:06:45.961626Z","caller":"traceutil/trace.go:171","msg":"trace[504002700] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:1; response_revision:8086; }","duration":"1.246319276s","start":"2026-04-07T18:06:44.715297Z","end":"2026-04-07T18:06:45.961617Z","steps":["trace[504002700] 'agreement among raft nodes before linearized reading' (duration: 1.246207713s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.961654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.715283Z","time spent":"1.246364028s","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":7426,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"warn","ts":"2026-04-07T18:06:45.961682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282827082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T18:06:45.961705Z","caller":"traceutil/trace.go:171","msg":"trace[225459609] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8086; }","duration":"1.282910184s","start":"2026-04-07T18:06:44.678788Z","end":"2026-04-07T18:06:45.961699Z","steps":["trace[225459609] 'agreement among raft nodes before linearized reading' (duration: 1.282848813s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.961723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.678757Z","time spent":"1.282961406s","remote":"127.0.0.1:60008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":48,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-07T18:06:45.961952Z","caller":"traceutil/trace.go:171","msg":"trace[1125251346] transaction","detail":"{read_only:false; response_revision:8085; number_of_response:1; }","duration":"1.246959274s","start":"2026-04-07T18:06:44.714982Z","end":"2026-04-07T18:06:45.961941Z","steps":["trace[1125251346] 'process raft request' (duration: 1.245150514s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.962009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.714938Z","time spent":"1.247044136s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T18:06:45.960646Z","caller":"traceutil/trace.go:171","msg":"trace[361463162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8086; }","duration":"1.497868594s","start":"2026-04-07T18:06:44.462768Z","end":"2026-04-07T18:06:45.960637Z","steps":["trace[361463162] 'agreement among raft nodes before linearized reading' (duration: 1.497788432s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.962107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.462761Z","time spent":"1.499329754s","remote":"127.0.0.1:59960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T18:06:45.961005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486890426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:06:45.963456Z","caller":"traceutil/trace.go:171","msg":"trace[2007859371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8086; }","duration":"1.489388886s","start":"2026-04-07T18:06:44.474051Z","end":"2026-04-07T18:06:45.96344Z","steps":["trace[2007859371] 'agreement among raft nodes before linearized reading' (duration: 1.486892755s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:06:45.963591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T18:06:44.474042Z","time spent":"1.48949707s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-07T18:07:28.883737Z","caller":"traceutil/trace.go:171","msg":"trace[1198772203] linearizableReadLoop","detail":"{readStateIndex:8800; appliedIndex:8799; }","duration":"137.686928ms","start":"2026-04-07T18:07:28.745981Z","end":"2026-04-07T18:07:28.883668Z","steps":["trace[1198772203] 'read index received' (duration: 137.529534ms)","trace[1198772203] 'applied index is now lower than readState.Index' (duration: 156.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:28.883994Z","caller":"traceutil/trace.go:171","msg":"trace[1477902029] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"231.957808ms","start":"2026-04-07T18:07:28.652023Z","end":"2026-04-07T18:07:28.88398Z","steps":["trace[1477902029] 'process raft request' (duration: 231.466234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:28.884153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.153962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T18:07:28.884202Z","caller":"traceutil/trace.go:171","msg":"trace[367369165] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8494; }","duration":"138.257385ms","start":"2026-04-07T18:07:28.745934Z","end":"2026-04-07T18:07:28.884191Z","steps":["trace[367369165] 'agreement among raft nodes before linearized reading' (duration: 138.149612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:28.884314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.127206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T18:07:28.884453Z","caller":"traceutil/trace.go:171","msg":"trace[158629585] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8494; }","duration":"125.311281ms","start":"2026-04-07T18:07:28.759116Z","end":"2026-04-07T18:07:28.884427Z","steps":["trace[158629585] 'agreement among raft nodes before linearized reading' (duration: 124.999933ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:30.511067Z","caller":"traceutil/trace.go:171","msg":"trace[352984358] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"168.931607ms","start":"2026-04-07T18:07:30.342098Z","end":"2026-04-07T18:07:30.51103Z","steps":["trace[352984358] 'process raft request' (duration: 168.824514ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:30.511236Z","caller":"traceutil/trace.go:171","msg":"trace[1604532994] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"169.316277ms","start":"2026-04-07T18:07:30.341874Z","end":"2026-04-07T18:07:30.51119Z","steps":["trace[1604532994] 'process raft request' (duration: 150.842478ms)","trace[1604532994] 'compare' (duration: 18.008147ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:30.511269Z","caller":"traceutil/trace.go:171","msg":"trace[96603874] linearizableReadLoop","detail":"{readStateIndex:8809; appliedIndex:8806; }","duration":"168.475633ms","start":"2026-04-07T18:07:30.342766Z","end":"2026-04-07T18:07:30.511242Z","steps":["trace[96603874] 'read index received' (duration: 30.391764ms)","trace[96603874] 'applied index is now lower than readState.Index' (duration: 138.081469ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:30.511459Z","caller":"traceutil/trace.go:171","msg":"trace[1124367533] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"167.665681ms","start":"2026-04-07T18:07:30.34375Z","end":"2026-04-07T18:07:30.511415Z","steps":["trace[1124367533] 'process raft request' (duration: 167.234179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:30.511798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.012028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223784"} {"level":"info","ts":"2026-04-07T18:07:30.511851Z","caller":"traceutil/trace.go:171","msg":"trace[489024784] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8503; }","duration":"169.125721ms","start":"2026-04-07T18:07:30.342714Z","end":"2026-04-07T18:07:30.51184Z","steps":["trace[489024784] 'agreement among raft nodes before linearized reading' (duration: 168.609717ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:58.466177Z","caller":"traceutil/trace.go:171","msg":"trace[692836755] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"156.385114ms","start":"2026-04-07T18:07:58.309742Z","end":"2026-04-07T18:07:58.466127Z","steps":["trace[692836755] 'process raft request' (duration: 156.097806ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:58.545544Z","caller":"traceutil/trace.go:171","msg":"trace[1838548528] linearizableReadLoop","detail":"{readStateIndex:9016; appliedIndex:9013; }","duration":"131.375021ms","start":"2026-04-07T18:07:58.414127Z","end":"2026-04-07T18:07:58.545502Z","steps":["trace[1838548528] 'read index received' (duration: 51.822076ms)","trace[1838548528] 'applied index is now lower than readState.Index' (duration: 79.551735ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:07:58.545561Z","caller":"traceutil/trace.go:171","msg":"trace[269006063] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"234.903529ms","start":"2026-04-07T18:07:58.31062Z","end":"2026-04-07T18:07:58.545523Z","steps":["trace[269006063] 'process raft request' (duration: 234.593571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:07:58.545897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.741592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T18:07:58.545965Z","caller":"traceutil/trace.go:171","msg":"trace[1633796891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8701; }","duration":"131.905016ms","start":"2026-04-07T18:07:58.414047Z","end":"2026-04-07T18:07:58.545952Z","steps":["trace[1633796891] 'agreement among raft nodes before linearized reading' (duration: 131.653399ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:58.545597Z","caller":"traceutil/trace.go:171","msg":"trace[1165929996] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"234.084196ms","start":"2026-04-07T18:07:58.311467Z","end":"2026-04-07T18:07:58.545551Z","steps":["trace[1165929996] 'process raft request' (duration: 233.983093ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:58.67187Z","caller":"traceutil/trace.go:171","msg":"trace[1005559875] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"120.584248ms","start":"2026-04-07T18:07:58.551254Z","end":"2026-04-07T18:07:58.671839Z","steps":["trace[1005559875] 'process raft request' (duration: 118.624363ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:07:58.672141Z","caller":"traceutil/trace.go:171","msg":"trace[1281673103] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"119.743283ms","start":"2026-04-07T18:07:58.552379Z","end":"2026-04-07T18:07:58.672123Z","steps":["trace[1281673103] 'process raft request' (duration: 119.394404ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:11.828162Z","caller":"traceutil/trace.go:171","msg":"trace[1114571580] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8812; }","duration":"104.186878ms","start":"2026-04-07T18:08:11.723938Z","end":"2026-04-07T18:08:11.828125Z","steps":["trace[1114571580] 'process raft request' (duration: 25.527587ms)","trace[1114571580] 'compare' (duration: 78.351162ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:08:11.834005Z","caller":"traceutil/trace.go:171","msg":"trace[728590055] transaction","detail":"{read_only:false; response_revision:8813; number_of_response:1; }","duration":"107.607594ms","start":"2026-04-07T18:08:11.726348Z","end":"2026-04-07T18:08:11.833955Z","steps":["trace[728590055] 'process raft request' (duration: 107.12435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.909316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.26483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T18:08:12.909563Z","caller":"traceutil/trace.go:171","msg":"trace[1053020874] transaction","detail":"{read_only:false; response_revision:8818; number_of_response:1; }","duration":"246.503556ms","start":"2026-04-07T18:08:12.663024Z","end":"2026-04-07T18:08:12.909528Z","steps":["trace[1053020874] 'process raft request' (duration: 110.827913ms)","trace[1053020874] 'compare' (duration: 134.766757ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:08:12.909713Z","caller":"traceutil/trace.go:171","msg":"trace[269552306] linearizableReadLoop","detail":"{readStateIndex:9140; appliedIndex:9138; }","duration":"240.042304ms","start":"2026-04-07T18:08:12.66965Z","end":"2026-04-07T18:08:12.909693Z","steps":["trace[269552306] 'read index received' (duration: 104.212028ms)","trace[269552306] 'applied index is now lower than readState.Index' (duration: 135.828906ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:08:12.909841Z","caller":"traceutil/trace.go:171","msg":"trace[1164456129] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"230.60142ms","start":"2026-04-07T18:08:12.679225Z","end":"2026-04-07T18:08:12.909826Z","steps":["trace[1164456129] 'process raft request' (duration: 230.380124ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:12.909982Z","caller":"traceutil/trace.go:171","msg":"trace[1596294123] transaction","detail":"{read_only:false; response_revision:8819; number_of_response:1; }","duration":"240.376514ms","start":"2026-04-07T18:08:12.669578Z","end":"2026-04-07T18:08:12.909955Z","steps":["trace[1596294123] 'process raft request' (duration: 239.898711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.910005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.658249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T18:08:12.910118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.861287ms","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-07T18:08:12.910144Z","caller":"traceutil/trace.go:171","msg":"trace[1228571365] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8820; }","duration":"231.821673ms","start":"2026-04-07T18:08:12.678298Z","end":"2026-04-07T18:08:12.91012Z","steps":["trace[1228571365] 'agreement among raft nodes before linearized reading' (duration: 231.601997ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:12.910165Z","caller":"traceutil/trace.go:171","msg":"trace[511704558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8820; }","duration":"141.943658ms","start":"2026-04-07T18:08:12.768209Z","end":"2026-04-07T18:08:12.910153Z","steps":["trace[511704558] 'agreement among raft nodes before linearized reading' (duration: 141.783864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.91031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.593667ms","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-07T18:08:12.910352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.709173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pp8g8\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-04-07T18:08:12.910373Z","caller":"traceutil/trace.go:171","msg":"trace[145801764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8820; }","duration":"147.654379ms","start":"2026-04-07T18:08:12.762703Z","end":"2026-04-07T18:08:12.910357Z","steps":["trace[145801764] 'agreement among raft nodes before linearized reading' (duration: 147.484644ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:12.910386Z","caller":"traceutil/trace.go:171","msg":"trace[1913114150] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pp8g8; range_end:; response_count:1; response_revision:8820; }","duration":"240.788115ms","start":"2026-04-07T18:08:12.669588Z","end":"2026-04-07T18:08:12.910376Z","steps":["trace[1913114150] 'agreement among raft nodes before linearized reading' (duration: 240.688033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.910397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.770471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T18:08:12.910465Z","caller":"traceutil/trace.go:171","msg":"trace[1847312116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8820; }","duration":"142.886074ms","start":"2026-04-07T18:08:12.767561Z","end":"2026-04-07T18:08:12.910447Z","steps":["trace[1847312116] 'agreement among raft nodes before linearized reading' (duration: 142.73631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.910503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.113091ms","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-07T18:08:12.91059Z","caller":"traceutil/trace.go:171","msg":"trace[1154057342] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8820; }","duration":"148.195574ms","start":"2026-04-07T18:08:12.762359Z","end":"2026-04-07T18:08:12.910554Z","steps":["trace[1154057342] 'agreement among raft nodes before linearized reading' (duration: 148.008639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.910663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.171594ms","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-07T18:08:12.910738Z","caller":"traceutil/trace.go:171","msg":"trace[91135851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8820; }","duration":"142.279018ms","start":"2026-04-07T18:08:12.768436Z","end":"2026-04-07T18:08:12.910715Z","steps":["trace[91135851] 'agreement among raft nodes before linearized reading' (duration: 142.070282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.910763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.610943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-07T18:08:12.910799Z","caller":"traceutil/trace.go:171","msg":"trace[110521494] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8820; }","duration":"191.675255ms","start":"2026-04-07T18:08:12.719115Z","end":"2026-04-07T18:08:12.91079Z","steps":["trace[110521494] 'agreement among raft nodes before linearized reading' (duration: 191.572132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T18:08:12.910802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.495004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xsf4f\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T18:08:12.91084Z","caller":"traceutil/trace.go:171","msg":"trace[1139636785] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xsf4f; range_end:; response_count:1; response_revision:8820; }","duration":"126.564526ms","start":"2026-04-07T18:08:12.784266Z","end":"2026-04-07T18:08:12.910831Z","steps":["trace[1139636785] 'agreement among raft nodes before linearized reading' (duration: 126.461543ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:13.064767Z","caller":"traceutil/trace.go:171","msg":"trace[869918068] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"147.638499ms","start":"2026-04-07T18:08:12.917083Z","end":"2026-04-07T18:08:13.064722Z","steps":["trace[869918068] 'process raft request' (duration: 106.428861ms)","trace[869918068] 'compare' (duration: 40.891229ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T18:08:13.064903Z","caller":"traceutil/trace.go:171","msg":"trace[1953308774] transaction","detail":"{read_only:false; response_revision:8823; number_of_response:1; }","duration":"147.70861ms","start":"2026-04-07T18:08:12.917164Z","end":"2026-04-07T18:08:13.064873Z","steps":["trace[1953308774] 'process raft request' (duration: 147.399961ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:13.064967Z","caller":"traceutil/trace.go:171","msg":"trace[157676178] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"147.594337ms","start":"2026-04-07T18:08:12.917363Z","end":"2026-04-07T18:08:13.064958Z","steps":["trace[157676178] 'process raft request' (duration: 147.305809ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:13.064986Z","caller":"traceutil/trace.go:171","msg":"trace[396686028] linearizableReadLoop","detail":"{readStateIndex:9147; appliedIndex:9142; }","duration":"104.991369ms","start":"2026-04-07T18:08:12.959974Z","end":"2026-04-07T18:08:13.064966Z","steps":["trace[396686028] 'read index received' (duration: 63.465233ms)","trace[396686028] 'applied index is now lower than readState.Index' (duration: 41.525036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T18:08:13.065188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.307828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19382"} {"level":"info","ts":"2026-04-07T18:08:13.065236Z","caller":"traceutil/trace.go:171","msg":"trace[893765049] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8826; }","duration":"147.394591ms","start":"2026-04-07T18:08:12.917829Z","end":"2026-04-07T18:08:13.065224Z","steps":["trace[893765049] 'agreement among raft nodes before linearized reading' (duration: 147.207435ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:13.065286Z","caller":"traceutil/trace.go:171","msg":"trace[431118043] transaction","detail":"{read_only:false; response_revision:8825; number_of_response:1; }","duration":"147.763512ms","start":"2026-04-07T18:08:12.917505Z","end":"2026-04-07T18:08:13.065269Z","steps":["trace[431118043] 'process raft request' (duration: 147.244517ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:13.065332Z","caller":"traceutil/trace.go:171","msg":"trace[120778566] transaction","detail":"{read_only:false; response_revision:8826; number_of_response:1; }","duration":"140.941279ms","start":"2026-04-07T18:08:12.924381Z","end":"2026-04-07T18:08:13.065322Z","steps":["trace[120778566] 'process raft request' (duration: 140.410965ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T18:08:24.759916Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002417.wal"} {"level":"info","ts":"2026-04-07T18:09:39.590766Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1484a2210d3c2b8","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T18:09:39.596661Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T18:09:39.596766Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T18:11:42.459249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8070} {"level":"info","ts":"2026-04-07T18:11:42.518732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8070,"took":"57.785244ms","hash":177552441,"current-db-size-bytes":58023936,"current-db-size":"58 MB","current-db-size-in-use-bytes":28319744,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T18:11:42.518806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":177552441,"revision":8070,"compact-revision":6027} {"level":"info","ts":"2026-04-07T18:16:42.497886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10510} {"level":"info","ts":"2026-04-07T18:16:42.581415Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10510,"took":"81.626054ms","hash":3574116581,"current-db-size-bytes":58023936,"current-db-size":"58 MB","current-db-size-in-use-bytes":21319680,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-07T18:16:42.581505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3574116581,"revision":10510,"compact-revision":8070}