{"level":"warn","ts":"2026-04-18T22:15:56.170765Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:15:56.170897Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-18T22:15:56.17099Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:15:56.171008Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-18T22:15:56.171034Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:15:56.171936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-18T22:15:56.172043Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-18T22:15:56.175523Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.215746ms"} {"level":"info","ts":"2026-04-18T22:15:56.187969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-18T22:15:56.188205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:15:56.188281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-18T22:15:56.188342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:15:56.188394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-18T22:15:56.188482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-18T22:15:56.195058Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:15:56.197417Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:15:56.199077Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-18T22:15:56.201937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:15:56.202437Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:15:56.202568Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:15:56.202617Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:15:56.202633Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:15:56.203615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-18T22:15:56.203915Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-18T22:15:56.204685Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:15:56.204785Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-18T22:15:56.204819Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-18T22:15:56.204928Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:15:56.204984Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:15:56.989827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:15:56.989934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:15:56.989981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-18T22:15:56.990002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:15:56.990012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-18T22:15:56.990041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-18T22:15:56.990062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-18T22:15:56.99113Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:15:56.99215Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:15:56.992229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:15:56.992293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:15:56.992586Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:15:56.992644Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:15:56.993829Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:15:56.99396Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:15:56.994013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:15:56.995538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-18T22:15:56.995814Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:16:34.298253Z","caller":"traceutil/trace.go:171","msg":"trace[574639843] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"132.384178ms","start":"2026-04-18T22:16:34.165836Z","end":"2026-04-18T22:16:34.298221Z","steps":["trace[574639843] 'process raft request' (duration: 132.192742ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:34.418871Z","caller":"traceutil/trace.go:171","msg":"trace[439600926] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"208.017363ms","start":"2026-04-18T22:16:34.210828Z","end":"2026-04-18T22:16:34.418845Z","steps":["trace[439600926] 'process raft request' (duration: 203.766382ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:34.743051Z","caller":"traceutil/trace.go:171","msg":"trace[132264620] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"259.524934ms","start":"2026-04-18T22:16:34.483499Z","end":"2026-04-18T22:16:34.743024Z","steps":["trace[132264620] 'process raft request' (duration: 259.388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:35.204523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.189529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:16:35.204632Z","caller":"traceutil/trace.go:171","msg":"trace[2123597900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"195.413445ms","start":"2026-04-18T22:16:35.009196Z","end":"2026-04-18T22:16:35.20461Z","steps":["trace[2123597900] 'range keys from in-memory index tree' (duration: 194.967123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:51.165125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.344857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:51.165226Z","caller":"traceutil/trace.go:171","msg":"trace[606039504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"186.510842ms","start":"2026-04-18T22:16:50.978697Z","end":"2026-04-18T22:16:51.165208Z","steps":["trace[606039504] 'range keys from in-memory index tree' (duration: 186.185983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.951066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.170159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:17:33.951221Z","caller":"traceutil/trace.go:171","msg":"trace[1733978380] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"346.355943ms","start":"2026-04-18T22:17:33.604842Z","end":"2026-04-18T22:17:33.951198Z","steps":["trace[1733978380] 'process raft request' (duration: 141.355473ms)","trace[1733978380] 'compare' (duration: 203.992225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:33.951303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.604824Z","time spent":"346.438665ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:33.968483Z","caller":"traceutil/trace.go:171","msg":"trace[1098511618] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"195.078492ms","start":"2026-04-18T22:17:33.773381Z","end":"2026-04-18T22:17:33.968459Z","steps":["trace[1098511618] 'read index received' (duration: 194.98032ms)","trace[1098511618] 'applied index is now lower than readState.Index' (duration: 95.012µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:33.968548Z","caller":"traceutil/trace.go:171","msg":"trace[726774287] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"360.876199ms","start":"2026-04-18T22:17:33.607633Z","end":"2026-04-18T22:17:33.96851Z","steps":["trace[726774287] 'process raft request' (duration: 360.709565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.968682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.301288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:17:33.968782Z","caller":"traceutil/trace.go:171","msg":"trace[355077335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"195.441662ms","start":"2026-04-18T22:17:33.773321Z","end":"2026-04-18T22:17:33.968763Z","steps":["trace[355077335] 'agreement among raft nodes before linearized reading' (duration: 195.228967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.968956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.607609Z","time spent":"361.019941ms","remote":"127.0.0.1:50972","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-04-18T22:17:34.012961Z","caller":"traceutil/trace.go:171","msg":"trace[815800137] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"147.164083ms","start":"2026-04-18T22:17:33.865778Z","end":"2026-04-18T22:17:34.012943Z","steps":["trace[815800137] 'process raft request' (duration: 147.064081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:05.344548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.522013ms","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-04-18T22:18:05.344672Z","caller":"traceutil/trace.go:171","msg":"trace[1494293269] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1840; }","duration":"173.690338ms","start":"2026-04-18T22:18:05.170954Z","end":"2026-04-18T22:18:05.344645Z","steps":["trace[1494293269] 'range keys from in-memory index tree' (duration: 173.322569ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:05.786187Z","caller":"traceutil/trace.go:171","msg":"trace[1360904055] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"164.715462ms","start":"2026-04-18T22:18:05.621449Z","end":"2026-04-18T22:18:05.786165Z","steps":["trace[1360904055] 'process raft request' (duration: 164.499287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:14.262778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.197728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-18T22:18:14.262893Z","caller":"traceutil/trace.go:171","msg":"trace[2046204739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1925; }","duration":"115.354102ms","start":"2026-04-18T22:18:14.147517Z","end":"2026-04-18T22:18:14.262871Z","steps":["trace[2046204739] 'range keys from in-memory index tree' (duration: 114.999333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:14.263115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.266799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:18:14.263255Z","caller":"traceutil/trace.go:171","msg":"trace[1641556663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1925; }","duration":"110.448474ms","start":"2026-04-18T22:18:14.152775Z","end":"2026-04-18T22:18:14.263224Z","steps":["trace[1641556663] 'range keys from in-memory index tree' (duration: 110.038974ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:50.413412Z","caller":"traceutil/trace.go:171","msg":"trace[2044217900] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"112.853528ms","start":"2026-04-18T22:18:50.300525Z","end":"2026-04-18T22:18:50.413379Z","steps":["trace[2044217900] 'process raft request' (duration: 112.654594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:50.783656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.757668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:18:50.78379Z","caller":"traceutil/trace.go:171","msg":"trace[611302984] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"269.9647ms","start":"2026-04-18T22:18:50.513803Z","end":"2026-04-18T22:18:50.783768Z","steps":["trace[611302984] 'process raft request' (duration: 132.933396ms)","trace[611302984] 'compare' (duration: 136.618525ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:50.814002Z","caller":"traceutil/trace.go:171","msg":"trace[1221322810] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2217; }","duration":"270.063712ms","start":"2026-04-18T22:18:50.543913Z","end":"2026-04-18T22:18:50.813977Z","steps":["trace[1221322810] 'read index received' (duration: 102.848189ms)","trace[1221322810] 'applied index is now lower than readState.Index' (duration: 167.214493ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:50.814116Z","caller":"traceutil/trace.go:171","msg":"trace[1092521092] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"299.232387ms","start":"2026-04-18T22:18:50.514838Z","end":"2026-04-18T22:18:50.81407Z","steps":["trace[1092521092] 'process raft request' (duration: 298.976381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:50.814254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.320988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:18:50.814358Z","caller":"traceutil/trace.go:171","msg":"trace[1205726793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2142; }","duration":"270.451791ms","start":"2026-04-18T22:18:50.543877Z","end":"2026-04-18T22:18:50.814329Z","steps":["trace[1205726793] 'agreement among raft nodes before linearized reading' (duration: 270.232276ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:50.915105Z","caller":"traceutil/trace.go:171","msg":"trace[633766753] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"174.26179ms","start":"2026-04-18T22:18:50.740797Z","end":"2026-04-18T22:18:50.915058Z","steps":["trace[633766753] 'process raft request' (duration: 166.836374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:50.915333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.59914ms","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-04-18T22:18:50.915396Z","caller":"traceutil/trace.go:171","msg":"trace[1394540014] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2145; }","duration":"240.714553ms","start":"2026-04-18T22:18:50.674668Z","end":"2026-04-18T22:18:50.915382Z","steps":["trace[1394540014] 'agreement among raft nodes before linearized reading' (duration: 240.530508ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:50.915131Z","caller":"traceutil/trace.go:171","msg":"trace[1906902430] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"101.023246ms","start":"2026-04-18T22:18:50.814072Z","end":"2026-04-18T22:18:50.915095Z","steps":["trace[1906902430] 'read index received' (duration: 93.690522ms)","trace[1906902430] 'applied index is now lower than readState.Index' (duration: 7.329324ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:29.370601Z","caller":"traceutil/trace.go:171","msg":"trace[1808858188] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"114.276845ms","start":"2026-04-18T22:20:29.256296Z","end":"2026-04-18T22:20:29.370573Z","steps":["trace[1808858188] 'process raft request' (duration: 114.0786ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:29.466792Z","caller":"traceutil/trace.go:171","msg":"trace[779458284] linearizableReadLoop","detail":"{readStateIndex:2972; appliedIndex:2970; }","duration":"192.765282ms","start":"2026-04-18T22:20:29.274005Z","end":"2026-04-18T22:20:29.466771Z","steps":["trace[779458284] 'read index received' (duration: 96.363536ms)","trace[779458284] 'applied index is now lower than readState.Index' (duration: 96.400716ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:29.466988Z","caller":"traceutil/trace.go:171","msg":"trace[725250046] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"210.602639ms","start":"2026-04-18T22:20:29.256333Z","end":"2026-04-18T22:20:29.466935Z","steps":["trace[725250046] 'process raft request' (duration: 209.867652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:29.467188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.453961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:20:29.467238Z","caller":"traceutil/trace.go:171","msg":"trace[2025374463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2868; }","duration":"191.547413ms","start":"2026-04-18T22:20:29.275678Z","end":"2026-04-18T22:20:29.467226Z","steps":["trace[2025374463] 'agreement among raft nodes before linearized reading' (duration: 191.396489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:29.467471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.456339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:20:29.467542Z","caller":"traceutil/trace.go:171","msg":"trace[176440320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2868; }","duration":"193.564882ms","start":"2026-04-18T22:20:29.273958Z","end":"2026-04-18T22:20:29.467523Z","steps":["trace[176440320] 'agreement among raft nodes before linearized reading' (duration: 192.957998ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:29.650818Z","caller":"traceutil/trace.go:171","msg":"trace[452031463] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"180.139001ms","start":"2026-04-18T22:20:29.470651Z","end":"2026-04-18T22:20:29.65079Z","steps":["trace[452031463] 'process raft request' (duration: 179.967027ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:29.665826Z","caller":"traceutil/trace.go:171","msg":"trace[446464704] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"194.185566ms","start":"2026-04-18T22:20:29.471611Z","end":"2026-04-18T22:20:29.665797Z","steps":["trace[446464704] 'process raft request' (duration: 194.019952ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:29.665991Z","caller":"traceutil/trace.go:171","msg":"trace[970425061] transaction","detail":"{read_only:false; response_revision:2871; number_of_response:1; }","duration":"193.11087ms","start":"2026-04-18T22:20:29.472856Z","end":"2026-04-18T22:20:29.665967Z","steps":["trace[970425061] 'process raft request' (duration: 192.896755ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:23:00.044285Z","caller":"traceutil/trace.go:171","msg":"trace[2092797012] transaction","detail":"{read_only:false; response_revision:3862; number_of_response:1; }","duration":"122.330182ms","start":"2026-04-18T22:22:59.921934Z","end":"2026-04-18T22:23:00.044264Z","steps":["trace[2092797012] 'process raft request' (duration: 122.178768ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:15.649434Z","caller":"traceutil/trace.go:171","msg":"trace[1645841495] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"143.858609ms","start":"2026-04-18T22:24:15.505556Z","end":"2026-04-18T22:24:15.649414Z","steps":["trace[1645841495] 'process raft request' (duration: 143.821358ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:15.649507Z","caller":"traceutil/trace.go:171","msg":"trace[1068008269] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"150.244348ms","start":"2026-04-18T22:24:15.499224Z","end":"2026-04-18T22:24:15.649468Z","steps":["trace[1068008269] 'process raft request' (duration: 150.028053ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:24.364063Z","caller":"traceutil/trace.go:171","msg":"trace[633058795] linearizableReadLoop","detail":"{readStateIndex:5206; appliedIndex:5205; }","duration":"131.51365ms","start":"2026-04-18T22:24:24.232528Z","end":"2026-04-18T22:24:24.364041Z","steps":["trace[633058795] 'read index received' (duration: 131.428968ms)","trace[633058795] 'applied index is now lower than readState.Index' (duration: 83.802µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:24:24.364154Z","caller":"traceutil/trace.go:171","msg":"trace[1477700564] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"173.301836ms","start":"2026-04-18T22:24:24.190828Z","end":"2026-04-18T22:24:24.36413Z","steps":["trace[1477700564] 'process raft request' (duration: 172.849906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:24.364447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.901949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:24:24.36454Z","caller":"traceutil/trace.go:171","msg":"trace[491701685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5022; }","duration":"132.052682ms","start":"2026-04-18T22:24:24.232465Z","end":"2026-04-18T22:24:24.364518Z","steps":["trace[491701685] 'agreement among raft nodes before linearized reading' (duration: 131.710254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:24.377794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.672471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-18T22:24:24.377875Z","caller":"traceutil/trace.go:171","msg":"trace[231626507] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5023; }","duration":"139.803714ms","start":"2026-04-18T22:24:24.238056Z","end":"2026-04-18T22:24:24.37786Z","steps":["trace[231626507] 'agreement among raft nodes before linearized reading' (duration: 139.614589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:24.377814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.803874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:24:24.377957Z","caller":"traceutil/trace.go:171","msg":"trace[283526938] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5023; }","duration":"115.959237ms","start":"2026-04-18T22:24:24.261974Z","end":"2026-04-18T22:24:24.377933Z","steps":["trace[283526938] 'agreement among raft nodes before linearized reading' (duration: 115.737602ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:34.939072Z","caller":"traceutil/trace.go:171","msg":"trace[1913871250] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"103.1808ms","start":"2026-04-18T22:24:34.835788Z","end":"2026-04-18T22:24:34.938969Z","steps":["trace[1913871250] 'process raft request' (duration: 102.840401ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:42.921499Z","caller":"traceutil/trace.go:171","msg":"trace[1787472929] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"160.669091ms","start":"2026-04-18T22:24:42.760785Z","end":"2026-04-18T22:24:42.921454Z","steps":["trace[1787472929] 'process raft request' (duration: 79.502326ms)","trace[1787472929] 'compare' (duration: 80.94919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:24:43.225098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.101874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:24:43.225193Z","caller":"traceutil/trace.go:171","msg":"trace[1142526098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5419; }","duration":"198.225407ms","start":"2026-04-18T22:24:43.026939Z","end":"2026-04-18T22:24:43.225165Z","steps":["trace[1142526098] 'range keys from in-memory index tree' (duration: 197.812788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:43.22521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.166083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:24:43.225277Z","caller":"traceutil/trace.go:171","msg":"trace[856113619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5419; }","duration":"203.269316ms","start":"2026-04-18T22:24:43.021992Z","end":"2026-04-18T22:24:43.225261Z","steps":["trace[856113619] 'range keys from in-memory index tree' (duration: 202.734503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:43.225472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.104492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:24:43.225553Z","caller":"traceutil/trace.go:171","msg":"trace[1817611976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5419; }","duration":"200.243755ms","start":"2026-04-18T22:24:43.025293Z","end":"2026-04-18T22:24:43.225537Z","steps":["trace[1817611976] 'range keys from in-memory index tree' (duration: 199.354684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:43.225604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.448118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:24:43.225798Z","caller":"traceutil/trace.go:171","msg":"trace[2032387454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5419; }","duration":"196.631281ms","start":"2026-04-18T22:24:43.02908Z","end":"2026-04-18T22:24:43.225712Z","steps":["trace[2032387454] 'range keys from in-memory index tree' (duration: 195.656527ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:25:57.016929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3017} {"level":"info","ts":"2026-04-18T22:25:57.094593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3017,"took":"76.220089ms","hash":395150272,"current-db-size-bytes":58507264,"current-db-size":"58 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-18T22:25:57.094675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395150272,"revision":3017,"compact-revision":-1} {"level":"info","ts":"2026-04-18T22:30:28.766119Z","caller":"traceutil/trace.go:171","msg":"trace[322284408] transaction","detail":"{read_only:false; response_revision:8146; number_of_response:1; }","duration":"258.747062ms","start":"2026-04-18T22:30:28.507357Z","end":"2026-04-18T22:30:28.766104Z","steps":["trace[322284408] 'process raft request' (duration: 258.602908ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:30:28.766267Z","caller":"traceutil/trace.go:171","msg":"trace[47543049] linearizableReadLoop","detail":"{readStateIndex:8424; appliedIndex:8423; }","duration":"138.2748ms","start":"2026-04-18T22:30:28.627798Z","end":"2026-04-18T22:30:28.766073Z","steps":["trace[47543049] 'read index received' (duration: 138.061655ms)","trace[47543049] 'applied index is now lower than readState.Index' (duration: 212.525µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:30:28.766665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.845694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-18T22:30:28.766765Z","caller":"traceutil/trace.go:171","msg":"trace[2134942286] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8146; }","duration":"138.983058ms","start":"2026-04-18T22:30:28.627764Z","end":"2026-04-18T22:30:28.766747Z","steps":["trace[2134942286] 'agreement among raft nodes before linearized reading' (duration: 138.552527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:30:28.95059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.518369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-18T22:30:28.950682Z","caller":"traceutil/trace.go:171","msg":"trace[24777838] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8146; }","duration":"107.659012ms","start":"2026-04-18T22:30:28.843002Z","end":"2026-04-18T22:30:28.950661Z","steps":["trace[24777838] 'range keys from in-memory index tree' (duration: 107.344165ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:30:57.023624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6125} {"level":"info","ts":"2026-04-18T22:30:57.11858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6125,"took":"93.130358ms","hash":2140787806,"current-db-size-bytes":58507264,"current-db-size":"58 MB","current-db-size-in-use-bytes":23281664,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-18T22:30:57.118664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140787806,"revision":6125,"compact-revision":3017} {"level":"info","ts":"2026-04-18T22:31:08.504216Z","caller":"traceutil/trace.go:171","msg":"trace[191723531] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"135.743837ms","start":"2026-04-18T22:31:08.368447Z","end":"2026-04-18T22:31:08.504191Z","steps":["trace[191723531] 'process raft request' (duration: 135.232853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:20.547786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.388198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:31:20.548069Z","caller":"traceutil/trace.go:171","msg":"trace[715848986] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8519; }","duration":"150.939726ms","start":"2026-04-18T22:31:20.397109Z","end":"2026-04-18T22:31:20.548049Z","steps":["trace[715848986] 'process raft request' (duration: 150.818733ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:20.548097Z","caller":"traceutil/trace.go:171","msg":"trace[1293163921] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"152.022202ms","start":"2026-04-18T22:31:20.396028Z","end":"2026-04-18T22:31:20.54805Z","steps":["trace[1293163921] 'process raft request' (duration: 16.254785ms)","trace[1293163921] 'compare' (duration: 135.095721ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:31:20.686769Z","caller":"traceutil/trace.go:171","msg":"trace[31404091] linearizableReadLoop","detail":"{readStateIndex:8815; appliedIndex:8814; }","duration":"130.554418ms","start":"2026-04-18T22:31:20.556194Z","end":"2026-04-18T22:31:20.686748Z","steps":["trace[31404091] 'read index received' (duration: 117.617914ms)","trace[31404091] 'applied index is now lower than readState.Index' (duration: 12.935664ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:31:20.686845Z","caller":"traceutil/trace.go:171","msg":"trace[1489735021] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"131.061421ms","start":"2026-04-18T22:31:20.555753Z","end":"2026-04-18T22:31:20.686814Z","steps":["trace[1489735021] 'process raft request' (duration: 118.140587ms)","trace[1489735021] 'compare' (duration: 12.75417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:31:20.688954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.740482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7nz7c\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-04-18T22:31:20.689179Z","caller":"traceutil/trace.go:171","msg":"trace[876487596] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7nz7c; range_end:; response_count:1; response_revision:8520; }","duration":"132.876356ms","start":"2026-04-18T22:31:20.556175Z","end":"2026-04-18T22:31:20.689052Z","steps":["trace[876487596] 'agreement among raft nodes before linearized reading' (duration: 132.62273ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:21.485373Z","caller":"traceutil/trace.go:171","msg":"trace[551727021] linearizableReadLoop","detail":"{readStateIndex:8819; appliedIndex:8818; }","duration":"172.662704ms","start":"2026-04-18T22:31:21.312689Z","end":"2026-04-18T22:31:21.485351Z","steps":["trace[551727021] 'read index received' (duration: 172.550502ms)","trace[551727021] 'applied index is now lower than readState.Index' (duration: 111.212µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:31:21.4855Z","caller":"traceutil/trace.go:171","msg":"trace[280221652] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"180.055025ms","start":"2026-04-18T22:31:21.305401Z","end":"2026-04-18T22:31:21.485456Z","steps":["trace[280221652] 'process raft request' (duration: 179.805749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:21.4856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.89104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-18T22:31:21.485657Z","caller":"traceutil/trace.go:171","msg":"trace[366032911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8524; }","duration":"172.978452ms","start":"2026-04-18T22:31:21.312668Z","end":"2026-04-18T22:31:21.485647Z","steps":["trace[366032911] 'agreement among raft nodes before linearized reading' (duration: 172.772837ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:21.615992Z","caller":"traceutil/trace.go:171","msg":"trace[1198321896] linearizableReadLoop","detail":"{readStateIndex:8820; appliedIndex:8819; }","duration":"130.533019ms","start":"2026-04-18T22:31:21.485434Z","end":"2026-04-18T22:31:21.615967Z","steps":["trace[1198321896] 'read index received' (duration: 125.817654ms)","trace[1198321896] 'applied index is now lower than readState.Index' (duration: 4.714265ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:31:21.616093Z","caller":"traceutil/trace.go:171","msg":"trace[116011774] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"235.523005ms","start":"2026-04-18T22:31:21.380534Z","end":"2026-04-18T22:31:21.616057Z","steps":["trace[116011774] 'process raft request' (duration: 230.658757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:21.61624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.60475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"warn","ts":"2026-04-18T22:31:21.616242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.537879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T22:31:21.616283Z","caller":"traceutil/trace.go:171","msg":"trace[305470708] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8525; }","duration":"300.671041ms","start":"2026-04-18T22:31:21.3156Z","end":"2026-04-18T22:31:21.616272Z","steps":["trace[305470708] 'agreement among raft nodes before linearized reading' (duration: 300.465077ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:21.616291Z","caller":"traceutil/trace.go:171","msg":"trace[300060706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8525; }","duration":"268.612222ms","start":"2026-04-18T22:31:21.347666Z","end":"2026-04-18T22:31:21.616278Z","steps":["trace[300060706] 'agreement among raft nodes before linearized reading' (duration: 268.438957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:31:21.616316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:31:21.315595Z","time spent":"300.712922ms","remote":"127.0.0.1:51416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5755,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-04-18T22:31:21.61631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.985117ms","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-04-18T22:31:21.616402Z","caller":"traceutil/trace.go:171","msg":"trace[1703560595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8525; }","duration":"236.12865ms","start":"2026-04-18T22:31:21.380254Z","end":"2026-04-18T22:31:21.616383Z","steps":["trace[1703560595] 'agreement among raft nodes before linearized reading' (duration: 235.841153ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:31:21.635544Z","caller":"traceutil/trace.go:171","msg":"trace[107227970] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"146.501507ms","start":"2026-04-18T22:31:21.489018Z","end":"2026-04-18T22:31:21.63552Z","steps":["trace[107227970] 'process raft request' (duration: 146.286122ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:32:19.938661Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d5.wal"} {"level":"info","ts":"2026-04-18T22:33:31.366093Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-18T22:33:31.37539Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-18T22:33:31.375489Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-18T22:35:57.029806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8303} {"level":"info","ts":"2026-04-18T22:35:57.107655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8303,"took":"75.789583ms","hash":576175627,"current-db-size-bytes":58507264,"current-db-size":"58 MB","current-db-size-in-use-bytes":24440832,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-18T22:35:57.107719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":576175627,"revision":8303,"compact-revision":6125} {"level":"info","ts":"2026-04-18T22:40:57.037454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10703} {"level":"info","ts":"2026-04-18T22:40:57.110269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10703,"took":"70.770147ms","hash":4255043796,"current-db-size-bytes":58507264,"current-db-size":"58 MB","current-db-size-in-use-bytes":20729856,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-18T22:40:57.110322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4255043796,"revision":10703,"compact-revision":8303}