{"level":"warn","ts":"2026-05-05T12:50:29.832342Z","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-05T12:50:29.832443Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-05T12:50:29.83252Z","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-05T12:50:29.832533Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-05T12:50:29.832553Z","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-05T12:50:29.833215Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-05T12:50:29.833293Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-05T12:50:29.838403Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.873071ms"} {"level":"info","ts":"2026-05-05T12:50:29.852966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-05T12:50:29.853112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:50:29.853149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-05T12:50:29.85318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T12:50:29.853206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-05T12:50:29.853261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-05T12:50:29.860585Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:50:29.862278Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:50:29.863284Z","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-05T12:50:29.865434Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T12:50:29.865581Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T12:50:29.865711Z","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-05T12:50:29.865834Z","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-05T12:50:29.865843Z","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-05T12:50:29.8665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-05T12:50:29.867585Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-05T12:50:29.870688Z","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-05T12:50:29.870853Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-05T12:50:29.870964Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-05T12:50:29.871153Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T12:50:29.871222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:50:30.754158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:50:30.754258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:50:30.754287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-05T12:50:30.754307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:50:30.754318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-05T12:50:30.754334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-05T12:50:30.754347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-05T12:50:30.755329Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:30.7561Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T12:50:30.75612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:50:30.756182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:50:30.756405Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:50:30.756464Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:50:30.757072Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:30.757176Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:30.75721Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:30.759993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-05T12:50:30.760032Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T12:50:35.662414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.184969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-05T12:50:35.662529Z","caller":"traceutil/trace.go:171","msg":"trace[700745465] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:234; }","duration":"120.366075ms","start":"2026-05-05T12:50:35.542136Z","end":"2026-05-05T12:50:35.662502Z","steps":["trace[700745465] 'range keys from in-memory index tree' (duration: 120.045386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:41.994427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.736353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-05T12:50:41.994565Z","caller":"traceutil/trace.go:171","msg":"trace[328598450] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:350; }","duration":"175.937928ms","start":"2026-05-05T12:50:41.8186Z","end":"2026-05-05T12:50:41.994538Z","steps":["trace[328598450] 'range keys from in-memory index tree' (duration: 175.596788ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:42.119642Z","caller":"traceutil/trace.go:171","msg":"trace[825152465] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"120.599841ms","start":"2026-05-05T12:50:41.999012Z","end":"2026-05-05T12:50:42.119612Z","steps":["trace[825152465] 'process raft request' (duration: 120.372445ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:42.358696Z","caller":"traceutil/trace.go:171","msg":"trace[2071509544] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"102.72623ms","start":"2026-05-05T12:50:42.255933Z","end":"2026-05-05T12:50:42.35866Z","steps":["trace[2071509544] 'read index received' (duration: 97.568042ms)","trace[2071509544] 'applied index is now lower than readState.Index' (duration: 5.156768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:42.359069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.119801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T12:50:42.359136Z","caller":"traceutil/trace.go:171","msg":"trace[2116514298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:352; }","duration":"103.248805ms","start":"2026-05-05T12:50:42.255871Z","end":"2026-05-05T12:50:42.35912Z","steps":["trace[2116514298] 'agreement among raft nodes before linearized reading' (duration: 102.931676ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:42.358971Z","caller":"traceutil/trace.go:171","msg":"trace[2116833115] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"355.087741ms","start":"2026-05-05T12:50:42.003833Z","end":"2026-05-05T12:50:42.358921Z","steps":["trace[2116833115] 'process raft request' (duration: 349.655175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:42.368712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:42.003813Z","time spent":"355.3829ms","remote":"127.0.0.1:33518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:01.702979Z","caller":"traceutil/trace.go:171","msg":"trace[1417789729] transaction","detail":"{read_only:false; number_of_response:1; response_revision:493; }","duration":"201.265778ms","start":"2026-05-05T12:51:01.501662Z","end":"2026-05-05T12:51:01.702928Z","steps":["trace[1417789729] 'process raft request' (duration: 168.373104ms)","trace[1417789729] 'compare' (duration: 32.74843ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:01.704035Z","caller":"traceutil/trace.go:171","msg":"trace[1148454540] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"198.815132ms","start":"2026-05-05T12:51:01.505192Z","end":"2026-05-05T12:51:01.704007Z","steps":["trace[1148454540] 'process raft request' (duration: 198.521544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:01.704488Z","caller":"traceutil/trace.go:171","msg":"trace[1179647951] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"192.01244ms","start":"2026-05-05T12:51:01.512433Z","end":"2026-05-05T12:51:01.704445Z","steps":["trace[1179647951] 'process raft request' (duration: 191.514306ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:01.704535Z","caller":"traceutil/trace.go:171","msg":"trace[834505605] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"189.153933ms","start":"2026-05-05T12:51:01.515358Z","end":"2026-05-05T12:51:01.704512Z","steps":["trace[834505605] 'process raft request' (duration: 188.948007ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:07.892342Z","caller":"traceutil/trace.go:171","msg":"trace[1486652121] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"243.247576ms","start":"2026-05-05T12:51:07.649048Z","end":"2026-05-05T12:51:07.892296Z","steps":["trace[1486652121] 'process raft request' (duration: 178.807345ms)","trace[1486652121] 'compare' (duration: 64.183475ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:08.168055Z","caller":"traceutil/trace.go:171","msg":"trace[1244307019] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"120.117068ms","start":"2026-05-05T12:51:08.047909Z","end":"2026-05-05T12:51:08.168026Z","steps":["trace[1244307019] 'read index received' (duration: 44.738692ms)","trace[1244307019] 'applied index is now lower than readState.Index' (duration: 75.377446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:08.168205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.285962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:08.168241Z","caller":"traceutil/trace.go:171","msg":"trace[1279236129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"120.378414ms","start":"2026-05-05T12:51:08.047851Z","end":"2026-05-05T12:51:08.168229Z","steps":["trace[1279236129] 'agreement among raft nodes before linearized reading' (duration: 120.275921ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:08.16848Z","caller":"traceutil/trace.go:171","msg":"trace[1421729094] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"256.448161ms","start":"2026-05-05T12:51:07.91201Z","end":"2026-05-05T12:51:08.168458Z","steps":["trace[1421729094] 'process raft request' (duration: 180.710806ms)","trace[1421729094] 'compare' (duration: 75.18506ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:08.695961Z","caller":"traceutil/trace.go:171","msg":"trace[490331862] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"445.312096ms","start":"2026-05-05T12:51:08.250596Z","end":"2026-05-05T12:51:08.695908Z","steps":["trace[490331862] 'process raft request' (duration: 378.269655ms)","trace[490331862] 'compare' (duration: 66.731273ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:08.696265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:08.25056Z","time spent":"445.534332ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:08.711048Z","caller":"traceutil/trace.go:171","msg":"trace[794669051] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:598; }","duration":"219.92897ms","start":"2026-05-05T12:51:08.491091Z","end":"2026-05-05T12:51:08.71102Z","steps":["trace[794669051] 'read index received' (duration: 137.830324ms)","trace[794669051] 'applied index is now lower than readState.Index' (duration: 82.097666ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:08.711124Z","caller":"traceutil/trace.go:171","msg":"trace[473143595] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"400.41087ms","start":"2026-05-05T12:51:08.310676Z","end":"2026-05-05T12:51:08.711087Z","steps":["trace[473143595] 'process raft request' (duration: 399.806524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:08.711292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.169726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T12:51:08.711301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:08.310645Z","time spent":"400.554544ms","remote":"127.0.0.1:33422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:08.711353Z","caller":"traceutil/trace.go:171","msg":"trace[356086133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"220.273049ms","start":"2026-05-05T12:51:08.491056Z","end":"2026-05-05T12:51:08.711329Z","steps":["trace[356086133] 'agreement among raft nodes before linearized reading' (duration: 220.085384ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:10.124929Z","caller":"traceutil/trace.go:171","msg":"trace[1000168799] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"116.233905ms","start":"2026-05-05T12:51:10.008659Z","end":"2026-05-05T12:51:10.124893Z","steps":["trace[1000168799] 'process raft request' (duration: 116.180944ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:10.124975Z","caller":"traceutil/trace.go:171","msg":"trace[1870080795] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"116.359379ms","start":"2026-05-05T12:51:10.008576Z","end":"2026-05-05T12:51:10.124935Z","steps":["trace[1870080795] 'process raft request' (duration: 116.108862ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:10.128976Z","caller":"traceutil/trace.go:171","msg":"trace[1132139415] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"109.766851ms","start":"2026-05-05T12:51:10.019191Z","end":"2026-05-05T12:51:10.128958Z","steps":["trace[1132139415] 'process raft request' (duration: 109.463193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:36.276638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.824915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:36.276985Z","caller":"traceutil/trace.go:171","msg":"trace[1792225238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1784; }","duration":"229.194155ms","start":"2026-05-05T12:52:36.047736Z","end":"2026-05-05T12:52:36.27693Z","steps":["trace[1792225238] 'range keys from in-memory index tree' (duration: 228.649309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:36.662996Z","caller":"traceutil/trace.go:171","msg":"trace[981287759] linearizableReadLoop","detail":"{readStateIndex:1853; appliedIndex:1852; }","duration":"223.252622ms","start":"2026-05-05T12:52:36.439709Z","end":"2026-05-05T12:52:36.662962Z","steps":["trace[981287759] 'read index received' (duration: 223.036656ms)","trace[981287759] 'applied index is now lower than readState.Index' (duration: 214.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:36.663121Z","caller":"traceutil/trace.go:171","msg":"trace[1729642094] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"328.302561ms","start":"2026-05-05T12:52:36.334797Z","end":"2026-05-05T12:52:36.663099Z","steps":["trace[1729642094] 'process raft request' (duration: 328.011613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:36.663166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.437748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:36.663207Z","caller":"traceutil/trace.go:171","msg":"trace[1748179973] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1785; }","duration":"223.534901ms","start":"2026-05-05T12:52:36.439657Z","end":"2026-05-05T12:52:36.663192Z","steps":["trace[1748179973] 'agreement among raft nodes before linearized reading' (duration: 223.448518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:36.663245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:36.334776Z","time spent":"328.395504ms","remote":"127.0.0.1:33518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:52:36.663483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.898317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-05T12:52:36.663542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.573782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T12:52:36.663611Z","caller":"traceutil/trace.go:171","msg":"trace[183870155] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1785; }","duration":"180.088352ms","start":"2026-05-05T12:52:36.483489Z","end":"2026-05-05T12:52:36.663577Z","steps":["trace[183870155] 'agreement among raft nodes before linearized reading' (duration: 179.696011ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:36.663661Z","caller":"traceutil/trace.go:171","msg":"trace[826387280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1785; }","duration":"150.743127ms","start":"2026-05-05T12:52:36.512897Z","end":"2026-05-05T12:52:36.66364Z","steps":["trace[826387280] 'agreement among raft nodes before linearized reading' (duration: 150.418788ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:36.953035Z","caller":"traceutil/trace.go:171","msg":"trace[273021359] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1853; }","duration":"261.695424ms","start":"2026-05-05T12:52:36.691306Z","end":"2026-05-05T12:52:36.953002Z","steps":["trace[273021359] 'read index received' (duration: 3.156195ms)","trace[273021359] 'applied index is now lower than readState.Index' (duration: 258.537639ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:36.95309Z","caller":"traceutil/trace.go:171","msg":"trace[1980479136] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"284.106362ms","start":"2026-05-05T12:52:36.668943Z","end":"2026-05-05T12:52:36.95305Z","steps":["trace[1980479136] 'process raft request' (duration: 200.186998ms)","trace[1980479136] 'compare' (duration: 83.720869ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:36.953349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.021633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-05T12:52:36.953372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.17126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T12:52:36.953431Z","caller":"traceutil/trace.go:171","msg":"trace[299073291] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1787; }","duration":"262.165697ms","start":"2026-05-05T12:52:36.691241Z","end":"2026-05-05T12:52:36.953407Z","steps":["trace[299073291] 'agreement among raft nodes before linearized reading' (duration: 261.93321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:36.953438Z","caller":"traceutil/trace.go:171","msg":"trace[962269041] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1787; }","duration":"178.316723ms","start":"2026-05-05T12:52:36.775098Z","end":"2026-05-05T12:52:36.953415Z","steps":["trace[962269041] 'agreement among raft nodes before linearized reading' (duration: 178.101477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:36.953522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.018991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:36.953573Z","caller":"traceutil/trace.go:171","msg":"trace[1445716389] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1787; }","duration":"152.136273ms","start":"2026-05-05T12:52:36.801425Z","end":"2026-05-05T12:52:36.953561Z","steps":["trace[1445716389] 'agreement among raft nodes before linearized reading' (duration: 152.054461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:49.865369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.809335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T12:52:49.86551Z","caller":"traceutil/trace.go:171","msg":"trace[243507176] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1903; }","duration":"104.094733ms","start":"2026-05-05T12:52:49.761367Z","end":"2026-05-05T12:52:49.865462Z","steps":["trace[243507176] 'range keys from in-memory index tree' (duration: 103.497276ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:53:21.615223Z","caller":"traceutil/trace.go:171","msg":"trace[1144320788] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"162.471855ms","start":"2026-05-05T12:53:21.452713Z","end":"2026-05-05T12:53:21.615184Z","steps":["trace[1144320788] 'process raft request' (duration: 151.333023ms)","trace[1144320788] 'compare' (duration: 10.998978ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:53:27.152937Z","caller":"traceutil/trace.go:171","msg":"trace[380543872] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"121.856013ms","start":"2026-05-05T12:53:27.031049Z","end":"2026-05-05T12:53:27.152905Z","steps":["trace[380543872] 'process raft request' (duration: 121.602336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:44.819473Z","caller":"traceutil/trace.go:171","msg":"trace[314060948] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"173.044384ms","start":"2026-05-05T12:58:44.646406Z","end":"2026-05-05T12:58:44.819451Z","steps":["trace[314060948] 'process raft request' (duration: 172.91698ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:46.520054Z","caller":"traceutil/trace.go:171","msg":"trace[799684172] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"362.628851ms","start":"2026-05-05T12:58:46.157403Z","end":"2026-05-05T12:58:46.520031Z","steps":["trace[799684172] 'process raft request' (duration: 362.477557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:46.520174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:58:46.157389Z","time spent":"362.725783ms","remote":"127.0.0.1:42684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:58:46.520298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.449976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6q7vq\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T12:58:46.520357Z","caller":"traceutil/trace.go:171","msg":"trace[46512556] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6q7vq; range_end:; response_count:1; response_revision:4778; }","duration":"134.549259ms","start":"2026-05-05T12:58:46.385795Z","end":"2026-05-05T12:58:46.520345Z","steps":["trace[46512556] 'agreement among raft nodes before linearized reading' (duration: 134.337883ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:46.520017Z","caller":"traceutil/trace.go:171","msg":"trace[965817318] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4957; }","duration":"134.164158ms","start":"2026-05-05T12:58:46.385833Z","end":"2026-05-05T12:58:46.519997Z","steps":["trace[965817318] 'read index received' (duration: 134.037685ms)","trace[965817318] 'applied index is now lower than readState.Index' (duration: 125.733µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:58:57.092484Z","caller":"traceutil/trace.go:171","msg":"trace[1018480640] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"105.348508ms","start":"2026-05-05T12:58:56.987088Z","end":"2026-05-05T12:58:57.092436Z","steps":["trace[1018480640] 'process raft request' (duration: 105.128712ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:57.111967Z","caller":"traceutil/trace.go:171","msg":"trace[864430815] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"123.553258ms","start":"2026-05-05T12:58:56.988384Z","end":"2026-05-05T12:58:57.111937Z","steps":["trace[864430815] 'process raft request' (duration: 123.27921ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:57.444097Z","caller":"traceutil/trace.go:171","msg":"trace[993638999] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"174.114973ms","start":"2026-05-05T12:58:57.269954Z","end":"2026-05-05T12:58:57.444069Z","steps":["trace[993638999] 'process raft request' (duration: 147.288008ms)","trace[993638999] 'compare' (duration: 26.691402ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:58:57.444063Z","caller":"traceutil/trace.go:171","msg":"trace[213171614] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5170; }","duration":"173.947478ms","start":"2026-05-05T12:58:57.270087Z","end":"2026-05-05T12:58:57.444035Z","steps":["trace[213171614] 'read index received' (duration: 147.126903ms)","trace[213171614] 'applied index is now lower than readState.Index' (duration: 26.819175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:58:57.444264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.190485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-rfn9q\" ","response":"range_response_count:1 size:5851"} {"level":"info","ts":"2026-05-05T12:58:57.444313Z","caller":"traceutil/trace.go:171","msg":"trace[2076794093] range","detail":"{range_begin:/registry/pods/monitoring/vector-rfn9q; range_end:; response_count:1; response_revision:4985; }","duration":"174.276138ms","start":"2026-05-05T12:58:57.270024Z","end":"2026-05-05T12:58:57.444301Z","steps":["trace[2076794093] 'agreement among raft nodes before linearized reading' (duration: 174.102673ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:57.44685Z","caller":"traceutil/trace.go:171","msg":"trace[2132173108] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"176.767036ms","start":"2026-05-05T12:58:57.27007Z","end":"2026-05-05T12:58:57.446837Z","steps":["trace[2132173108] 'process raft request' (duration: 176.54808ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:57.446926Z","caller":"traceutil/trace.go:171","msg":"trace[1639792676] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"176.151799ms","start":"2026-05-05T12:58:57.270752Z","end":"2026-05-05T12:58:57.446904Z","steps":["trace[1639792676] 'process raft request' (duration: 175.973404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:57.447227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.745294ms","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-05T12:58:57.44728Z","caller":"traceutil/trace.go:171","msg":"trace[732171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4988; }","duration":"138.834216ms","start":"2026-05-05T12:58:57.308425Z","end":"2026-05-05T12:58:57.447259Z","steps":["trace[732171] 'agreement among raft nodes before linearized reading' (duration: 138.731033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:57.447339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.387151ms","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-05T12:58:57.447449Z","caller":"traceutil/trace.go:171","msg":"trace[469589699] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"176.612851ms","start":"2026-05-05T12:58:57.270811Z","end":"2026-05-05T12:58:57.447424Z","steps":["trace[469589699] 'process raft request' (duration: 175.965004ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:58:57.447445Z","caller":"traceutil/trace.go:171","msg":"trace[739666104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4988; }","duration":"162.526605ms","start":"2026-05-05T12:58:57.284898Z","end":"2026-05-05T12:58:57.447425Z","steps":["trace[739666104] 'agreement among raft nodes before linearized reading' (duration: 162.246078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:57.447516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.156305ms","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-05T12:58:57.447593Z","caller":"traceutil/trace.go:171","msg":"trace[1512197726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4988; }","duration":"139.279469ms","start":"2026-05-05T12:58:57.308299Z","end":"2026-05-05T12:58:57.447579Z","steps":["trace[1512197726] 'agreement among raft nodes before linearized reading' (duration: 139.121224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:57.447646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.26266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T12:58:57.447682Z","caller":"traceutil/trace.go:171","msg":"trace[674069633] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4988; }","duration":"134.327132ms","start":"2026-05-05T12:58:57.313346Z","end":"2026-05-05T12:58:57.447673Z","steps":["trace[674069633] 'agreement among raft nodes before linearized reading' (duration: 134.263581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:58:57.447958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.728799ms","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-05T12:58:57.448743Z","caller":"traceutil/trace.go:171","msg":"trace[1240128462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4988; }","duration":"159.858262ms","start":"2026-05-05T12:58:57.288188Z","end":"2026-05-05T12:58:57.448047Z","steps":["trace[1240128462] 'agreement among raft nodes before linearized reading' (duration: 159.639616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:59:14.593326Z","caller":"traceutil/trace.go:171","msg":"trace[1733530294] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"104.434933ms","start":"2026-05-05T12:59:14.48887Z","end":"2026-05-05T12:59:14.593305Z","steps":["trace[1733530294] 'process raft request' (duration: 104.34783ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:59:14.593331Z","caller":"traceutil/trace.go:171","msg":"trace[499599735] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"104.633619ms","start":"2026-05-05T12:59:14.488667Z","end":"2026-05-05T12:59:14.5933Z","steps":["trace[499599735] 'process raft request' (duration: 104.351341ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:59:14.593543Z","caller":"traceutil/trace.go:171","msg":"trace[2021990984] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"103.895838ms","start":"2026-05-05T12:59:14.489623Z","end":"2026-05-05T12:59:14.593519Z","steps":["trace[2021990984] 'process raft request' (duration: 103.645911ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:00:30.779781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2832} {"level":"info","ts":"2026-05-05T13:00:30.845936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2832,"took":"64.483249ms","hash":3838231635,"current-db-size-bytes":57925632,"current-db-size":"58 MB","current-db-size-in-use-bytes":33034240,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T13:00:30.846013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3838231635,"revision":2832,"compact-revision":-1} {"level":"info","ts":"2026-05-05T13:04:54.993071Z","caller":"traceutil/trace.go:171","msg":"trace[218687549] linearizableReadLoop","detail":"{readStateIndex:8179; appliedIndex:8178; }","duration":"169.181047ms","start":"2026-05-05T13:04:54.823875Z","end":"2026-05-05T13:04:54.993056Z","steps":["trace[218687549] 'read index received' (duration: 169.048784ms)","trace[218687549] 'applied index is now lower than readState.Index' (duration: 131.563µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:04:54.99314Z","caller":"traceutil/trace.go:171","msg":"trace[1989435217] transaction","detail":"{read_only:false; response_revision:7905; number_of_response:1; }","duration":"223.347815ms","start":"2026-05-05T13:04:54.769759Z","end":"2026-05-05T13:04:54.993107Z","steps":["trace[1989435217] 'process raft request' (duration: 223.16978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:04:54.993229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.348791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T13:04:54.99326Z","caller":"traceutil/trace.go:171","msg":"trace[1725192556] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7905; }","duration":"169.429184ms","start":"2026-05-05T13:04:54.823822Z","end":"2026-05-05T13:04:54.993251Z","steps":["trace[1725192556] 'agreement among raft nodes before linearized reading' (duration: 169.329841ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:04:55.122635Z","caller":"traceutil/trace.go:171","msg":"trace[705913901] transaction","detail":"{read_only:false; response_revision:7906; number_of_response:1; }","duration":"126.302478ms","start":"2026-05-05T13:04:54.996307Z","end":"2026-05-05T13:04:55.122609Z","steps":["trace[705913901] 'process raft request' (duration: 126.153144ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:04:55.123714Z","caller":"traceutil/trace.go:171","msg":"trace[634510294] linearizableReadLoop","detail":"{readStateIndex:8181; appliedIndex:8179; }","duration":"126.457013ms","start":"2026-05-05T13:04:54.997231Z","end":"2026-05-05T13:04:55.123688Z","steps":["trace[634510294] 'read index received' (duration: 125.295431ms)","trace[634510294] 'applied index is now lower than readState.Index' (duration: 1.160462ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:04:55.123823Z","caller":"traceutil/trace.go:171","msg":"trace[1572213627] transaction","detail":"{read_only:false; response_revision:7907; number_of_response:1; }","duration":"126.926146ms","start":"2026-05-05T13:04:54.996876Z","end":"2026-05-05T13:04:55.123802Z","steps":["trace[1572213627] 'process raft request' (duration: 126.670359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:04:55.12395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.692199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19387"} {"level":"info","ts":"2026-05-05T13:04:55.123994Z","caller":"traceutil/trace.go:171","msg":"trace[2122173399] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:7907; }","duration":"126.765861ms","start":"2026-05-05T13:04:54.997217Z","end":"2026-05-05T13:04:55.123982Z","steps":["trace[2122173399] 'agreement among raft nodes before linearized reading' (duration: 126.589836ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:07.261531Z","caller":"traceutil/trace.go:171","msg":"trace[1838147995] transaction","detail":"{read_only:false; response_revision:7966; number_of_response:1; }","duration":"210.883693ms","start":"2026-05-05T13:05:07.05061Z","end":"2026-05-05T13:05:07.261493Z","steps":["trace[1838147995] 'process raft request' (duration: 210.602675ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:30.787474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5929} {"level":"info","ts":"2026-05-05T13:05:30.893048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5929,"took":"104.071253ms","hash":501330048,"current-db-size-bytes":57925632,"current-db-size":"58 MB","current-db-size-in-use-bytes":26017792,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T13:05:30.893125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":501330048,"revision":5929,"compact-revision":2832} {"level":"info","ts":"2026-05-05T13:05:34.122389Z","caller":"traceutil/trace.go:171","msg":"trace[273048895] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"164.590099ms","start":"2026-05-05T13:05:33.957763Z","end":"2026-05-05T13:05:34.122353Z","steps":["trace[273048895] 'process raft request' (duration: 140.690181ms)","trace[273048895] 'compare' (duration: 23.649631ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:05:47.635053Z","caller":"traceutil/trace.go:171","msg":"trace[1554842013] linearizableReadLoop","detail":"{readStateIndex:8572; appliedIndex:8571; }","duration":"118.50419ms","start":"2026-05-05T13:05:47.516523Z","end":"2026-05-05T13:05:47.635027Z","steps":["trace[1554842013] 'read index received' (duration: 90.007736ms)","trace[1554842013] 'applied index is now lower than readState.Index' (duration: 28.495504ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:05:47.635098Z","caller":"traceutil/trace.go:171","msg":"trace[1033221773] transaction","detail":"{read_only:false; response_revision:8282; number_of_response:1; }","duration":"119.894649ms","start":"2026-05-05T13:05:47.515163Z","end":"2026-05-05T13:05:47.635058Z","steps":["trace[1033221773] 'process raft request' (duration: 91.355554ms)","trace[1033221773] 'compare' (duration: 28.384131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:05:47.63568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.138499ms","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-05T13:05:47.635951Z","caller":"traceutil/trace.go:171","msg":"trace[1275699460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8282; }","duration":"119.429476ms","start":"2026-05-05T13:05:47.516497Z","end":"2026-05-05T13:05:47.635926Z","steps":["trace[1275699460] 'agreement among raft nodes before linearized reading' (duration: 118.610204ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:47.641877Z","caller":"traceutil/trace.go:171","msg":"trace[441349791] transaction","detail":"{read_only:false; response_revision:8283; number_of_response:1; }","duration":"125.251086ms","start":"2026-05-05T13:05:47.51661Z","end":"2026-05-05T13:05:47.641861Z","steps":["trace[441349791] 'process raft request' (duration: 125.145834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:05:47.64209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.328936ms","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-05T13:05:47.642178Z","caller":"traceutil/trace.go:171","msg":"trace[501567893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8283; }","duration":"122.45753ms","start":"2026-05-05T13:05:47.519702Z","end":"2026-05-05T13:05:47.642159Z","steps":["trace[501567893] 'agreement among raft nodes before linearized reading' (duration: 122.228423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:05:47.642642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.69854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T13:05:47.642842Z","caller":"traceutil/trace.go:171","msg":"trace[2121583607] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8283; }","duration":"112.917617ms","start":"2026-05-05T13:05:47.529903Z","end":"2026-05-05T13:05:47.642821Z","steps":["trace[2121583607] 'agreement among raft nodes before linearized reading' (duration: 112.646749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:05:47.643009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.719871ms","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-05T13:05:47.643068Z","caller":"traceutil/trace.go:171","msg":"trace[2067058465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8283; }","duration":"112.820204ms","start":"2026-05-05T13:05:47.530235Z","end":"2026-05-05T13:05:47.643055Z","steps":["trace[2067058465] 'agreement among raft nodes before linearized reading' (duration: 112.613088ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:47.946481Z","caller":"traceutil/trace.go:171","msg":"trace[403343301] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"296.638102ms","start":"2026-05-05T13:05:47.649818Z","end":"2026-05-05T13:05:47.946456Z","steps":["trace[403343301] 'process raft request' (duration: 296.55541ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:47.946655Z","caller":"traceutil/trace.go:171","msg":"trace[2002077144] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"296.398235ms","start":"2026-05-05T13:05:47.650239Z","end":"2026-05-05T13:05:47.946637Z","steps":["trace[2002077144] 'process raft request' (duration: 296.181719ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:47.946668Z","caller":"traceutil/trace.go:171","msg":"trace[1696475772] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"306.268016ms","start":"2026-05-05T13:05:47.64035Z","end":"2026-05-05T13:05:47.946618Z","steps":["trace[1696475772] 'process raft request' (duration: 257.262508ms)","trace[1696475772] 'compare' (duration: 48.630098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T13:05:47.946818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T13:05:47.640328Z","time spent":"306.406181ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T13:05:47.946956Z","caller":"traceutil/trace.go:171","msg":"trace[766325596] linearizableReadLoop","detail":"{readStateIndex:8577; appliedIndex:8573; }","duration":"206.023518ms","start":"2026-05-05T13:05:47.740914Z","end":"2026-05-05T13:05:47.946938Z","steps":["trace[766325596] 'read index received' (duration: 156.787244ms)","trace[766325596] 'applied index is now lower than readState.Index' (duration: 49.234634ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T13:05:47.947019Z","caller":"traceutil/trace.go:171","msg":"trace[366093367] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"296.541089ms","start":"2026-05-05T13:05:47.650427Z","end":"2026-05-05T13:05:47.946968Z","steps":["trace[366093367] 'process raft request' (duration: 296.160249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:05:47.947124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.781276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T13:05:47.947163Z","caller":"traceutil/trace.go:171","msg":"trace[153196894] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8287; }","duration":"203.858359ms","start":"2026-05-05T13:05:47.743291Z","end":"2026-05-05T13:05:47.94715Z","steps":["trace[153196894] 'agreement among raft nodes before linearized reading' (duration: 203.773347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T13:05:47.947225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.306947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-05-05T13:05:47.947313Z","caller":"traceutil/trace.go:171","msg":"trace[918612364] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8287; }","duration":"206.390719ms","start":"2026-05-05T13:05:47.740898Z","end":"2026-05-05T13:05:47.947289Z","steps":["trace[918612364] 'agreement among raft nodes before linearized reading' (duration: 206.124992ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:05:48.498609Z","caller":"traceutil/trace.go:171","msg":"trace[491747160] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"119.709433ms","start":"2026-05-05T13:05:48.378873Z","end":"2026-05-05T13:05:48.498582Z","steps":["trace[491747160] 'process raft request' (duration: 119.532758ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:07:03.253785Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245c.wal"} {"level":"info","ts":"2026-05-05T13:08:26.428777Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T13:08:26.433265Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T13:08:26.433476Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T13:10:27.483065Z","caller":"traceutil/trace.go:171","msg":"trace[595577013] transaction","detail":"{read_only:false; response_revision:10807; number_of_response:1; }","duration":"155.290287ms","start":"2026-05-05T13:10:27.327734Z","end":"2026-05-05T13:10:27.483025Z","steps":["trace[595577013] 'process raft request' (duration: 155.000429ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:10:27.483339Z","caller":"traceutil/trace.go:171","msg":"trace[297433231] transaction","detail":"{read_only:false; response_revision:10808; number_of_response:1; }","duration":"155.481282ms","start":"2026-05-05T13:10:27.327831Z","end":"2026-05-05T13:10:27.483312Z","steps":["trace[297433231] 'process raft request' (duration: 155.137093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:10:27.48357Z","caller":"traceutil/trace.go:171","msg":"trace[1574772628] transaction","detail":"{read_only:false; response_revision:10810; number_of_response:1; }","duration":"155.591685ms","start":"2026-05-05T13:10:27.327963Z","end":"2026-05-05T13:10:27.483555Z","steps":["trace[1574772628] 'process raft request' (duration: 155.523063ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:10:27.483602Z","caller":"traceutil/trace.go:171","msg":"trace[1652884020] transaction","detail":"{read_only:false; response_revision:10809; number_of_response:1; }","duration":"155.655086ms","start":"2026-05-05T13:10:27.32791Z","end":"2026-05-05T13:10:27.483565Z","steps":["trace[1652884020] 'process raft request' (duration: 155.300557ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T13:10:30.79466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8146} {"level":"info","ts":"2026-05-05T13:10:30.868439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8146,"took":"71.836433ms","hash":105279595,"current-db-size-bytes":57925632,"current-db-size":"58 MB","current-db-size-in-use-bytes":26128384,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T13:10:30.868502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":105279595,"revision":8146,"compact-revision":5929} {"level":"info","ts":"2026-05-05T13:15:30.799981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10827} {"level":"info","ts":"2026-05-05T13:15:30.881598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10827,"took":"79.45026ms","hash":3475713313,"current-db-size-bytes":57925632,"current-db-size":"58 MB","current-db-size-in-use-bytes":27537408,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T13:15:30.88168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3475713313,"revision":10827,"compact-revision":8146}