{"level":"warn","ts":"2026-04-19T13:29:29.135095Z","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-19T13:29:29.135189Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.80: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.80:2380","--initial-cluster=instance=https://199.19.213.80:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.80:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.80: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-19T13:29:29.135258Z","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-19T13:29:29.13527Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.80:2380"]} {"level":"info","ts":"2026-04-19T13:29:29.135286Z","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-19T13:29:29.13591Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.80:2379"]} {"level":"info","ts":"2026-04-19T13:29:29.135981Z","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.80:2380"],"listen-peer-urls":["https://199.19.213.80:2380"],"advertise-client-urls":["https://199.19.213.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.80: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-19T13:29:29.141739Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.556004ms"} {"level":"info","ts":"2026-04-19T13:29:29.152603Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95f079218969d088","cluster-id":"4cb7d1aa36c4178f"} {"level":"info","ts":"2026-04-19T13:29:29.152699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:29:29.152727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became follower at term 0"} {"level":"info","ts":"2026-04-19T13:29:29.152748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95f079218969d088 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:29:29.15276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became follower at term 1"} {"level":"info","ts":"2026-04-19T13:29:29.152807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 switched to configuration voters=(10804268691008114824)"} {"level":"warn","ts":"2026-04-19T13:29:29.162194Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:29:29.163997Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:29:29.16782Z","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-19T13:29:29.173458Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95f079218969d088","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:29:29.174776Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95f079218969d088","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:29:29.175053Z","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-19T13:29:29.175529Z","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-19T13:29:29.175548Z","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-19T13:29:29.17569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 switched to configuration voters=(10804268691008114824)"} {"level":"info","ts":"2026-04-19T13:29:29.176063Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4cb7d1aa36c4178f","local-member-id":"95f079218969d088","added-peer-id":"95f079218969d088","added-peer-peer-urls":["https://199.19.213.80:2380"]} {"level":"info","ts":"2026-04-19T13:29:29.17854Z","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-19T13:29:29.178645Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.80:2380"} {"level":"info","ts":"2026-04-19T13:29:29.178711Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.80:2380"} {"level":"info","ts":"2026-04-19T13:29:29.178781Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95f079218969d088","initial-advertise-peer-urls":["https://199.19.213.80:2380"],"listen-peer-urls":["https://199.19.213.80:2380"],"advertise-client-urls":["https://199.19.213.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:29:29.178817Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:29:30.153261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:29:30.153314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:29:30.153339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 received MsgPreVoteResp from 95f079218969d088 at term 1"} {"level":"info","ts":"2026-04-19T13:29:30.153354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:29:30.153361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 received MsgVoteResp from 95f079218969d088 at term 2"} {"level":"info","ts":"2026-04-19T13:29:30.153372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95f079218969d088 became leader at term 2"} {"level":"info","ts":"2026-04-19T13:29:30.153382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95f079218969d088 elected leader 95f079218969d088 at term 2"} {"level":"info","ts":"2026-04-19T13:29:30.154431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:30.154795Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95f079218969d088","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.80:2379]}","request-path":"/0/members/95f079218969d088/attributes","cluster-id":"4cb7d1aa36c4178f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:29:30.154801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:29:30.154861Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:29:30.155064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:29:30.155221Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:29:30.157504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.80:2379"} {"level":"info","ts":"2026-04-19T13:29:30.158738Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T13:29:30.16012Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4cb7d1aa36c4178f","local-member-id":"95f079218969d088","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:30.160248Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:30.160301Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:50.977771Z","caller":"traceutil/trace.go:171","msg":"trace[1819090550] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"112.321386ms","start":"2026-04-19T13:29:50.865425Z","end":"2026-04-19T13:29:50.977746Z","steps":["trace[1819090550] 'process raft request' (duration: 112.183573ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:01.006733Z","caller":"traceutil/trace.go:171","msg":"trace[2003442410] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"270.476318ms","start":"2026-04-19T13:30:00.736233Z","end":"2026-04-19T13:30:01.00671Z","steps":["trace[2003442410] 'process raft request' (duration: 262.502256ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:01.021869Z","caller":"traceutil/trace.go:171","msg":"trace[1798814393] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"285.223143ms","start":"2026-04-19T13:30:00.736617Z","end":"2026-04-19T13:30:01.02184Z","steps":["trace[1798814393] 'process raft request' (duration: 281.507084ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:01.022294Z","caller":"traceutil/trace.go:171","msg":"trace[635866388] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"281.359819ms","start":"2026-04-19T13:30:00.740903Z","end":"2026-04-19T13:30:01.022263Z","steps":["trace[635866388] 'process raft request' (duration: 280.863288ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:06.533811Z","caller":"traceutil/trace.go:171","msg":"trace[169043430] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"136.393437ms","start":"2026-04-19T13:30:06.396881Z","end":"2026-04-19T13:30:06.533274Z","steps":["trace[169043430] 'process raft request' (duration: 135.879024ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:07.162273Z","caller":"traceutil/trace.go:171","msg":"trace[2022572600] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"107.719843ms","start":"2026-04-19T13:30:07.054481Z","end":"2026-04-19T13:30:07.162201Z","steps":["trace[2022572600] 'read index received' (duration: 96.609125ms)","trace[2022572600] 'applied index is now lower than readState.Index' (duration: 11.108798ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:07.162339Z","caller":"traceutil/trace.go:171","msg":"trace[1500145363] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"135.990757ms","start":"2026-04-19T13:30:07.026291Z","end":"2026-04-19T13:30:07.162282Z","steps":["trace[1500145363] 'process raft request' (duration: 125.601826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:07.162724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.237646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-19T13:30:07.162812Z","caller":"traceutil/trace.go:171","msg":"trace[267625299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"108.41789ms","start":"2026-04-19T13:30:07.054378Z","end":"2026-04-19T13:30:07.162796Z","steps":["trace[267625299] 'agreement among raft nodes before linearized reading' (duration: 107.97611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:21.38505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.905491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-19T13:30:21.385148Z","caller":"traceutil/trace.go:171","msg":"trace[1473922521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"147.074625ms","start":"2026-04-19T13:30:21.238051Z","end":"2026-04-19T13:30:21.385125Z","steps":["trace[1473922521] 'range keys from in-memory index tree' (duration: 146.642105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:21.385651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.478051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-19T13:30:21.385774Z","caller":"traceutil/trace.go:171","msg":"trace[970248217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"127.677415ms","start":"2026-04-19T13:30:21.258071Z","end":"2026-04-19T13:30:21.385749Z","steps":["trace[970248217] 'range keys from in-memory index tree' (duration: 127.230346ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:21.504686Z","caller":"traceutil/trace.go:171","msg":"trace[2088103251] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"114.189091ms","start":"2026-04-19T13:30:21.390472Z","end":"2026-04-19T13:30:21.504661Z","steps":["trace[2088103251] 'process raft request' (duration: 114.018496ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:21.504885Z","caller":"traceutil/trace.go:171","msg":"trace[1321817791] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"111.527246ms","start":"2026-04-19T13:30:21.393315Z","end":"2026-04-19T13:30:21.504842Z","steps":["trace[1321817791] 'process raft request' (duration: 111.28914ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:35.106034Z","caller":"traceutil/trace.go:171","msg":"trace[263190171] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"107.054966ms","start":"2026-04-19T13:31:34.998942Z","end":"2026-04-19T13:31:35.105997Z","steps":["trace[263190171] 'process raft request' (duration: 24.215483ms)","trace[263190171] 'compare' (duration: 82.70134ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:35.377075Z","caller":"traceutil/trace.go:171","msg":"trace[1707247541] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"161.835936ms","start":"2026-04-19T13:31:35.215196Z","end":"2026-04-19T13:31:35.377032Z","steps":["trace[1707247541] 'read index received' (duration: 161.545009ms)","trace[1707247541] 'applied index is now lower than readState.Index' (duration: 289.287µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:35.377224Z","caller":"traceutil/trace.go:171","msg":"trace[166019428] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"260.604512ms","start":"2026-04-19T13:31:35.116553Z","end":"2026-04-19T13:31:35.377158Z","steps":["trace[166019428] 'process raft request' (duration: 260.278044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:35.377414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.192964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-04-19T13:31:35.377505Z","caller":"traceutil/trace.go:171","msg":"trace[440217421] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1829; }","duration":"162.301866ms","start":"2026-04-19T13:31:35.215182Z","end":"2026-04-19T13:31:35.377484Z","steps":["trace[440217421] 'agreement among raft nodes before linearized reading' (duration: 161.990889ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:35.925754Z","caller":"traceutil/trace.go:171","msg":"trace[1172437245] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"217.960846ms","start":"2026-04-19T13:31:35.707757Z","end":"2026-04-19T13:31:35.925718Z","steps":["trace[1172437245] 'read index received' (duration: 217.622778ms)","trace[1172437245] 'applied index is now lower than readState.Index' (duration: 336.608µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:35.925953Z","caller":"traceutil/trace.go:171","msg":"trace[138849186] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"229.579025ms","start":"2026-04-19T13:31:35.696327Z","end":"2026-04-19T13:31:35.925906Z","steps":["trace[138849186] 'process raft request' (duration: 229.165996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:35.926213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.416746ms","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-19T13:31:35.926353Z","caller":"traceutil/trace.go:171","msg":"trace[974050204] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"218.588701ms","start":"2026-04-19T13:31:35.707708Z","end":"2026-04-19T13:31:35.926296Z","steps":["trace[974050204] 'agreement among raft nodes before linearized reading' (duration: 218.253213ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:15.826016Z","caller":"traceutil/trace.go:171","msg":"trace[264371731] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"246.465741ms","start":"2026-04-19T13:32:15.579527Z","end":"2026-04-19T13:32:15.825993Z","steps":["trace[264371731] 'read index received' (duration: 246.273676ms)","trace[264371731] 'applied index is now lower than readState.Index' (duration: 191.575µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:15.826114Z","caller":"traceutil/trace.go:171","msg":"trace[1964489345] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"283.819652ms","start":"2026-04-19T13:32:15.542255Z","end":"2026-04-19T13:32:15.826075Z","steps":["trace[1964489345] 'process raft request' (duration: 283.594776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:15.826194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.649605ms","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-19T13:32:15.826218Z","caller":"traceutil/trace.go:171","msg":"trace[998598474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2107; }","duration":"246.733387ms","start":"2026-04-19T13:32:15.579479Z","end":"2026-04-19T13:32:15.826212Z","steps":["trace[998598474] 'agreement among raft nodes before linearized reading' (duration: 246.620194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:15.826499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.311793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-19T13:32:15.82658Z","caller":"traceutil/trace.go:171","msg":"trace[712895106] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2107; }","duration":"175.460216ms","start":"2026-04-19T13:32:15.651101Z","end":"2026-04-19T13:32:15.826561Z","steps":["trace[712895106] 'agreement among raft nodes before linearized reading' (duration: 175.230821ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:21.414793Z","caller":"traceutil/trace.go:171","msg":"trace[917354224] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"129.025084ms","start":"2026-04-19T13:32:21.285722Z","end":"2026-04-19T13:32:21.414748Z","steps":["trace[917354224] 'process raft request' (duration: 128.811049ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:12.556221Z","caller":"traceutil/trace.go:171","msg":"trace[788065325] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"355.919953ms","start":"2026-04-19T13:33:12.200267Z","end":"2026-04-19T13:33:12.556187Z","steps":["trace[788065325] 'process raft request' (duration: 355.740949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:12.556955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:12.200254Z","time spent":"356.068756ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:33:12.561476Z","caller":"traceutil/trace.go:171","msg":"trace[857610939] linearizableReadLoop","detail":"{readStateIndex:2549; appliedIndex:2547; }","duration":"242.185487ms","start":"2026-04-19T13:33:12.319265Z","end":"2026-04-19T13:33:12.56145Z","steps":["trace[857610939] 'read index received' (duration: 236.753396ms)","trace[857610939] 'applied index is now lower than readState.Index' (duration: 5.430651ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:12.561783Z","caller":"traceutil/trace.go:171","msg":"trace[586594838] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"357.751118ms","start":"2026-04-19T13:33:12.204018Z","end":"2026-04-19T13:33:12.561769Z","steps":["trace[586594838] 'process raft request' (duration: 357.308518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:12.561896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:33:12.20398Z","time spent":"357.83924ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:33:12.562152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.891875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-19T13:33:12.562191Z","caller":"traceutil/trace.go:171","msg":"trace[251506772] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2456; }","duration":"242.970017ms","start":"2026-04-19T13:33:12.319211Z","end":"2026-04-19T13:33:12.562181Z","steps":["trace[251506772] 'agreement among raft nodes before linearized reading' (duration: 242.843284ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:49.449488Z","caller":"traceutil/trace.go:171","msg":"trace[1062495985] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"107.286677ms","start":"2026-04-19T13:33:49.342167Z","end":"2026-04-19T13:33:49.449454Z","steps":["trace[1062495985] 'process raft request' (duration: 107.095433ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:08.021402Z","caller":"traceutil/trace.go:171","msg":"trace[793053081] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5097; }","duration":"177.370812ms","start":"2026-04-19T13:38:07.844002Z","end":"2026-04-19T13:38:08.021373Z","steps":["trace[793053081] 'read index received' (duration: 177.220529ms)","trace[793053081] 'applied index is now lower than readState.Index' (duration: 149.053µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:08.0215Z","caller":"traceutil/trace.go:171","msg":"trace[301785015] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"184.502723ms","start":"2026-04-19T13:38:07.836952Z","end":"2026-04-19T13:38:08.021454Z","steps":["trace[301785015] 'process raft request' (duration: 184.221947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:08.021729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.70552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-19T13:38:08.021775Z","caller":"traceutil/trace.go:171","msg":"trace[155703783] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4915; }","duration":"177.814422ms","start":"2026-04-19T13:38:07.843948Z","end":"2026-04-19T13:38:08.021762Z","steps":["trace[155703783] 'agreement among raft nodes before linearized reading' (duration: 177.597277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:08.134508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.926709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-mfgd5-tls\" ","response":"range_response_count:1 size:4435"} {"level":"info","ts":"2026-04-19T13:38:08.134614Z","caller":"traceutil/trace.go:171","msg":"trace[482409262] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-mfgd5-tls; range_end:; response_count:1; response_revision:4915; }","duration":"105.063053ms","start":"2026-04-19T13:38:08.029526Z","end":"2026-04-19T13:38:08.134589Z","steps":["trace[482409262] 'range keys from in-memory index tree' (duration: 104.759065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:08.134597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.658925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-19T13:38:08.134704Z","caller":"traceutil/trace.go:171","msg":"trace[2031280094] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4915; }","duration":"107.83217ms","start":"2026-04-19T13:38:08.026847Z","end":"2026-04-19T13:38:08.134679Z","steps":["trace[2031280094] 'range keys from in-memory index tree' (duration: 107.338198ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:20.649932Z","caller":"traceutil/trace.go:171","msg":"trace[2078438333] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"170.479348ms","start":"2026-04-19T13:38:20.479409Z","end":"2026-04-19T13:38:20.649888Z","steps":["trace[2078438333] 'process raft request' (duration: 123.77901ms)","trace[2078438333] 'compare' (duration: 46.250037ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:28.103592Z","caller":"traceutil/trace.go:171","msg":"trace[1198418598] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"107.590694ms","start":"2026-04-19T13:38:27.995957Z","end":"2026-04-19T13:38:28.103548Z","steps":["trace[1198418598] 'process raft request' (duration: 107.351318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:28.307525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.767999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:38:28.307703Z","caller":"traceutil/trace.go:171","msg":"trace[1620288797] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"152.222022ms","start":"2026-04-19T13:38:28.155451Z","end":"2026-04-19T13:38:28.307673Z","steps":["trace[1620288797] 'process raft request' (duration: 30.975671ms)","trace[1620288797] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/services/endpoints/monitoring/prometheus-pushgateway; req_size:996; } (duration: 120.303338ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:28.364544Z","caller":"traceutil/trace.go:171","msg":"trace[1352223399] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"208.912638ms","start":"2026-04-19T13:38:28.155595Z","end":"2026-04-19T13:38:28.364508Z","steps":["trace[1352223399] 'process raft request' (duration: 208.353604ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:17.258292Z","caller":"traceutil/trace.go:171","msg":"trace[453033198] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"129.371286ms","start":"2026-04-19T13:39:17.128882Z","end":"2026-04-19T13:39:17.258253Z","steps":["trace[453033198] 'process raft request' (duration: 48.933962ms)","trace[453033198] 'compare' (duration: 80.187428ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:39:17.259394Z","caller":"traceutil/trace.go:171","msg":"trace[1142046395] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"124.01851ms","start":"2026-04-19T13:39:17.134675Z","end":"2026-04-19T13:39:17.258694Z","steps":["trace[1142046395] 'process raft request' (duration: 123.505957ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:39:30.246108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2877} {"level":"info","ts":"2026-04-19T13:39:30.399381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2877,"took":"151.707041ms","hash":533301405,"current-db-size-bytes":57106432,"current-db-size":"57 MB","current-db-size-in-use-bytes":32432128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-19T13:39:30.399484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":533301405,"revision":2877,"compact-revision":-1} {"level":"warn","ts":"2026-04-19T13:44:24.95429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.431753ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:44:24.95451Z","caller":"traceutil/trace.go:171","msg":"trace[1780321582] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8108; }","duration":"168.668978ms","start":"2026-04-19T13:44:24.785818Z","end":"2026-04-19T13:44:24.954487Z","steps":["trace[1780321582] 'range keys from in-memory index tree' (duration: 168.412603ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:24.954972Z","caller":"traceutil/trace.go:171","msg":"trace[201817495] linearizableReadLoop","detail":"{readStateIndex:8391; appliedIndex:8390; }","duration":"289.624556ms","start":"2026-04-19T13:44:24.665333Z","end":"2026-04-19T13:44:24.954958Z","steps":["trace[201817495] 'read index received' (duration: 213.021821ms)","trace[201817495] 'applied index is now lower than readState.Index' (duration: 76.601195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:44:24.95511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.781479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:44:24.955086Z","caller":"traceutil/trace.go:171","msg":"trace[778575069] transaction","detail":"{read_only:false; response_revision:8109; number_of_response:1; }","duration":"358.888114ms","start":"2026-04-19T13:44:24.596156Z","end":"2026-04-19T13:44:24.955044Z","steps":["trace[778575069] 'process raft request' (duration: 282.278299ms)","trace[778575069] 'compare' (duration: 76.250826ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:44:24.955145Z","caller":"traceutil/trace.go:171","msg":"trace[1934039016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8109; }","duration":"289.868951ms","start":"2026-04-19T13:44:24.665267Z","end":"2026-04-19T13:44:24.955136Z","steps":["trace[1934039016] 'agreement among raft nodes before linearized reading' (duration: 289.795659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:24.955281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.537143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-19T13:44:24.955391Z","caller":"traceutil/trace.go:171","msg":"trace[342909944] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8109; }","duration":"251.700297ms","start":"2026-04-19T13:44:24.703662Z","end":"2026-04-19T13:44:24.955362Z","steps":["trace[342909944] 'agreement among raft nodes before linearized reading' (duration: 251.449101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:24.955416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.153363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:44:24.955308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:44:24.596124Z","time spent":"359.073538ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:44:24.955522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.934529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-19T13:44:24.955571Z","caller":"traceutil/trace.go:171","msg":"trace[1535128221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8109; }","duration":"251.037121ms","start":"2026-04-19T13:44:24.704523Z","end":"2026-04-19T13:44:24.95556Z","steps":["trace[1535128221] 'agreement among raft nodes before linearized reading' (duration: 250.834746ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:24.955523Z","caller":"traceutil/trace.go:171","msg":"trace[1680481791] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8109; }","duration":"162.334767ms","start":"2026-04-19T13:44:24.793164Z","end":"2026-04-19T13:44:24.955498Z","steps":["trace[1680481791] 'agreement among raft nodes before linearized reading' (duration: 162.132573ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:30.253133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5916} {"level":"info","ts":"2026-04-19T13:44:30.341587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5916,"took":"85.964999ms","hash":1762065410,"current-db-size-bytes":57106432,"current-db-size":"57 MB","current-db-size-in-use-bytes":26497024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-19T13:44:30.341689Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1762065410,"revision":5916,"compact-revision":2877} {"level":"info","ts":"2026-04-19T13:44:36.577904Z","caller":"traceutil/trace.go:171","msg":"trace[1501436360] linearizableReadLoop","detail":"{readStateIndex:8454; appliedIndex:8453; }","duration":"191.089235ms","start":"2026-04-19T13:44:36.386788Z","end":"2026-04-19T13:44:36.577877Z","steps":["trace[1501436360] 'read index received' (duration: 190.936382ms)","trace[1501436360] 'applied index is now lower than readState.Index' (duration: 151.463µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:44:36.577948Z","caller":"traceutil/trace.go:171","msg":"trace[1343185957] transaction","detail":"{read_only:false; response_revision:8168; number_of_response:1; }","duration":"201.654449ms","start":"2026-04-19T13:44:36.37626Z","end":"2026-04-19T13:44:36.577915Z","steps":["trace[1343185957] 'process raft request' (duration: 201.448904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:36.578143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.329161ms","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-19T13:44:36.578182Z","caller":"traceutil/trace.go:171","msg":"trace[590377251] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8168; }","duration":"191.403503ms","start":"2026-04-19T13:44:36.386764Z","end":"2026-04-19T13:44:36.578168Z","steps":["trace[590377251] 'agreement among raft nodes before linearized reading' (duration: 191.218939ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:36.670655Z","caller":"traceutil/trace.go:171","msg":"trace[830295374] transaction","detail":"{read_only:false; response_revision:8169; number_of_response:1; }","duration":"254.540755ms","start":"2026-04-19T13:44:36.416078Z","end":"2026-04-19T13:44:36.670619Z","steps":["trace[830295374] 'process raft request' (duration: 254.31874ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:37.446719Z","caller":"traceutil/trace.go:171","msg":"trace[1409877727] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"265.003686ms","start":"2026-04-19T13:44:37.181683Z","end":"2026-04-19T13:44:37.446687Z","steps":["trace[1409877727] 'process raft request' (duration: 264.181146ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:37.538346Z","caller":"traceutil/trace.go:171","msg":"trace[769007537] linearizableReadLoop","detail":"{readStateIndex:8460; appliedIndex:8458; }","duration":"332.480272ms","start":"2026-04-19T13:44:37.205797Z","end":"2026-04-19T13:44:37.538277Z","steps":["trace[769007537] 'read index received' (duration: 240.059599ms)","trace[769007537] 'applied index is now lower than readState.Index' (duration: 92.419623ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:44:37.538432Z","caller":"traceutil/trace.go:171","msg":"trace[1730809530] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"356.472336ms","start":"2026-04-19T13:44:37.181904Z","end":"2026-04-19T13:44:37.538377Z","steps":["trace[1730809530] 'process raft request' (duration: 325.906244ms)","trace[1730809530] 'compare' (duration: 30.290886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:44:37.538629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.791529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cm968\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-04-19T13:44:37.538724Z","caller":"traceutil/trace.go:171","msg":"trace[437371512] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cm968; range_end:; response_count:1; response_revision:8174; }","duration":"332.924783ms","start":"2026-04-19T13:44:37.205774Z","end":"2026-04-19T13:44:37.538699Z","steps":["trace[437371512] 'agreement among raft nodes before linearized reading' (duration: 332.651767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:37.53867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:44:37.181893Z","time spent":"356.65459ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:44:37.538792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:44:37.205765Z","time spent":"333.010245ms","remote":"127.0.0.1:41098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6541,"request content":"key:\"/registry/pods/openstack/keepalived-cm968\" "} {"level":"info","ts":"2026-04-19T13:44:37.671954Z","caller":"traceutil/trace.go:171","msg":"trace[363898527] linearizableReadLoop","detail":"{readStateIndex:8461; appliedIndex:8460; }","duration":"126.803097ms","start":"2026-04-19T13:44:37.545123Z","end":"2026-04-19T13:44:37.671926Z","steps":["trace[363898527] 'read index received' (duration: 103.945699ms)","trace[363898527] 'applied index is now lower than readState.Index' (duration: 22.856168ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:44:37.672058Z","caller":"traceutil/trace.go:171","msg":"trace[206155722] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"127.75526ms","start":"2026-04-19T13:44:37.544265Z","end":"2026-04-19T13:44:37.672021Z","steps":["trace[206155722] 'process raft request' (duration: 104.741088ms)","trace[206155722] 'compare' (duration: 22.789687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:44:37.67267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.526475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:206998"} {"level":"info","ts":"2026-04-19T13:44:37.672784Z","caller":"traceutil/trace.go:171","msg":"trace[820675043] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8175; }","duration":"127.710209ms","start":"2026-04-19T13:44:37.545048Z","end":"2026-04-19T13:44:37.672759Z","steps":["trace[820675043] 'agreement among raft nodes before linearized reading' (duration: 126.974861ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:04.963472Z","caller":"traceutil/trace.go:171","msg":"trace[2061152751] transaction","detail":"{read_only:false; response_revision:8371; number_of_response:1; }","duration":"230.07193ms","start":"2026-04-19T13:45:04.733368Z","end":"2026-04-19T13:45:04.96344Z","steps":["trace[2061152751] 'process raft request' (duration: 229.896276ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:05.104693Z","caller":"traceutil/trace.go:171","msg":"trace[1585247891] linearizableReadLoop","detail":"{readStateIndex:8665; appliedIndex:8663; }","duration":"272.134786ms","start":"2026-04-19T13:45:04.832533Z","end":"2026-04-19T13:45:05.104668Z","steps":["trace[1585247891] 'read index received' (duration: 130.759811ms)","trace[1585247891] 'applied index is now lower than readState.Index' (duration: 141.373995ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:45:05.104902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.346281ms","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-19T13:45:05.104935Z","caller":"traceutil/trace.go:171","msg":"trace[749706731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8372; }","duration":"272.449623ms","start":"2026-04-19T13:45:04.832476Z","end":"2026-04-19T13:45:05.104926Z","steps":["trace[749706731] 'agreement among raft nodes before linearized reading' (duration: 272.28746ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:05.105053Z","caller":"traceutil/trace.go:171","msg":"trace[1795839245] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"335.331229ms","start":"2026-04-19T13:45:04.76971Z","end":"2026-04-19T13:45:05.105041Z","steps":["trace[1795839245] 'process raft request' (duration: 334.802257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:05.105144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:45:04.769679Z","time spent":"335.406501ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:45:19.201223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.267715ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-19T13:45:19.20136Z","caller":"traceutil/trace.go:171","msg":"trace[508920437] linearizableReadLoop","detail":"{readStateIndex:8788; appliedIndex:8787; }","duration":"159.92946ms","start":"2026-04-19T13:45:19.041406Z","end":"2026-04-19T13:45:19.201336Z","steps":["trace[508920437] 'read index received' (duration: 93.223µs)","trace[508920437] 'applied index is now lower than readState.Index' (duration: 159.834217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:45:19.202175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.74202ms","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-19T13:45:19.202387Z","caller":"traceutil/trace.go:171","msg":"trace[449095111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8489; }","duration":"161.022606ms","start":"2026-04-19T13:45:19.041338Z","end":"2026-04-19T13:45:19.202361Z","steps":["trace[449095111] 'agreement among raft nodes before linearized reading' (duration: 160.632477ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:19.92494Z","caller":"traceutil/trace.go:171","msg":"trace[2028905378] linearizableReadLoop","detail":"{readStateIndex:8795; appliedIndex:8794; }","duration":"151.395776ms","start":"2026-04-19T13:45:19.773503Z","end":"2026-04-19T13:45:19.924899Z","steps":["trace[2028905378] 'read index received' (duration: 151.032636ms)","trace[2028905378] 'applied index is now lower than readState.Index' (duration: 361.2µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:19.925027Z","caller":"traceutil/trace.go:171","msg":"trace[601753972] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"239.23031ms","start":"2026-04-19T13:45:19.685756Z","end":"2026-04-19T13:45:19.924986Z","steps":["trace[601753972] 'process raft request' (duration: 238.923572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:19.925483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.964749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-19T13:45:19.925563Z","caller":"traceutil/trace.go:171","msg":"trace[696288359] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8496; }","duration":"152.087792ms","start":"2026-04-19T13:45:19.773446Z","end":"2026-04-19T13:45:19.925534Z","steps":["trace[696288359] 'agreement among raft nodes before linearized reading' (duration: 151.626241ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:20.61568Z","caller":"traceutil/trace.go:171","msg":"trace[675226927] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"136.905938ms","start":"2026-04-19T13:45:20.478739Z","end":"2026-04-19T13:45:20.615645Z","steps":["trace[675226927] 'process raft request' (duration: 59.31173ms)","trace[675226927] 'compare' (duration: 77.315132ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:46:09.873324Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002482.wal"} {"level":"info","ts":"2026-04-19T13:47:29.042934Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"95f079218969d088","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-19T13:47:29.049971Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-19T13:47:29.050087Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-19T13:49:30.260856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8137} {"level":"info","ts":"2026-04-19T13:49:30.337754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8137,"took":"74.252686ms","hash":949934931,"current-db-size-bytes":57106432,"current-db-size":"57 MB","current-db-size-in-use-bytes":24829952,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-19T13:49:30.337887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":949934931,"revision":8137,"compact-revision":5916} {"level":"info","ts":"2026-04-19T13:54:30.267084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10565} {"level":"info","ts":"2026-04-19T13:54:30.342108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10565,"took":"73.098895ms","hash":123973353,"current-db-size-bytes":57106432,"current-db-size":"57 MB","current-db-size-in-use-bytes":20189184,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-19T13:54:30.342173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123973353,"revision":10565,"compact-revision":8137}