{"level":"warn","ts":"2026-04-20T11:55:49.974675Z","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-20T11:55:49.974775Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.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.19.213.132:2380","--initial-cluster=instance=https://199.19.213.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.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-04-20T11:55:49.974864Z","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-20T11:55:49.974882Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-20T11:55:49.974903Z","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-20T11:55:49.975675Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"]} {"level":"info","ts":"2026-04-20T11:55:49.975745Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.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-04-20T11:55:49.981823Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.849104ms"} {"level":"info","ts":"2026-04-20T11:55:49.988832Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"19a81b20c4b111c3","cluster-id":"42a0cab9df6b0eb"} {"level":"info","ts":"2026-04-20T11:55:49.988946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:55:49.988981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 0"} {"level":"info","ts":"2026-04-20T11:55:49.989007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 19a81b20c4b111c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:55:49.989026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 1"} {"level":"info","ts":"2026-04-20T11:55:49.989083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"warn","ts":"2026-04-20T11:55:50.001329Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:55:50.003219Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:55:50.004592Z","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-20T11:55:50.007462Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"19a81b20c4b111c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:55:50.007613Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"19a81b20c4b111c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:55:50.007803Z","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-20T11:55:50.007914Z","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-20T11:55:50.007983Z","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-20T11:55:50.010021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"info","ts":"2026-04-20T11:55:50.010177Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","added-peer-id":"19a81b20c4b111c3","added-peer-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-20T11:55:50.011969Z","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-20T11:55:50.012106Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-20T11:55:50.012148Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-20T11:55:50.012363Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"19a81b20c4b111c3","initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:55:50.01242Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:55:50.990033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:55:50.990181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:55:50.990253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgPreVoteResp from 19a81b20c4b111c3 at term 1"} {"level":"info","ts":"2026-04-20T11:55:50.990336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:55:50.990396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgVoteResp from 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-20T11:55:50.99048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became leader at term 2"} {"level":"info","ts":"2026-04-20T11:55:50.990553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 19a81b20c4b111c3 elected leader 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-20T11:55:50.99148Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"19a81b20c4b111c3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.132:2379]}","request-path":"/0/members/19a81b20c4b111c3/attributes","cluster-id":"42a0cab9df6b0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:55:50.991713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:55:50.991797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:55:50.991911Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:55:50.99199Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:55:50.992441Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:50.99406Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:50.99469Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:50.994762Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:50.995657Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.132:2379"} {"level":"info","ts":"2026-04-20T11:55:50.995958Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T11:56:16.535775Z","caller":"traceutil/trace.go:171","msg":"trace[1559925590] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"174.482232ms","start":"2026-04-20T11:56:16.361258Z","end":"2026-04-20T11:56:16.53574Z","steps":["trace[1559925590] 'process raft request' (duration: 174.290886ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:27.569947Z","caller":"traceutil/trace.go:171","msg":"trace[176463172] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"130.090459ms","start":"2026-04-20T11:56:27.439827Z","end":"2026-04-20T11:56:27.569917Z","steps":["trace[176463172] 'process raft request' (duration: 124.311426ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:28.074565Z","caller":"traceutil/trace.go:171","msg":"trace[1664397976] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"329.223276ms","start":"2026-04-20T11:56:27.745312Z","end":"2026-04-20T11:56:28.074536Z","steps":["trace[1664397976] 'read index received' (duration: 244.211909ms)","trace[1664397976] 'applied index is now lower than readState.Index' (duration: 85.002017ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:28.074667Z","caller":"traceutil/trace.go:171","msg":"trace[323992641] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"447.661058ms","start":"2026-04-20T11:56:27.626963Z","end":"2026-04-20T11:56:28.074624Z","steps":["trace[323992641] 'process raft request' (duration: 362.65778ms)","trace[323992641] 'compare' (duration: 84.745081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:28.074836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.31113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T11:56:28.07484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.517304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bm8h5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T11:56:28.074916Z","caller":"traceutil/trace.go:171","msg":"trace[1157675442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"217.457324ms","start":"2026-04-20T11:56:27.857447Z","end":"2026-04-20T11:56:28.074905Z","steps":["trace[1157675442] 'agreement among raft nodes before linearized reading' (duration: 217.249378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:28.074949Z","caller":"traceutil/trace.go:171","msg":"trace[833899830] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bm8h5; range_end:; response_count:1; response_revision:620; }","duration":"329.669758ms","start":"2026-04-20T11:56:27.745258Z","end":"2026-04-20T11:56:28.074928Z","steps":["trace[833899830] 'agreement among raft nodes before linearized reading' (duration: 329.401531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:28.074999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:27.745235Z","time spent":"329.755211ms","remote":"127.0.0.1:39898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-bm8h5\" "} {"level":"warn","ts":"2026-04-20T11:56:28.075269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:27.626935Z","time spent":"447.827073ms","remote":"127.0.0.1:40198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:56:44.321327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.895617ms","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-04-20T11:56:44.321382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.986777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T11:56:44.321426Z","caller":"traceutil/trace.go:171","msg":"trace[726783429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"127.046311ms","start":"2026-04-20T11:56:44.19436Z","end":"2026-04-20T11:56:44.321406Z","steps":["trace[726783429] 'range keys from in-memory index tree' (duration: 126.701391ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:44.321457Z","caller":"traceutil/trace.go:171","msg":"trace[2098437024] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:785; }","duration":"206.13418ms","start":"2026-04-20T11:56:44.115306Z","end":"2026-04-20T11:56:44.32144Z","steps":["trace[2098437024] 'range keys from in-memory index tree' (duration: 205.798191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:30.992381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.153104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:57:30.992494Z","caller":"traceutil/trace.go:171","msg":"trace[399131501] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1245; }","duration":"193.34762ms","start":"2026-04-20T11:57:30.799126Z","end":"2026-04-20T11:57:30.992473Z","steps":["trace[399131501] 'count revisions from in-memory index tree' (duration: 192.984899ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:54.956412Z","caller":"traceutil/trace.go:171","msg":"trace[1934432041] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"113.205533ms","start":"2026-04-20T11:58:54.843166Z","end":"2026-04-20T11:58:54.956371Z","steps":["trace[1934432041] 'process raft request' (duration: 80.931658ms)","trace[1934432041] 'compare' (duration: 32.175643ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:58:55.457025Z","caller":"traceutil/trace.go:171","msg":"trace[2107044460] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"132.623829ms","start":"2026-04-20T11:58:55.324377Z","end":"2026-04-20T11:58:55.457001Z","steps":["trace[2107044460] 'process raft request' (duration: 132.467304ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:35.623699Z","caller":"traceutil/trace.go:171","msg":"trace[839634500] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"211.14046ms","start":"2026-04-20T11:59:35.412536Z","end":"2026-04-20T11:59:35.623676Z","steps":["trace[839634500] 'process raft request' (duration: 210.936745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:07.992249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.910773ms","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-20T12:00:07.992355Z","caller":"traceutil/trace.go:171","msg":"trace[448579243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2762; }","duration":"173.088538ms","start":"2026-04-20T12:00:07.819248Z","end":"2026-04-20T12:00:07.992336Z","steps":["trace[448579243] 'range keys from in-memory index tree' (duration: 172.728158ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:07.992525Z","caller":"traceutil/trace.go:171","msg":"trace[1206653653] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"146.334682ms","start":"2026-04-20T12:00:07.846148Z","end":"2026-04-20T12:00:07.992483Z","steps":["trace[1206653653] 'process raft request' (duration: 56.294973ms)","trace[1206653653] 'compare' (duration: 89.867285ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:30.176982Z","caller":"traceutil/trace.go:171","msg":"trace[172991071] linearizableReadLoop","detail":"{readStateIndex:3626; appliedIndex:3625; }","duration":"103.359295ms","start":"2026-04-20T12:01:30.073599Z","end":"2026-04-20T12:01:30.176958Z","steps":["trace[172991071] 'read index received' (duration: 103.17486ms)","trace[172991071] 'applied index is now lower than readState.Index' (duration: 183.525µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:30.177039Z","caller":"traceutil/trace.go:171","msg":"trace[2048520966] transaction","detail":"{read_only:false; response_revision:3484; number_of_response:1; }","duration":"173.273363ms","start":"2026-04-20T12:01:30.003731Z","end":"2026-04-20T12:01:30.177005Z","steps":["trace[2048520966] 'process raft request' (duration: 173.071077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:30.177451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.833589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-20T12:01:30.177526Z","caller":"traceutil/trace.go:171","msg":"trace[1988089651] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3484; }","duration":"103.958032ms","start":"2026-04-20T12:01:30.073547Z","end":"2026-04-20T12:01:30.177505Z","steps":["trace[1988089651] 'agreement among raft nodes before linearized reading' (duration: 103.713375ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:39.560824Z","caller":"traceutil/trace.go:171","msg":"trace[1996971245] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"118.957705ms","start":"2026-04-20T12:02:39.441817Z","end":"2026-04-20T12:02:39.560775Z","steps":["trace[1996971245] 'process raft request' (duration: 118.708028ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:39.742723Z","caller":"traceutil/trace.go:171","msg":"trace[130388991] linearizableReadLoop","detail":"{readStateIndex:4856; appliedIndex:4855; }","duration":"182.01723ms","start":"2026-04-20T12:02:39.560677Z","end":"2026-04-20T12:02:39.742694Z","steps":["trace[130388991] 'read index received' (duration: 129.830806ms)","trace[130388991] 'applied index is now lower than readState.Index' (duration: 52.183764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:39.742971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.336244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5740bc3-3297-42f1-a2ea-d7870f4f1b91\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T12:02:39.743032Z","caller":"traceutil/trace.go:171","msg":"trace[1075506472] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5740bc3-3297-42f1-a2ea-d7870f4f1b91; range_end:; response_count:1; response_revision:4662; }","duration":"171.434247ms","start":"2026-04-20T12:02:39.571585Z","end":"2026-04-20T12:02:39.743019Z","steps":["trace[1075506472] 'agreement among raft nodes before linearized reading' (duration: 171.306054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:39.743036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.292981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:02:39.743052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.925588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-bhzrt\" ","response":"range_response_count:1 size:5851"} {"level":"warn","ts":"2026-04-20T12:02:39.743095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.347875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5740bc3-3297-42f1-a2ea-d7870f4f1b91\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T12:02:39.743106Z","caller":"traceutil/trace.go:171","msg":"trace[459066799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4662; }","duration":"173.420854ms","start":"2026-04-20T12:02:39.569668Z","end":"2026-04-20T12:02:39.743089Z","steps":["trace[459066799] 'agreement among raft nodes before linearized reading' (duration: 173.28424ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:39.74313Z","caller":"traceutil/trace.go:171","msg":"trace[352408798] range","detail":"{range_begin:/registry/pods/monitoring/vector-bhzrt; range_end:; response_count:1; response_revision:4662; }","duration":"181.050672ms","start":"2026-04-20T12:02:39.562064Z","end":"2026-04-20T12:02:39.743114Z","steps":["trace[352408798] 'agreement among raft nodes before linearized reading' (duration: 180.829756ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:39.743143Z","caller":"traceutil/trace.go:171","msg":"trace[1254794673] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5740bc3-3297-42f1-a2ea-d7870f4f1b91; range_end:; response_count:1; response_revision:4662; }","duration":"251.441498ms","start":"2026-04-20T12:02:39.491689Z","end":"2026-04-20T12:02:39.743131Z","steps":["trace[1254794673] 'agreement among raft nodes before linearized reading' (duration: 251.226782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:39.743152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.713667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:781820"} {"level":"info","ts":"2026-04-20T12:02:39.743189Z","caller":"traceutil/trace.go:171","msg":"trace[515262234] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4662; }","duration":"221.776949ms","start":"2026-04-20T12:02:39.5214Z","end":"2026-04-20T12:02:39.743177Z","steps":["trace[515262234] 'agreement among raft nodes before linearized reading' (duration: 221.360497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:52.730732Z","caller":"traceutil/trace.go:171","msg":"trace[1486170187] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"111.272225ms","start":"2026-04-20T12:02:52.61942Z","end":"2026-04-20T12:02:52.730692Z","steps":["trace[1486170187] 'process raft request' (duration: 101.597778ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:52.866746Z","caller":"traceutil/trace.go:171","msg":"trace[584530409] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"130.130696ms","start":"2026-04-20T12:02:52.736583Z","end":"2026-04-20T12:02:52.866714Z","steps":["trace[584530409] 'process raft request' (duration: 100.557879ms)","trace[584530409] 'compare' (duration: 29.309739ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:52.86687Z","caller":"traceutil/trace.go:171","msg":"trace[1303989503] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"128.258091ms","start":"2026-04-20T12:02:52.738587Z","end":"2026-04-20T12:02:52.866845Z","steps":["trace[1303989503] 'process raft request' (duration: 128.118578ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:52.8669Z","caller":"traceutil/trace.go:171","msg":"trace[128864226] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"129.990171ms","start":"2026-04-20T12:02:52.736875Z","end":"2026-04-20T12:02:52.866865Z","steps":["trace[128864226] 'process raft request' (duration: 129.742824ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:52.867064Z","caller":"traceutil/trace.go:171","msg":"trace[40043576] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"127.667085ms","start":"2026-04-20T12:02:52.739371Z","end":"2026-04-20T12:02:52.867038Z","steps":["trace[40043576] 'process raft request' (duration: 127.417188ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:52.867087Z","caller":"traceutil/trace.go:171","msg":"trace[1881177957] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"121.863869ms","start":"2026-04-20T12:02:52.745213Z","end":"2026-04-20T12:02:52.867077Z","steps":["trace[1881177957] 'process raft request' (duration: 121.814838ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:52.867174Z","caller":"traceutil/trace.go:171","msg":"trace[1184994925] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"127.446678ms","start":"2026-04-20T12:02:52.73967Z","end":"2026-04-20T12:02:52.867117Z","steps":["trace[1184994925] 'process raft request' (duration: 127.287514ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:53.558728Z","caller":"traceutil/trace.go:171","msg":"trace[838684729] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"455.422507ms","start":"2026-04-20T12:02:53.103279Z","end":"2026-04-20T12:02:53.558702Z","steps":["trace[838684729] 'process raft request' (duration: 455.264212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:53.559088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:53.103266Z","time spent":"455.55022ms","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:02:53.559842Z","caller":"traceutil/trace.go:171","msg":"trace[1699757012] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5122; }","duration":"208.096976ms","start":"2026-04-20T12:02:53.351719Z","end":"2026-04-20T12:02:53.559816Z","steps":["trace[1699757012] 'read index received' (duration: 206.80753ms)","trace[1699757012] 'applied index is now lower than readState.Index' (duration: 1.287856ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:53.559879Z","caller":"traceutil/trace.go:171","msg":"trace[648143170] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"408.772841ms","start":"2026-04-20T12:02:53.151076Z","end":"2026-04-20T12:02:53.559848Z","steps":["trace[648143170] 'process raft request' (duration: 408.576396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:53.560009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:53.151054Z","time spent":"408.888225ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:02:53.560025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.192994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:02:53.560101Z","caller":"traceutil/trace.go:171","msg":"trace[1777549050] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4920; }","duration":"197.316458ms","start":"2026-04-20T12:02:53.362757Z","end":"2026-04-20T12:02:53.560074Z","steps":["trace[1777549050] 'agreement among raft nodes before linearized reading' (duration: 197.199164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:53.56034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.551259ms","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-20T12:02:53.560465Z","caller":"traceutil/trace.go:171","msg":"trace[868825065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4920; }","duration":"208.775897ms","start":"2026-04-20T12:02:53.351657Z","end":"2026-04-20T12:02:53.560433Z","steps":["trace[868825065] 'agreement among raft nodes before linearized reading' (duration: 208.294332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:53.560424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.019535ms","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-20T12:02:53.560652Z","caller":"traceutil/trace.go:171","msg":"trace[1108425189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4920; }","duration":"178.283854ms","start":"2026-04-20T12:02:53.38232Z","end":"2026-04-20T12:02:53.560604Z","steps":["trace[1108425189] 'agreement among raft nodes before linearized reading' (duration: 177.712776ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:03:10.994078Z","caller":"traceutil/trace.go:171","msg":"trace[404614809] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"115.417463ms","start":"2026-04-20T12:03:10.878629Z","end":"2026-04-20T12:03:10.994047Z","steps":["trace[404614809] 'process raft request' (duration: 115.120434ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:05:51.039867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3187} {"level":"info","ts":"2026-04-20T12:05:51.128652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3187,"took":"86.567923ms","hash":2137535642,"current-db-size-bytes":60567552,"current-db-size":"61 MB","current-db-size-in-use-bytes":33566720,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T12:05:51.128727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2137535642,"revision":3187,"compact-revision":-1} {"level":"info","ts":"2026-04-20T12:08:51.585201Z","caller":"traceutil/trace.go:171","msg":"trace[466879610] linearizableReadLoop","detail":"{readStateIndex:8637; appliedIndex:8636; }","duration":"179.914644ms","start":"2026-04-20T12:08:51.405261Z","end":"2026-04-20T12:08:51.585175Z","steps":["trace[466879610] 'read index received' (duration: 179.702958ms)","trace[466879610] 'applied index is now lower than readState.Index' (duration: 206.286µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:51.585302Z","caller":"traceutil/trace.go:171","msg":"trace[1970639155] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"256.72133ms","start":"2026-04-20T12:08:51.328538Z","end":"2026-04-20T12:08:51.585259Z","steps":["trace[1970639155] 'process raft request' (duration: 256.458262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:51.5855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.228213ms","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-20T12:08:51.585578Z","caller":"traceutil/trace.go:171","msg":"trace[1404590773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8273; }","duration":"180.355756ms","start":"2026-04-20T12:08:51.405204Z","end":"2026-04-20T12:08:51.58556Z","steps":["trace[1404590773] 'agreement among raft nodes before linearized reading' (duration: 180.14489ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:51.723794Z","caller":"traceutil/trace.go:171","msg":"trace[2014972875] transaction","detail":"{read_only:false; response_revision:8274; number_of_response:1; }","duration":"132.061957ms","start":"2026-04-20T12:08:51.591702Z","end":"2026-04-20T12:08:51.723764Z","steps":["trace[2014972875] 'process raft request' (duration: 49.318829ms)","trace[2014972875] 'compare' (duration: 82.508341ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:51.723952Z","caller":"traceutil/trace.go:171","msg":"trace[974346374] linearizableReadLoop","detail":"{readStateIndex:8639; appliedIndex:8637; }","duration":"129.857404ms","start":"2026-04-20T12:08:51.594055Z","end":"2026-04-20T12:08:51.723913Z","steps":["trace[974346374] 'read index received' (duration: 46.92761ms)","trace[974346374] 'applied index is now lower than readState.Index' (duration: 82.927704ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:51.723995Z","caller":"traceutil/trace.go:171","msg":"trace[694803352] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"132.272584ms","start":"2026-04-20T12:08:51.591694Z","end":"2026-04-20T12:08:51.723967Z","steps":["trace[694803352] 'process raft request' (duration: 131.987755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:51.724147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.692428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:08:51.724193Z","caller":"traceutil/trace.go:171","msg":"trace[1800944904] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8275; }","duration":"130.813242ms","start":"2026-04-20T12:08:51.593367Z","end":"2026-04-20T12:08:51.72418Z","steps":["trace[1800944904] 'agreement among raft nodes before linearized reading' (duration: 130.717629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:51.724263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.760858ms","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-20T12:08:51.724348Z","caller":"traceutil/trace.go:171","msg":"trace[2059468727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8275; }","duration":"122.902942ms","start":"2026-04-20T12:08:51.601428Z","end":"2026-04-20T12:08:51.724331Z","steps":["trace[2059468727] 'agreement among raft nodes before linearized reading' (duration: 122.709717ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:53.918196Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021d3.wal"} {"level":"info","ts":"2026-04-20T12:09:03.209131Z","caller":"traceutil/trace.go:171","msg":"trace[1839697778] linearizableReadLoop","detail":"{readStateIndex:8720; appliedIndex:8719; }","duration":"124.641952ms","start":"2026-04-20T12:09:03.084452Z","end":"2026-04-20T12:09:03.209094Z","steps":["trace[1839697778] 'read index received' (duration: 124.432086ms)","trace[1839697778] 'applied index is now lower than readState.Index' (duration: 208.836µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:03.209169Z","caller":"traceutil/trace.go:171","msg":"trace[1213682286] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"190.83419ms","start":"2026-04-20T12:09:03.018301Z","end":"2026-04-20T12:09:03.209135Z","steps":["trace[1213682286] 'process raft request' (duration: 190.637035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:03.209341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.867598ms","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-20T12:09:03.209383Z","caller":"traceutil/trace.go:171","msg":"trace[358853207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8351; }","duration":"124.976541ms","start":"2026-04-20T12:09:03.084396Z","end":"2026-04-20T12:09:03.209372Z","steps":["trace[358853207] 'agreement among raft nodes before linearized reading' (duration: 124.812126ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:03.45436Z","caller":"traceutil/trace.go:171","msg":"trace[679821515] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"240.687986ms","start":"2026-04-20T12:09:03.213641Z","end":"2026-04-20T12:09:03.454329Z","steps":["trace[679821515] 'process raft request' (duration: 230.294124ms)","trace[679821515] 'compare' (duration: 10.115904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:04.208705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.710006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:09:04.208843Z","caller":"traceutil/trace.go:171","msg":"trace[250525299] transaction","detail":"{read_only:false; response_revision:8358; number_of_response:1; }","duration":"339.21188ms","start":"2026-04-20T12:09:03.869608Z","end":"2026-04-20T12:09:04.208819Z","steps":["trace[250525299] 'process raft request' (duration: 39.118153ms)","trace[250525299] 'compare' (duration: 298.835231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:04.208917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:03.86959Z","time spent":"339.296663ms","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:09:04.340387Z","caller":"traceutil/trace.go:171","msg":"trace[22519772] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8727; }","duration":"327.487121ms","start":"2026-04-20T12:09:04.012873Z","end":"2026-04-20T12:09:04.34036Z","steps":["trace[22519772] 'read index received' (duration: 295.234507ms)","trace[22519772] 'applied index is now lower than readState.Index' (duration: 32.251714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:04.340612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.244451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T12:09:04.340644Z","caller":"traceutil/trace.go:171","msg":"trace[1770997946] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:8359; }","duration":"182.330324ms","start":"2026-04-20T12:09:04.1583Z","end":"2026-04-20T12:09:04.340631Z","steps":["trace[1770997946] 'agreement among raft nodes before linearized reading' (duration: 182.187039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:04.340628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.734287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-20T12:09:04.340718Z","caller":"traceutil/trace.go:171","msg":"trace[1129232573] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8359; }","duration":"327.873972ms","start":"2026-04-20T12:09:04.012827Z","end":"2026-04-20T12:09:04.340701Z","steps":["trace[1129232573] 'agreement among raft nodes before linearized reading' (duration: 327.629015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:04.340759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:04.012811Z","time spent":"327.939593ms","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T12:09:04.34073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.072671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-20T12:09:04.340821Z","caller":"traceutil/trace.go:171","msg":"trace[72665214] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8359; }","duration":"127.206825ms","start":"2026-04-20T12:09:04.213592Z","end":"2026-04-20T12:09:04.340799Z","steps":["trace[72665214] 'agreement among raft nodes before linearized reading' (duration: 126.941378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:04.340947Z","caller":"traceutil/trace.go:171","msg":"trace[1092689111] transaction","detail":"{read_only:false; response_revision:8359; number_of_response:1; }","duration":"470.648769ms","start":"2026-04-20T12:09:03.869907Z","end":"2026-04-20T12:09:04.340556Z","steps":["trace[1092689111] 'process raft request' (duration: 438.233961ms)","trace[1092689111] 'compare' (duration: 32.10947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:04.342326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:03.869898Z","time spent":"472.278997ms","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:09:32.21453Z","caller":"traceutil/trace.go:171","msg":"trace[993236380] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"260.948038ms","start":"2026-04-20T12:09:31.953559Z","end":"2026-04-20T12:09:32.214507Z","steps":["trace[993236380] 'process raft request' (duration: 260.814344ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:32.215064Z","caller":"traceutil/trace.go:171","msg":"trace[980393290] linearizableReadLoop","detail":"{readStateIndex:8979; appliedIndex:8979; }","duration":"231.047691ms","start":"2026-04-20T12:09:31.983998Z","end":"2026-04-20T12:09:32.215046Z","steps":["trace[980393290] 'read index received' (duration: 231.040941ms)","trace[980393290] 'applied index is now lower than readState.Index' (duration: 4.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:32.215275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.971134ms","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-20T12:09:32.215331Z","caller":"traceutil/trace.go:171","msg":"trace[586840861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8597; }","duration":"228.125518ms","start":"2026-04-20T12:09:31.987191Z","end":"2026-04-20T12:09:32.215317Z","steps":["trace[586840861] 'agreement among raft nodes before linearized reading' (duration: 227.941121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:32.215535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.531876ms","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-20T12:09:32.215578Z","caller":"traceutil/trace.go:171","msg":"trace[537561569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8597; }","duration":"231.602918ms","start":"2026-04-20T12:09:31.983966Z","end":"2026-04-20T12:09:32.215569Z","steps":["trace[537561569] 'agreement among raft nodes before linearized reading' (duration: 231.475584ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:32.403123Z","caller":"traceutil/trace.go:171","msg":"trace[105760270] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"182.697963ms","start":"2026-04-20T12:09:32.220407Z","end":"2026-04-20T12:09:32.403105Z","steps":["trace[105760270] 'process raft request' (duration: 182.60626ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:32.40316Z","caller":"traceutil/trace.go:171","msg":"trace[916823865] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"183.487505ms","start":"2026-04-20T12:09:32.219636Z","end":"2026-04-20T12:09:32.403124Z","steps":["trace[916823865] 'process raft request' (duration: 84.374054ms)","trace[916823865] 'compare' (duration: 98.7565ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:45.737188Z","caller":"traceutil/trace.go:171","msg":"trace[734061785] linearizableReadLoop","detail":"{readStateIndex:9117; appliedIndex:9116; }","duration":"166.350658ms","start":"2026-04-20T12:09:45.570823Z","end":"2026-04-20T12:09:45.737173Z","steps":["trace[734061785] 'read index received' (duration: 166.231914ms)","trace[734061785] 'applied index is now lower than readState.Index' (duration: 118.044µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:45.737294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.470552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:09:45.737336Z","caller":"traceutil/trace.go:171","msg":"trace[1190782753] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"242.758631ms","start":"2026-04-20T12:09:45.494474Z","end":"2026-04-20T12:09:45.737233Z","steps":["trace[1190782753] 'process raft request' (duration: 242.574015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:45.737349Z","caller":"traceutil/trace.go:171","msg":"trace[1729578833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8727; }","duration":"166.591745ms","start":"2026-04-20T12:09:45.570747Z","end":"2026-04-20T12:09:45.737338Z","steps":["trace[1729578833] 'agreement among raft nodes before linearized reading' (duration: 166.508182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:45.824915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.249863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T12:09:45.824996Z","caller":"traceutil/trace.go:171","msg":"trace[2063929621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8729; }","duration":"149.387857ms","start":"2026-04-20T12:09:45.675588Z","end":"2026-04-20T12:09:45.824976Z","steps":["trace[2063929621] 'agreement among raft nodes before linearized reading' (duration: 149.157941ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:46.580886Z","caller":"traceutil/trace.go:171","msg":"trace[2072377401] transaction","detail":"{read_only:false; response_revision:8733; number_of_response:1; }","duration":"260.055523ms","start":"2026-04-20T12:09:46.320804Z","end":"2026-04-20T12:09:46.58086Z","steps":["trace[2072377401] 'process raft request' (duration: 215.870303ms)","trace[2072377401] 'compare' (duration: 44.079917ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:46.60513Z","caller":"traceutil/trace.go:171","msg":"trace[631745517] linearizableReadLoop","detail":"{readStateIndex:9125; appliedIndex:9122; }","duration":"130.16655ms","start":"2026-04-20T12:09:46.474942Z","end":"2026-04-20T12:09:46.605108Z","steps":["trace[631745517] 'read index received' (duration: 61.935283ms)","trace[631745517] 'applied index is now lower than readState.Index' (duration: 68.230456ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:46.605244Z","caller":"traceutil/trace.go:171","msg":"trace[1342009615] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"141.828417ms","start":"2026-04-20T12:09:46.463405Z","end":"2026-04-20T12:09:46.605233Z","steps":["trace[1342009615] 'process raft request' (duration: 141.57493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:46.605456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.489219ms","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-20T12:09:46.605802Z","caller":"traceutil/trace.go:171","msg":"trace[309968391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8734; }","duration":"130.88725ms","start":"2026-04-20T12:09:46.474905Z","end":"2026-04-20T12:09:46.605792Z","steps":["trace[309968391] 'agreement among raft nodes before linearized reading' (duration: 130.405226ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:46.605153Z","caller":"traceutil/trace.go:171","msg":"trace[1780408318] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8733; }","duration":"283.167282ms","start":"2026-04-20T12:09:46.321954Z","end":"2026-04-20T12:09:46.605122Z","steps":["trace[1780408318] 'process raft request' (duration: 282.929885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:46.60563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.058498ms","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-20T12:09:46.606071Z","caller":"traceutil/trace.go:171","msg":"trace[534136174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8734; }","duration":"129.534703ms","start":"2026-04-20T12:09:46.476517Z","end":"2026-04-20T12:09:46.606052Z","steps":["trace[534136174] 'agreement among raft nodes before linearized reading' (duration: 128.937514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:10:47.427689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.274341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t8d6h\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T12:10:47.427759Z","caller":"traceutil/trace.go:171","msg":"trace[1788517011] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t8d6h; range_end:; response_count:1; response_revision:9412; }","duration":"142.354203ms","start":"2026-04-20T12:10:47.285393Z","end":"2026-04-20T12:10:47.427748Z","steps":["trace[1788517011] 'range keys from in-memory index tree' (duration: 142.130746ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:10:51.050329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6550} {"level":"info","ts":"2026-04-20T12:10:51.148105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6550,"took":"95.838125ms","hash":3822845714,"current-db-size-bytes":60567552,"current-db-size":"61 MB","current-db-size-in-use-bytes":26619904,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-20T12:10:51.148211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3822845714,"revision":6550,"compact-revision":3187} {"level":"info","ts":"2026-04-20T12:11:05.877476Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"19a81b20c4b111c3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T12:11:05.882215Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T12:11:05.882295Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T12:15:51.058076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9445} {"level":"info","ts":"2026-04-20T12:15:51.140989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9445,"took":"81.248196ms","hash":3194515832,"current-db-size-bytes":60567552,"current-db-size":"61 MB","current-db-size-in-use-bytes":25714688,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-20T12:15:51.141083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3194515832,"revision":9445,"compact-revision":6550}