{"level":"warn","ts":"2026-05-22T23:29:42.52543Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-22T23:29:42.525582Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-22T23:29:42.525692Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-22T23:29:42.525711Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-22T23:29:42.525745Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-22T23:29:42.526907Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-05-22T23:29:42.527036Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-22T23:29:42.53238Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.976616ms"} {"level":"info","ts":"2026-05-22T23:29:42.538915Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-05-22T23:29:42.539027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-22T23:29:42.53906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-05-22T23:29:42.539089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-22T23:29:42.539108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-05-22T23:29:42.539167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-05-22T23:29:42.545365Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-22T23:29:42.546965Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-22T23:29:42.548196Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-22T23:29:42.550551Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-22T23:29:42.550768Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-22T23:29:42.550821Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-22T23:29:42.550926Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-22T23:29:42.550936Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-22T23:29:42.553131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-05-22T23:29:42.553495Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-22T23:29:42.556503Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-22T23:29:42.556626Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-22T23:29:42.556715Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-22T23:29:42.556819Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-22T23:29:42.556886Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-22T23:29:43.140368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-22T23:29:43.140472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-22T23:29:43.140501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-05-22T23:29:43.140555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-05-22T23:29:43.140575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-22T23:29:43.140594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-05-22T23:29:43.140616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-22T23:29:43.14275Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-05-22T23:29:43.142753Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-22T23:29:43.142761Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:43.142817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-22T23:29:43.143095Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-22T23:29:43.143164Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-22T23:29:43.143781Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:43.143909Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:43.143959Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-22T23:29:43.146305Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-22T23:29:43.149085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-05-22T23:29:50.946779Z","caller":"traceutil/trace.go:171","msg":"trace[139673203] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"117.455451ms","start":"2026-05-22T23:29:50.829297Z","end":"2026-05-22T23:29:50.946752Z","steps":["trace[139673203] 'process raft request' (duration: 117.318527ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:29:51.699913Z","caller":"traceutil/trace.go:171","msg":"trace[821339131] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"120.594765ms","start":"2026-05-22T23:29:51.579289Z","end":"2026-05-22T23:29:51.699884Z","steps":["trace[821339131] 'process raft request' (duration: 56.924715ms)","trace[821339131] 'compare' (duration: 63.544287ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:29:56.572011Z","caller":"traceutil/trace.go:171","msg":"trace[1570792593] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"290.90164ms","start":"2026-05-22T23:29:56.281068Z","end":"2026-05-22T23:29:56.571969Z","steps":["trace[1570792593] 'process raft request' (duration: 290.679384ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:30:23.268629Z","caller":"traceutil/trace.go:171","msg":"trace[450109232] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"115.116828ms","start":"2026-05-22T23:30:23.153476Z","end":"2026-05-22T23:30:23.268593Z","steps":["trace[450109232] 'process raft request' (duration: 98.204726ms)","trace[450109232] 'compare' (duration: 16.662836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:30:24.592239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.437735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:30:24.592392Z","caller":"traceutil/trace.go:171","msg":"trace[1218014925] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"252.779457ms","start":"2026-05-22T23:30:24.339591Z","end":"2026-05-22T23:30:24.59237Z","steps":["trace[1218014925] 'process raft request' (duration: 50.328285ms)","trace[1218014925] 'compare' (duration: 201.270911ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:30:25.173203Z","caller":"traceutil/trace.go:171","msg":"trace[242672662] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"191.865505ms","start":"2026-05-22T23:30:24.981311Z","end":"2026-05-22T23:30:25.173177Z","steps":["trace[242672662] 'read index received' (duration: 188.935469ms)","trace[242672662] 'applied index is now lower than readState.Index' (duration: 2.928836ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:30:25.173287Z","caller":"traceutil/trace.go:171","msg":"trace[556982111] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"485.201821ms","start":"2026-05-22T23:30:24.688052Z","end":"2026-05-22T23:30:25.173254Z","steps":["trace[556982111] 'process raft request' (duration: 482.406888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:30:25.173452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:30:24.68802Z","time spent":"485.352975ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T23:30:25.173672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.320717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-22T23:30:25.173776Z","caller":"traceutil/trace.go:171","msg":"trace[335458528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"192.477921ms","start":"2026-05-22T23:30:24.981273Z","end":"2026-05-22T23:30:25.173751Z","steps":["trace[335458528] 'agreement among raft nodes before linearized reading' (duration: 192.129982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:30:25.173829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.260075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-22T23:30:25.173982Z","caller":"traceutil/trace.go:171","msg":"trace[822124512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"166.4692ms","start":"2026-05-22T23:30:25.007475Z","end":"2026-05-22T23:30:25.173944Z","steps":["trace[822124512] 'agreement among raft nodes before linearized reading' (duration: 166.015328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:31:58.552229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.609645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-22T23:31:58.552319Z","caller":"traceutil/trace.go:171","msg":"trace[1894088690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1868; }","duration":"286.754728ms","start":"2026-05-22T23:31:58.265544Z","end":"2026-05-22T23:31:58.552298Z","steps":["trace[1894088690] 'range keys from in-memory index tree' (duration: 286.326377ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:59.000266Z","caller":"traceutil/trace.go:171","msg":"trace[999656086] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"176.916957ms","start":"2026-05-22T23:31:58.823319Z","end":"2026-05-22T23:31:59.000236Z","steps":["trace[999656086] 'read index received' (duration: 176.857246ms)","trace[999656086] 'applied index is now lower than readState.Index' (duration: 58.561µs)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:31:59.000502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.161903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-95dcc097-f245-4b69-9077-9429bd702c9f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-22T23:31:59.000554Z","caller":"traceutil/trace.go:171","msg":"trace[832684701] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-95dcc097-f245-4b69-9077-9429bd702c9f; range_end:; response_count:1; response_revision:1870; }","duration":"177.279466ms","start":"2026-05-22T23:31:58.82326Z","end":"2026-05-22T23:31:59.000539Z","steps":["trace[832684701] 'agreement among raft nodes before linearized reading' (duration: 177.079121ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:31:59.000773Z","caller":"traceutil/trace.go:171","msg":"trace[135314243] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"193.762383ms","start":"2026-05-22T23:31:58.806999Z","end":"2026-05-22T23:31:59.000762Z","steps":["trace[135314243] 'process raft request' (duration: 193.064405ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:12.191184Z","caller":"traceutil/trace.go:171","msg":"trace[104889681] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"156.889929ms","start":"2026-05-22T23:32:12.034255Z","end":"2026-05-22T23:32:12.191145Z","steps":["trace[104889681] 'process raft request' (duration: 156.728855ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:12.191845Z","caller":"traceutil/trace.go:171","msg":"trace[837495936] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"157.027803ms","start":"2026-05-22T23:32:12.034806Z","end":"2026-05-22T23:32:12.191834Z","steps":["trace[837495936] 'process raft request' (duration: 156.971791ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:44.259183Z","caller":"traceutil/trace.go:171","msg":"trace[1618637063] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"208.846265ms","start":"2026-05-22T23:32:44.050313Z","end":"2026-05-22T23:32:44.259159Z","steps":["trace[1618637063] 'process raft request' (duration: 119.597229ms)","trace[1618637063] 'compare' (duration: 89.119652ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:32:44.259367Z","caller":"traceutil/trace.go:171","msg":"trace[1732226665] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2252; }","duration":"153.894981ms","start":"2026-05-22T23:32:44.105463Z","end":"2026-05-22T23:32:44.259358Z","steps":["trace[1732226665] 'read index received' (duration: 64.39894ms)","trace[1732226665] 'applied index is now lower than readState.Index' (duration: 89.495351ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:32:44.259473Z","caller":"traceutil/trace.go:171","msg":"trace[1709340811] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"209.05314ms","start":"2026-05-22T23:32:44.050402Z","end":"2026-05-22T23:32:44.259455Z","steps":["trace[1709340811] 'process raft request' (duration: 208.718672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:44.259564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.094067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-22T23:32:44.259603Z","caller":"traceutil/trace.go:171","msg":"trace[480510571] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"154.167598ms","start":"2026-05-22T23:32:44.105421Z","end":"2026-05-22T23:32:44.259589Z","steps":["trace[480510571] 'agreement among raft nodes before linearized reading' (duration: 154.045005ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:50.183367Z","caller":"traceutil/trace.go:171","msg":"trace[1756196543] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"122.315908ms","start":"2026-05-22T23:32:50.060988Z","end":"2026-05-22T23:32:50.183304Z","steps":["trace[1756196543] 'process raft request' (duration: 121.977258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:50.494828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.199184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:32:50.494939Z","caller":"traceutil/trace.go:171","msg":"trace[481794743] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"224.664462ms","start":"2026-05-22T23:32:50.270254Z","end":"2026-05-22T23:32:50.494919Z","steps":["trace[481794743] 'read index received' (duration: 104.361335ms)","trace[481794743] 'applied index is now lower than readState.Index' (duration: 120.301907ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:32:50.495025Z","caller":"traceutil/trace.go:171","msg":"trace[1850416759] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"307.66749ms","start":"2026-05-22T23:32:50.187336Z","end":"2026-05-22T23:32:50.495003Z","steps":["trace[1850416759] 'process raft request' (duration: 187.198909ms)","trace[1850416759] 'compare' (duration: 120.089811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:32:50.495089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:32:50.1873Z","time spent":"307.757602ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T23:32:50.495597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.224225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-22T23:32:50.495738Z","caller":"traceutil/trace.go:171","msg":"trace[1965214769] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2204; }","duration":"225.527713ms","start":"2026-05-22T23:32:50.27018Z","end":"2026-05-22T23:32:50.495708Z","steps":["trace[1965214769] 'agreement among raft nodes before linearized reading' (duration: 224.99277ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:50.49957Z","caller":"traceutil/trace.go:171","msg":"trace[1184160844] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"166.543599ms","start":"2026-05-22T23:32:50.332924Z","end":"2026-05-22T23:32:50.499467Z","steps":["trace[1184160844] 'process raft request' (duration: 165.941214ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:32:50.500024Z","caller":"traceutil/trace.go:171","msg":"trace[1888642214] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"145.901261ms","start":"2026-05-22T23:32:50.354053Z","end":"2026-05-22T23:32:50.499955Z","steps":["trace[1888642214] 'process raft request' (duration: 145.639673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:50.500787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.523919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-22T23:32:50.500879Z","caller":"traceutil/trace.go:171","msg":"trace[875610019] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2206; }","duration":"199.620992ms","start":"2026-05-22T23:32:50.301221Z","end":"2026-05-22T23:32:50.500842Z","steps":["trace[875610019] 'agreement among raft nodes before linearized reading' (duration: 199.207631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:50.500919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.936778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-22T23:32:50.501058Z","caller":"traceutil/trace.go:171","msg":"trace[589112558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2206; }","duration":"221.166684ms","start":"2026-05-22T23:32:50.279856Z","end":"2026-05-22T23:32:50.501022Z","steps":["trace[589112558] 'agreement among raft nodes before linearized reading' (duration: 220.822065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:50.501451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.067041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-22T23:32:50.501594Z","caller":"traceutil/trace.go:171","msg":"trace[379063703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2206; }","duration":"219.224214ms","start":"2026-05-22T23:32:50.282332Z","end":"2026-05-22T23:32:50.501556Z","steps":["trace[379063703] 'agreement among raft nodes before linearized reading' (duration: 218.373043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:32:50.735692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.847495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:32:50.736129Z","caller":"traceutil/trace.go:171","msg":"trace[402223804] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"229.019262ms","start":"2026-05-22T23:32:50.507027Z","end":"2026-05-22T23:32:50.736047Z","steps":["trace[402223804] 'process raft request' (duration: 110.686045ms)","trace[402223804] 'compare' (duration: 117.688421ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:32:50.736156Z","caller":"traceutil/trace.go:171","msg":"trace[1322053806] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"228.998342ms","start":"2026-05-22T23:32:50.507086Z","end":"2026-05-22T23:32:50.736084Z","steps":["trace[1322053806] 'process raft request' (duration: 228.752136ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:33:44.430571Z","caller":"traceutil/trace.go:171","msg":"trace[1887061719] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"155.555482ms","start":"2026-05-22T23:33:44.27499Z","end":"2026-05-22T23:33:44.430546Z","steps":["trace[1887061719] 'process raft request' (duration: 155.362247ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:36:47.83423Z","caller":"traceutil/trace.go:171","msg":"trace[2006904983] transaction","detail":"{read_only:false; response_revision:3866; number_of_response:1; }","duration":"116.180425ms","start":"2026-05-22T23:36:47.71802Z","end":"2026-05-22T23:36:47.834201Z","steps":["trace[2006904983] 'process raft request' (duration: 115.320421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:42.790013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.809753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:38:42.790224Z","caller":"traceutil/trace.go:171","msg":"trace[1563761250] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"727.641724ms","start":"2026-05-22T23:38:42.062559Z","end":"2026-05-22T23:38:42.790201Z","steps":["trace[1563761250] 'process raft request' (duration: 490.547763ms)","trace[1563761250] 'compare' (duration: 236.6836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:38:42.790299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:38:42.062537Z","time spent":"727.730047ms","remote":"127.0.0.1:34550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T23:38:42.790563Z","caller":"traceutil/trace.go:171","msg":"trace[1699128649] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"727.154691ms","start":"2026-05-22T23:38:42.063355Z","end":"2026-05-22T23:38:42.79051Z","steps":["trace[1699128649] 'process raft request' (duration: 726.774811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:42.790732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:38:42.063344Z","time spent":"727.298625ms","remote":"127.0.0.1:34688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T23:38:42.791141Z","caller":"traceutil/trace.go:171","msg":"trace[1545905609] linearizableReadLoop","detail":"{readStateIndex:5601; appliedIndex:5599; }","duration":"728.141138ms","start":"2026-05-22T23:38:42.06297Z","end":"2026-05-22T23:38:42.791111Z","steps":["trace[1545905609] 'read index received' (duration: 5.93925ms)","trace[1545905609] 'applied index is now lower than readState.Index' (duration: 722.200418ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:38:42.791244Z","caller":"traceutil/trace.go:171","msg":"trace[741126453] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"727.826249ms","start":"2026-05-22T23:38:42.063367Z","end":"2026-05-22T23:38:42.791193Z","steps":["trace[741126453] 'process raft request' (duration: 727.594503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:42.791348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:38:42.063354Z","time spent":"727.946043ms","remote":"127.0.0.1:34688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T23:38:42.791327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.505038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:38:42.79144Z","caller":"traceutil/trace.go:171","msg":"trace[1599050004] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5401; }","duration":"728.660802ms","start":"2026-05-22T23:38:42.062745Z","end":"2026-05-22T23:38:42.791406Z","steps":["trace[1599050004] 'agreement among raft nodes before linearized reading' (duration: 728.488027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:42.791437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.929826ms","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-05-22T23:38:42.791483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:38:42.06273Z","time spent":"728.744894ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-22T23:38:42.791505Z","caller":"traceutil/trace.go:171","msg":"trace[1230836794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5401; }","duration":"338.035349ms","start":"2026-05-22T23:38:42.453443Z","end":"2026-05-22T23:38:42.791478Z","steps":["trace[1230836794] 'agreement among raft nodes before linearized reading' (duration: 337.834503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:42.791554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:38:42.453424Z","time spent":"338.11657ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-22T23:38:42.791584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.828475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-22T23:38:42.791737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.860054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-22T23:38:42.791732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.095751ms","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-05-22T23:38:42.791753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.127055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-22T23:38:42.79178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.090782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-22T23:38:42.7918Z","caller":"traceutil/trace.go:171","msg":"trace[602381623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5401; }","duration":"288.957187ms","start":"2026-05-22T23:38:42.502822Z","end":"2026-05-22T23:38:42.791779Z","steps":["trace[602381623] 'agreement among raft nodes before linearized reading' (duration: 288.840174ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:42.791806Z","caller":"traceutil/trace.go:171","msg":"trace[1739775785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5401; }","duration":"263.207224ms","start":"2026-05-22T23:38:42.528581Z","end":"2026-05-22T23:38:42.791788Z","steps":["trace[1739775785] 'agreement among raft nodes before linearized reading' (duration: 263.029879ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:42.791818Z","caller":"traceutil/trace.go:171","msg":"trace[794543426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5401; }","duration":"263.203304ms","start":"2026-05-22T23:38:42.528603Z","end":"2026-05-22T23:38:42.791806Z","steps":["trace[794543426] 'agreement among raft nodes before linearized reading' (duration: 263.0166ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:42.791825Z","caller":"traceutil/trace.go:171","msg":"trace[525299885] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5401; }","duration":"100.244158ms","start":"2026-05-22T23:38:42.69156Z","end":"2026-05-22T23:38:42.791804Z","steps":["trace[525299885] 'agreement among raft nodes before linearized reading' (duration: 100.115635ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:42.79204Z","caller":"traceutil/trace.go:171","msg":"trace[507764129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5401; }","duration":"262.996619ms","start":"2026-05-22T23:38:42.528672Z","end":"2026-05-22T23:38:42.791669Z","steps":["trace[507764129] 'agreement among raft nodes before linearized reading' (duration: 262.699601ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:49.974758Z","caller":"traceutil/trace.go:171","msg":"trace[56935377] linearizableReadLoop","detail":"{readStateIndex:5788; appliedIndex:5786; }","duration":"122.537868ms","start":"2026-05-22T23:38:49.852196Z","end":"2026-05-22T23:38:49.974734Z","steps":["trace[56935377] 'read index received' (duration: 122.302022ms)","trace[56935377] 'applied index is now lower than readState.Index' (duration: 234.916µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:38:49.974869Z","caller":"traceutil/trace.go:171","msg":"trace[242014514] transaction","detail":"{read_only:false; response_revision:5582; number_of_response:1; }","duration":"162.982456ms","start":"2026-05-22T23:38:49.811869Z","end":"2026-05-22T23:38:49.974851Z","steps":["trace[242014514] 'process raft request' (duration: 162.799421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:49.974994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.785114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tgbwb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-22T23:38:49.97504Z","caller":"traceutil/trace.go:171","msg":"trace[799687857] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tgbwb; range_end:; response_count:1; response_revision:5582; }","duration":"122.883097ms","start":"2026-05-22T23:38:49.852146Z","end":"2026-05-22T23:38:49.975029Z","steps":["trace[799687857] 'agreement among raft nodes before linearized reading' (duration: 122.719633ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:38:49.975044Z","caller":"traceutil/trace.go:171","msg":"trace[1540517007] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"191.096074ms","start":"2026-05-22T23:38:49.783917Z","end":"2026-05-22T23:38:49.975013Z","steps":["trace[1540517007] 'process raft request' (duration: 190.60075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:38:50.199909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.360107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3473de50-5367-4773-ade0-efe58003f183\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-22T23:38:50.200007Z","caller":"traceutil/trace.go:171","msg":"trace[734816348] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3473de50-5367-4773-ade0-efe58003f183; range_end:; response_count:1; response_revision:5582; }","duration":"134.514611ms","start":"2026-05-22T23:38:50.065467Z","end":"2026-05-22T23:38:50.199982Z","steps":["trace[734816348] 'range keys from in-memory index tree' (duration: 134.159152ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:39:43.174127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-05-22T23:39:43.256842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"80.319026ms","hash":3928635976,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":31162368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-22T23:39:43.256925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3928635976,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-05-22T23:44:43.2163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6067} {"level":"info","ts":"2026-05-22T23:44:43.444834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6067,"took":"226.397179ms","hash":3106208726,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":21934080,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-22T23:44:43.444915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3106208726,"revision":6067,"compact-revision":3012} {"level":"info","ts":"2026-05-22T23:44:50.843526Z","caller":"traceutil/trace.go:171","msg":"trace[313008051] linearizableReadLoop","detail":"{readStateIndex:8649; appliedIndex:8647; }","duration":"137.253507ms","start":"2026-05-22T23:44:50.706244Z","end":"2026-05-22T23:44:50.843498Z","steps":["trace[313008051] 'read index received' (duration: 93.711415ms)","trace[313008051] 'applied index is now lower than readState.Index' (duration: 43.541002ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:44:50.843604Z","caller":"traceutil/trace.go:171","msg":"trace[1682720759] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"141.644727ms","start":"2026-05-22T23:44:50.701915Z","end":"2026-05-22T23:44:50.84356Z","steps":["trace[1682720759] 'process raft request' (duration: 141.499293ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:44:50.843565Z","caller":"traceutil/trace.go:171","msg":"trace[1066248238] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"143.123267ms","start":"2026-05-22T23:44:50.700395Z","end":"2026-05-22T23:44:50.843519Z","steps":["trace[1066248238] 'process raft request' (duration: 99.568494ms)","trace[1066248238] 'compare' (duration: 43.263725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:44:50.843747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.428106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-22T23:44:50.843808Z","caller":"traceutil/trace.go:171","msg":"trace[2031658982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8355; }","duration":"185.499108ms","start":"2026-05-22T23:44:50.658293Z","end":"2026-05-22T23:44:50.843793Z","steps":["trace[2031658982] 'agreement among raft nodes before linearized reading' (duration: 185.321774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:44:50.843751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.680471ms","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-05-22T23:44:50.84393Z","caller":"traceutil/trace.go:171","msg":"trace[493731980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8355; }","duration":"186.904916ms","start":"2026-05-22T23:44:50.657Z","end":"2026-05-22T23:44:50.843905Z","steps":["trace[493731980] 'agreement among raft nodes before linearized reading' (duration: 186.65581ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:44:50.85225Z","caller":"traceutil/trace.go:171","msg":"trace[1644619197] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"142.232734ms","start":"2026-05-22T23:44:50.709997Z","end":"2026-05-22T23:44:50.852229Z","steps":["trace[1644619197] 'process raft request' (duration: 141.966116ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:01.562142Z","caller":"traceutil/trace.go:171","msg":"trace[591124697] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"194.445471ms","start":"2026-05-22T23:45:01.367665Z","end":"2026-05-22T23:45:01.562111Z","steps":["trace[591124697] 'process raft request' (duration: 167.984753ms)","trace[591124697] 'compare' (duration: 26.324544ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:01.591977Z","caller":"traceutil/trace.go:171","msg":"trace[36354360] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"177.630915ms","start":"2026-05-22T23:45:01.414309Z","end":"2026-05-22T23:45:01.59194Z","steps":["trace[36354360] 'process raft request' (duration: 177.494432ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:02.1528Z","caller":"traceutil/trace.go:171","msg":"trace[479008235] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"144.430733ms","start":"2026-05-22T23:45:02.008335Z","end":"2026-05-22T23:45:02.152766Z","steps":["trace[479008235] 'process raft request' (duration: 144.154306ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:03.559842Z","caller":"traceutil/trace.go:171","msg":"trace[971928215] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"227.122789ms","start":"2026-05-22T23:45:03.332691Z","end":"2026-05-22T23:45:03.559814Z","steps":["trace[971928215] 'process raft request' (duration: 226.938064ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:03.737044Z","caller":"traceutil/trace.go:171","msg":"trace[1224076667] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"139.869618ms","start":"2026-05-22T23:45:03.597146Z","end":"2026-05-22T23:45:03.737015Z","steps":["trace[1224076667] 'process raft request' (duration: 139.673932ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:05.184192Z","caller":"traceutil/trace.go:171","msg":"trace[363661464] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"183.534015ms","start":"2026-05-22T23:45:05.000632Z","end":"2026-05-22T23:45:05.184166Z","steps":["trace[363661464] 'process raft request' (duration: 149.750717ms)","trace[363661464] 'compare' (duration: 33.556782ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:05.184364Z","caller":"traceutil/trace.go:171","msg":"trace[1177362774] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"183.337859ms","start":"2026-05-22T23:45:05.001Z","end":"2026-05-22T23:45:05.184338Z","steps":["trace[1177362774] 'process raft request' (duration: 183.087453ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:32.062296Z","caller":"traceutil/trace.go:171","msg":"trace[620932289] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"230.082399ms","start":"2026-05-22T23:45:31.832184Z","end":"2026-05-22T23:45:32.062267Z","steps":["trace[620932289] 'process raft request' (duration: 229.863843ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:35.173729Z","caller":"traceutil/trace.go:171","msg":"trace[341279924] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"127.163344ms","start":"2026-05-22T23:45:35.046531Z","end":"2026-05-22T23:45:35.173694Z","steps":["trace[341279924] 'process raft request' (duration: 126.986779ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:35.20603Z","caller":"traceutil/trace.go:171","msg":"trace[1500986898] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"150.115018ms","start":"2026-05-22T23:45:35.055878Z","end":"2026-05-22T23:45:35.205993Z","steps":["trace[1500986898] 'process raft request' (duration: 149.986554ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:35.339707Z","caller":"traceutil/trace.go:171","msg":"trace[1230814757] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"129.415896ms","start":"2026-05-22T23:45:35.210271Z","end":"2026-05-22T23:45:35.339687Z","steps":["trace[1230814757] 'process raft request' (duration: 103.81679ms)","trace[1230814757] 'compare' (duration: 25.390139ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:35.33994Z","caller":"traceutil/trace.go:171","msg":"trace[1855662181] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"129.504718ms","start":"2026-05-22T23:45:35.210415Z","end":"2026-05-22T23:45:35.339919Z","steps":["trace[1855662181] 'process raft request' (duration: 129.21558ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:35.473502Z","caller":"traceutil/trace.go:171","msg":"trace[584536144] linearizableReadLoop","detail":"{readStateIndex:8951; appliedIndex:8950; }","duration":"129.209779ms","start":"2026-05-22T23:45:35.344255Z","end":"2026-05-22T23:45:35.473464Z","steps":["trace[584536144] 'read index received' (duration: 120.064071ms)","trace[584536144] 'applied index is now lower than readState.Index' (duration: 9.144008ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:35.473594Z","caller":"traceutil/trace.go:171","msg":"trace[2096619677] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"129.640011ms","start":"2026-05-22T23:45:35.343924Z","end":"2026-05-22T23:45:35.473564Z","steps":["trace[2096619677] 'process raft request' (duration: 120.232286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:35.473918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.640161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19381"} {"level":"info","ts":"2026-05-22T23:45:35.473986Z","caller":"traceutil/trace.go:171","msg":"trace[639443827] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8645; }","duration":"129.747905ms","start":"2026-05-22T23:45:35.344222Z","end":"2026-05-22T23:45:35.47397Z","steps":["trace[639443827] 'agreement among raft nodes before linearized reading' (duration: 129.380654ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:35.480326Z","caller":"traceutil/trace.go:171","msg":"trace[816427736] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"135.997825ms","start":"2026-05-22T23:45:35.344306Z","end":"2026-05-22T23:45:35.480304Z","steps":["trace[816427736] 'process raft request' (duration: 135.715947ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:45.523983Z","caller":"traceutil/trace.go:171","msg":"trace[387326683] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"139.459088ms","start":"2026-05-22T23:45:45.384494Z","end":"2026-05-22T23:45:45.523953Z","steps":["trace[387326683] 'process raft request' (duration: 97.149279ms)","trace[387326683] 'compare' (duration: 41.930919ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:45.524157Z","caller":"traceutil/trace.go:171","msg":"trace[607901152] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"138.244365ms","start":"2026-05-22T23:45:45.385889Z","end":"2026-05-22T23:45:45.524133Z","steps":["trace[607901152] 'process raft request' (duration: 138.028729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:45:45.765778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.835958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-22T23:45:45.766151Z","caller":"traceutil/trace.go:171","msg":"trace[1118188321] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"162.134012ms","start":"2026-05-22T23:45:45.603975Z","end":"2026-05-22T23:45:45.766109Z","steps":["trace[1118188321] 'process raft request' (duration: 53.859852ms)","trace[1118188321] 'compare' (duration: 107.632143ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:45:45.822506Z","caller":"traceutil/trace.go:171","msg":"trace[1333842083] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"114.716156ms","start":"2026-05-22T23:45:45.707755Z","end":"2026-05-22T23:45:45.822472Z","steps":["trace[1333842083] 'process raft request' (duration: 114.498639ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:45:46.216058Z","caller":"traceutil/trace.go:171","msg":"trace[2021197479] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"107.380796ms","start":"2026-05-22T23:45:46.108654Z","end":"2026-05-22T23:45:46.216035Z","steps":["trace[2021197479] 'process raft request' (duration: 107.247242ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:46:00.138393Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e8.wal"} {"level":"info","ts":"2026-05-22T23:47:00.53942Z","caller":"traceutil/trace.go:171","msg":"trace[401641111] linearizableReadLoop","detail":"{readStateIndex:9809; appliedIndex:9808; }","duration":"172.713855ms","start":"2026-05-22T23:47:00.366685Z","end":"2026-05-22T23:47:00.539398Z","steps":["trace[401641111] 'read index received' (duration: 172.662814ms)","trace[401641111] 'applied index is now lower than readState.Index' (duration: 49.961µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:47:00.539507Z","caller":"traceutil/trace.go:171","msg":"trace[773336776] transaction","detail":"{read_only:false; response_revision:9479; number_of_response:1; }","duration":"267.602566ms","start":"2026-05-22T23:47:00.271871Z","end":"2026-05-22T23:47:00.539474Z","steps":["trace[773336776] 'process raft request' (duration: 267.396271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:47:00.539579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.87601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:47:00.539667Z","caller":"traceutil/trace.go:171","msg":"trace[103536467] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:9479; }","duration":"172.989883ms","start":"2026-05-22T23:47:00.366623Z","end":"2026-05-22T23:47:00.539613Z","steps":["trace[103536467] 'agreement among raft nodes before linearized reading' (duration: 172.897931ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:47:00.66874Z","caller":"traceutil/trace.go:171","msg":"trace[1477412939] transaction","detail":"{read_only:false; response_revision:9480; number_of_response:1; }","duration":"189.566155ms","start":"2026-05-22T23:47:00.479142Z","end":"2026-05-22T23:47:00.668708Z","steps":["trace[1477412939] 'process raft request' (duration: 146.303734ms)","trace[1477412939] 'compare' (duration: 42.50213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-22T23:47:00.668945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.967762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:47:00.669004Z","caller":"traceutil/trace.go:171","msg":"trace[740432182] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:9480; }","duration":"251.076765ms","start":"2026-05-22T23:47:00.417916Z","end":"2026-05-22T23:47:00.668993Z","steps":["trace[740432182] 'agreement among raft nodes before linearized reading' (duration: 250.863739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:47:00.669046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.575799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:47:00.669141Z","caller":"traceutil/trace.go:171","msg":"trace[69116931] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:9480; }","duration":"258.737854ms","start":"2026-05-22T23:47:00.410379Z","end":"2026-05-22T23:47:00.669117Z","steps":["trace[69116931] 'agreement among raft nodes before linearized reading' (duration: 258.561729ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:47:00.669211Z","caller":"traceutil/trace.go:171","msg":"trace[2144232221] linearizableReadLoop","detail":"{readStateIndex:9810; appliedIndex:9809; }","duration":"129.207257ms","start":"2026-05-22T23:47:00.539498Z","end":"2026-05-22T23:47:00.668705Z","steps":["trace[2144232221] 'read index received' (duration: 85.942976ms)","trace[2144232221] 'applied index is now lower than readState.Index' (duration: 43.262401ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:47:24.759441Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-22T23:47:24.765326Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-22T23:47:24.765403Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-22T23:49:43.221577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8185} {"level":"info","ts":"2026-05-22T23:49:43.285573Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8185,"took":"61.632143ms","hash":1371469114,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":29597696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-22T23:49:43.285632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1371469114,"revision":8185,"compact-revision":6067} {"level":"warn","ts":"2026-05-22T23:50:44.60556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.427659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:50:44.605741Z","caller":"traceutil/trace.go:171","msg":"trace[145088764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11322; }","duration":"101.677066ms","start":"2026-05-22T23:50:44.504037Z","end":"2026-05-22T23:50:44.605714Z","steps":["trace[145088764] 'range keys from in-memory index tree' (duration: 101.296836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:50:44.605877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.669356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tgbwb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-22T23:50:44.605913Z","caller":"traceutil/trace.go:171","msg":"trace[1096278903] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tgbwb; range_end:; response_count:1; response_revision:11322; }","duration":"101.731177ms","start":"2026-05-22T23:50:44.504174Z","end":"2026-05-22T23:50:44.605905Z","steps":["trace[1096278903] 'range keys from in-memory index tree' (duration: 101.541192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:50:44.606173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.721804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-22T23:50:44.606276Z","caller":"traceutil/trace.go:171","msg":"trace[1361742821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11322; }","duration":"129.330551ms","start":"2026-05-22T23:50:44.476869Z","end":"2026-05-22T23:50:44.6062Z","steps":["trace[1361742821] 'range keys from in-memory index tree' (duration: 128.478238ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:54:43.232033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10773} {"level":"info","ts":"2026-05-22T23:54:43.313537Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10773,"took":"78.901123ms","hash":967044655,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":27115520,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-22T23:54:43.313604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":967044655,"revision":10773,"compact-revision":8185} {"level":"info","ts":"2026-05-22T23:57:01.159233Z","caller":"traceutil/trace.go:171","msg":"trace[59289063] transaction","detail":"{read_only:false; response_revision:13853; number_of_response:1; }","duration":"154.235211ms","start":"2026-05-22T23:57:01.004968Z","end":"2026-05-22T23:57:01.159204Z","steps":["trace[59289063] 'process raft request' (duration: 154.031056ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:57:01.260848Z","caller":"traceutil/trace.go:171","msg":"trace[1607866985] transaction","detail":"{read_only:false; response_revision:13854; number_of_response:1; }","duration":"253.221277ms","start":"2026-05-22T23:57:01.007603Z","end":"2026-05-22T23:57:01.260824Z","steps":["trace[1607866985] 'process raft request' (duration: 222.628964ms)","trace[1607866985] 'compare' (duration: 30.445889ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:57:01.261083Z","caller":"traceutil/trace.go:171","msg":"trace[121911017] transaction","detail":"{read_only:false; response_revision:13855; number_of_response:1; }","duration":"252.807155ms","start":"2026-05-22T23:57:01.008252Z","end":"2026-05-22T23:57:01.26106Z","steps":["trace[121911017] 'process raft request' (duration: 252.541908ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:57:01.261145Z","caller":"traceutil/trace.go:171","msg":"trace[657949102] linearizableReadLoop","detail":"{readStateIndex:14327; appliedIndex:14323; }","duration":"144.699241ms","start":"2026-05-22T23:57:01.116417Z","end":"2026-05-22T23:57:01.261117Z","steps":["trace[657949102] 'read index received' (duration: 42.566669ms)","trace[657949102] 'applied index is now lower than readState.Index' (duration: 102.130972ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:57:01.261238Z","caller":"traceutil/trace.go:171","msg":"trace[1680684598] transaction","detail":"{read_only:false; response_revision:13856; number_of_response:1; }","duration":"184.287939ms","start":"2026-05-22T23:57:01.076944Z","end":"2026-05-22T23:57:01.261232Z","steps":["trace[1680684598] 'process raft request' (duration: 184.029482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:01.261294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.867545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:57:01.261306Z","caller":"traceutil/trace.go:171","msg":"trace[897906772] transaction","detail":"{read_only:false; response_revision:13857; number_of_response:1; }","duration":"111.732904ms","start":"2026-05-22T23:57:01.149564Z","end":"2026-05-22T23:57:01.261297Z","steps":["trace[897906772] 'process raft request' (duration: 111.504477ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:57:01.261337Z","caller":"traceutil/trace.go:171","msg":"trace[1122964254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13857; }","duration":"144.964448ms","start":"2026-05-22T23:57:01.116361Z","end":"2026-05-22T23:57:01.261325Z","steps":["trace[1122964254] 'agreement among raft nodes before linearized reading' (duration: 144.856755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.739535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421303925953336521,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-22T23:57:17.807397Z","caller":"traceutil/trace.go:171","msg":"trace[2112069324] transaction","detail":"{read_only:false; response_revision:13953; number_of_response:1; }","duration":"728.125733ms","start":"2026-05-22T23:57:17.079241Z","end":"2026-05-22T23:57:17.807367Z","steps":["trace[2112069324] 'process raft request' (duration: 727.961819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.807577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.079204Z","time spent":"728.260037ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-22T23:57:17.819295Z","caller":"traceutil/trace.go:171","msg":"trace[512622828] linearizableReadLoop","detail":"{readStateIndex:14430; appliedIndex:14428; }","duration":"580.077291ms","start":"2026-05-22T23:57:17.239201Z","end":"2026-05-22T23:57:17.819279Z","steps":["trace[512622828] 'read index received' (duration: 568.016842ms)","trace[512622828] 'applied index is now lower than readState.Index' (duration: 12.059338ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:57:17.81949Z","caller":"traceutil/trace.go:171","msg":"trace[835330110] transaction","detail":"{read_only:false; response_revision:13954; number_of_response:1; }","duration":"510.046163ms","start":"2026-05-22T23:57:17.309405Z","end":"2026-05-22T23:57:17.819451Z","steps":["trace[835330110] 'process raft request' (duration: 509.774916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.819674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.309384Z","time spent":"510.179707ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-22T23:57:17.819694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.460361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-22T23:57:17.819772Z","caller":"traceutil/trace.go:171","msg":"trace[1645820886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13954; }","duration":"580.606985ms","start":"2026-05-22T23:57:17.239142Z","end":"2026-05-22T23:57:17.819749Z","steps":["trace[1645820886] 'agreement among raft nodes before linearized reading' (duration: 580.330368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.819818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.239122Z","time spent":"580.686727ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-22T23:57:17.82068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.382566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:57:17.820721Z","caller":"traceutil/trace.go:171","msg":"trace[1233653321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13955; }","duration":"317.471648ms","start":"2026-05-22T23:57:17.503241Z","end":"2026-05-22T23:57:17.820713Z","steps":["trace[1233653321] 'agreement among raft nodes before linearized reading' (duration: 317.303303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.820743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.503218Z","time spent":"317.52038ms","remote":"127.0.0.1:34350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-22T23:57:17.820808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.607705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-22T23:57:17.820854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.575019ms","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-05-22T23:57:17.820897Z","caller":"traceutil/trace.go:171","msg":"trace[1075624936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13955; }","duration":"490.724728ms","start":"2026-05-22T23:57:17.330157Z","end":"2026-05-22T23:57:17.820882Z","steps":["trace[1075624936] 'agreement among raft nodes before linearized reading' (duration: 490.362067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.820771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.420973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-22T23:57:17.820946Z","caller":"traceutil/trace.go:171","msg":"trace[1251446612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13955; }","duration":"484.667822ms","start":"2026-05-22T23:57:17.336222Z","end":"2026-05-22T23:57:17.82089Z","steps":["trace[1251446612] 'agreement among raft nodes before linearized reading' (duration: 484.481777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.820969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.33014Z","time spent":"490.815181ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-22T23:57:17.820986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.336193Z","time spent":"484.786105ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-22T23:57:17.820992Z","caller":"traceutil/trace.go:171","msg":"trace[15014979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13955; }","duration":"478.70086ms","start":"2026-05-22T23:57:17.342272Z","end":"2026-05-22T23:57:17.820973Z","steps":["trace[15014979] 'agreement among raft nodes before linearized reading' (duration: 478.306508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:17.821038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-22T23:57:17.342247Z","time spent":"478.780151ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-22T23:57:38.870742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.939777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-22T23:57:38.870884Z","caller":"traceutil/trace.go:171","msg":"trace[1305097590] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:14260; }","duration":"142.142442ms","start":"2026-05-22T23:57:38.728713Z","end":"2026-05-22T23:57:38.870855Z","steps":["trace[1305097590] 'range keys from in-memory index tree' (duration: 141.728471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:57:38.870905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.176119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-22T23:57:38.871021Z","caller":"traceutil/trace.go:171","msg":"trace[59784298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14260; }","duration":"176.388825ms","start":"2026-05-22T23:57:38.694604Z","end":"2026-05-22T23:57:38.870993Z","steps":["trace[59784298] 'range keys from in-memory index tree' (duration: 175.946233ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:57:39.089869Z","caller":"traceutil/trace.go:171","msg":"trace[1223623195] transaction","detail":"{read_only:false; response_revision:14261; number_of_response:1; }","duration":"213.708951ms","start":"2026-05-22T23:57:38.876123Z","end":"2026-05-22T23:57:39.089832Z","steps":["trace[1223623195] 'process raft request' (duration: 213.535717ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:30.241155Z","caller":"traceutil/trace.go:171","msg":"trace[1203909399] linearizableReadLoop","detail":"{readStateIndex:15388; appliedIndex:15387; }","duration":"151.819205ms","start":"2026-05-22T23:58:30.089295Z","end":"2026-05-22T23:58:30.241114Z","steps":["trace[1203909399] 'read index received' (duration: 151.574169ms)","trace[1203909399] 'applied index is now lower than readState.Index' (duration: 243.546µs)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:58:30.241239Z","caller":"traceutil/trace.go:171","msg":"trace[221204753] transaction","detail":"{read_only:false; response_revision:14885; number_of_response:1; }","duration":"157.13888ms","start":"2026-05-22T23:58:30.084059Z","end":"2026-05-22T23:58:30.241198Z","steps":["trace[221204753] 'process raft request' (duration: 156.775561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:30.241379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.056911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-22T23:58:30.241435Z","caller":"traceutil/trace.go:171","msg":"trace[596000384] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:14885; }","duration":"152.179164ms","start":"2026-05-22T23:58:30.089236Z","end":"2026-05-22T23:58:30.241416Z","steps":["trace[596000384] 'agreement among raft nodes before linearized reading' (duration: 152.056271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:30.241741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.744323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-22T23:58:30.241794Z","caller":"traceutil/trace.go:171","msg":"trace[1341002346] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14887; }","duration":"147.845666ms","start":"2026-05-22T23:58:30.093935Z","end":"2026-05-22T23:58:30.241781Z","steps":["trace[1341002346] 'agreement among raft nodes before linearized reading' (duration: 147.689072ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:30.242156Z","caller":"traceutil/trace.go:171","msg":"trace[1979977243] transaction","detail":"{read_only:false; response_revision:14887; number_of_response:1; }","duration":"120.505493ms","start":"2026-05-22T23:58:30.121609Z","end":"2026-05-22T23:58:30.242115Z","steps":["trace[1979977243] 'process raft request' (duration: 119.974878ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:30.242388Z","caller":"traceutil/trace.go:171","msg":"trace[1912362536] transaction","detail":"{read_only:false; response_revision:14886; number_of_response:1; }","duration":"147.027355ms","start":"2026-05-22T23:58:30.095347Z","end":"2026-05-22T23:58:30.242375Z","steps":["trace[1912362536] 'process raft request' (duration: 146.097049ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:54.283616Z","caller":"traceutil/trace.go:171","msg":"trace[68846730] transaction","detail":"{read_only:false; response_revision:15012; number_of_response:1; }","duration":"230.732446ms","start":"2026-05-22T23:58:54.052852Z","end":"2026-05-22T23:58:54.283585Z","steps":["trace[68846730] 'process raft request' (duration: 230.54866ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:54.475501Z","caller":"traceutil/trace.go:171","msg":"trace[232416284] linearizableReadLoop","detail":"{readStateIndex:15522; appliedIndex:15521; }","duration":"144.674911ms","start":"2026-05-22T23:58:54.330791Z","end":"2026-05-22T23:58:54.475466Z","steps":["trace[232416284] 'read index received' (duration: 143.570371ms)","trace[232416284] 'applied index is now lower than readState.Index' (duration: 1.10334ms)"],"step_count":2} {"level":"info","ts":"2026-05-22T23:58:54.475736Z","caller":"traceutil/trace.go:171","msg":"trace[598749959] transaction","detail":"{read_only:false; response_revision:15013; number_of_response:1; }","duration":"146.753507ms","start":"2026-05-22T23:58:54.328924Z","end":"2026-05-22T23:58:54.475677Z","steps":["trace[598749959] 'process raft request' (duration: 145.423841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.475781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.976249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:58:54.475945Z","caller":"traceutil/trace.go:171","msg":"trace[700160304] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15013; }","duration":"145.157474ms","start":"2026-05-22T23:58:54.330768Z","end":"2026-05-22T23:58:54.475925Z","steps":["trace[700160304] 'agreement among raft nodes before linearized reading' (duration: 144.927828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.479778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.754415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-22T23:58:54.479794Z","caller":"traceutil/trace.go:171","msg":"trace[1422448671] transaction","detail":"{read_only:false; response_revision:15015; number_of_response:1; }","duration":"143.062688ms","start":"2026-05-22T23:58:54.336689Z","end":"2026-05-22T23:58:54.479751Z","steps":["trace[1422448671] 'process raft request' (duration: 142.964565ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:54.479847Z","caller":"traceutil/trace.go:171","msg":"trace[1173983515] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15015; }","duration":"147.845797ms","start":"2026-05-22T23:58:54.331983Z","end":"2026-05-22T23:58:54.479829Z","steps":["trace[1173983515] 'agreement among raft nodes before linearized reading' (duration: 147.728004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.479904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.521321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-22T23:58:54.479955Z","caller":"traceutil/trace.go:171","msg":"trace[1962519085] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:15015; }","duration":"121.596943ms","start":"2026-05-22T23:58:54.358341Z","end":"2026-05-22T23:58:54.479938Z","steps":["trace[1962519085] 'agreement among raft nodes before linearized reading' (duration: 121.375677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.480174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.755662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-22T23:58:54.480179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.449616ms","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-05-22T23:58:54.480196Z","caller":"traceutil/trace.go:171","msg":"trace[1593340809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15015; }","duration":"101.800304ms","start":"2026-05-22T23:58:54.378389Z","end":"2026-05-22T23:58:54.48019Z","steps":["trace[1593340809] 'agreement among raft nodes before linearized reading' (duration: 101.721292ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:54.480211Z","caller":"traceutil/trace.go:171","msg":"trace[26786129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15015; }","duration":"133.498346ms","start":"2026-05-22T23:58:54.346703Z","end":"2026-05-22T23:58:54.480201Z","steps":["trace[26786129] 'agreement among raft nodes before linearized reading' (duration: 133.376303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.480344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.751838ms","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-05-22T23:58:54.480366Z","caller":"traceutil/trace.go:171","msg":"trace[419251485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15015; }","duration":"117.796629ms","start":"2026-05-22T23:58:54.362564Z","end":"2026-05-22T23:58:54.480361Z","steps":["trace[419251485] 'agreement among raft nodes before linearized reading' (duration: 117.733827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.480409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.934587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-22T23:58:54.480416Z","caller":"traceutil/trace.go:171","msg":"trace[502589430] transaction","detail":"{read_only:false; response_revision:15014; number_of_response:1; }","duration":"144.97884ms","start":"2026-05-22T23:58:54.335417Z","end":"2026-05-22T23:58:54.480396Z","steps":["trace[502589430] 'process raft request' (duration: 144.120036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.480345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.748192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-22T23:58:54.480498Z","caller":"traceutil/trace.go:171","msg":"trace[936897568] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15015; }","duration":"137.922287ms","start":"2026-05-22T23:58:54.34257Z","end":"2026-05-22T23:58:54.480492Z","steps":["trace[936897568] 'agreement among raft nodes before linearized reading' (duration: 137.710222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.480509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.387336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-22T23:58:54.48053Z","caller":"traceutil/trace.go:171","msg":"trace[581951549] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15015; }","duration":"147.425026ms","start":"2026-05-22T23:58:54.333098Z","end":"2026-05-22T23:58:54.480524Z","steps":["trace[581951549] 'agreement among raft nodes before linearized reading' (duration: 147.365194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-22T23:58:54.480639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.917079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-05-22T23:58:54.480696Z","caller":"traceutil/trace.go:171","msg":"trace[1829163416] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15015; }","duration":"148.005292ms","start":"2026-05-22T23:58:54.332675Z","end":"2026-05-22T23:58:54.480681Z","steps":["trace[1829163416] 'agreement among raft nodes before linearized reading' (duration: 147.821227ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:58:54.480446Z","caller":"traceutil/trace.go:171","msg":"trace[1633172774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15015; }","duration":"138.002979ms","start":"2026-05-22T23:58:54.342434Z","end":"2026-05-22T23:58:54.480437Z","steps":["trace[1633172774] 'agreement among raft nodes before linearized reading' (duration: 137.927467ms)"],"step_count":1} {"level":"info","ts":"2026-05-22T23:59:43.23933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12868} {"level":"info","ts":"2026-05-22T23:59:43.31549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12868,"took":"73.806753ms","hash":552982963,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-22T23:59:43.315564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":552982963,"revision":12868,"compact-revision":10773} {"level":"info","ts":"2026-05-23T00:04:43.245083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15326} {"level":"info","ts":"2026-05-23T00:04:43.344239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15326,"took":"96.016769ms","hash":1489692226,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":31584256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-23T00:04:43.344314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1489692226,"revision":15326,"compact-revision":12868} {"level":"info","ts":"2026-05-23T00:09:43.251203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17656} {"level":"info","ts":"2026-05-23T00:09:43.365803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17656,"took":"109.783375ms","hash":2981701989,"current-db-size-bytes":57180160,"current-db-size":"57 MB","current-db-size-in-use-bytes":26689536,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-23T00:09:43.36587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2981701989,"revision":17656,"compact-revision":15326} {"level":"info","ts":"2026-05-23T00:09:58.92658Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-23T00:09:58.932025Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-23T00:09:58.93213Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}