{"level":"warn","ts":"2026-06-27T19:01:51.169533Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-27T19:01:51.169624Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-06-27T19:01:51.169688Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-27T19:01:51.169703Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-06-27T19:01:51.169723Z","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-06-27T19:01:51.1707Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-06-27T19:01:51.171062Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-06-27T19:01:51.174305Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.112212ms"} {"level":"info","ts":"2026-06-27T19:01:51.186323Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-06-27T19:01:51.186486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-06-27T19:01:51.186548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-06-27T19:01:51.186585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-27T19:01:51.18661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-06-27T19:01:51.186684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-06-27T19:01:51.193574Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-27T19:01:51.201095Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-27T19:01:51.202732Z","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-06-27T19:01:51.20502Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-27T19:01:51.205108Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-27T19:01:51.205216Z","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-06-27T19:01:51.205248Z","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-06-27T19:01:51.205256Z","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-06-27T19:01:51.20636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-06-27T19:01:51.20673Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-06-27T19:01:51.208176Z","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-06-27T19:01:51.208393Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-06-27T19:01:51.208433Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-06-27T19:01:51.208452Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-27T19:01:51.208506Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-27T19:01:52.087605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-06-27T19:01:52.087694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-06-27T19:01:52.087719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-06-27T19:01:52.08774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-06-27T19:01:52.087751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-06-27T19:01:52.087767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-06-27T19:01:52.087835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-06-27T19:01:52.089688Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-06-27T19:01:52.08986Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-27T19:01:52.090047Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T19:01:52.09013Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-27T19:01:52.09019Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-27T19:01:52.091149Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T19:01:52.09128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T19:01:52.091307Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T19:01:52.092756Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-27T19:01:52.08977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-27T19:01:52.099177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-06-27T19:02:11.543819Z","caller":"traceutil/trace.go:171","msg":"trace[153383280] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"155.662133ms","start":"2026-06-27T19:02:11.388125Z","end":"2026-06-27T19:02:11.543787Z","steps":["trace[153383280] 'process raft request' (duration: 155.489809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:02:34.346878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.538712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fnlb2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-27T19:02:34.347051Z","caller":"traceutil/trace.go:171","msg":"trace[197193801] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fnlb2; range_end:; response_count:1; response_revision:588; }","duration":"106.758037ms","start":"2026-06-27T19:02:34.240264Z","end":"2026-06-27T19:02:34.347022Z","steps":["trace[197193801] 'range keys from in-memory index tree' (duration: 106.332877ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:02:34.588312Z","caller":"traceutil/trace.go:171","msg":"trace[1042589325] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"119.919218ms","start":"2026-06-27T19:02:34.468361Z","end":"2026-06-27T19:02:34.588281Z","steps":["trace[1042589325] 'process raft request' (duration: 119.109278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:02:34.851453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.147744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T19:02:34.851597Z","caller":"traceutil/trace.go:171","msg":"trace[463944941] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"376.49166ms","start":"2026-06-27T19:02:34.475074Z","end":"2026-06-27T19:02:34.851566Z","steps":["trace[463944941] 'process raft request' (duration: 218.597449ms)","trace[463944941] 'compare' (duration: 156.992559ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:02:34.851648Z","caller":"traceutil/trace.go:171","msg":"trace[194780504] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"202.277551ms","start":"2026-06-27T19:02:34.649357Z","end":"2026-06-27T19:02:34.851635Z","steps":["trace[194780504] 'process raft request' (duration: 202.22144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:02:34.851719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T19:02:34.475045Z","time spent":"376.611303ms","remote":"127.0.0.1:50866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T19:02:34.851719Z","caller":"traceutil/trace.go:171","msg":"trace[1686263195] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"241.982647ms","start":"2026-06-27T19:02:34.609711Z","end":"2026-06-27T19:02:34.851694Z","steps":["trace[1686263195] 'read index received' (duration: 83.957432ms)","trace[1686263195] 'applied index is now lower than readState.Index' (duration: 158.022555ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T19:02:34.851919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.200542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T19:02:34.85221Z","caller":"traceutil/trace.go:171","msg":"trace[642287032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"242.496999ms","start":"2026-06-27T19:02:34.60966Z","end":"2026-06-27T19:02:34.852157Z","steps":["trace[642287032] 'agreement among raft nodes before linearized reading' (duration: 242.199062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:02:35.368791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.45254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-27T19:02:35.368878Z","caller":"traceutil/trace.go:171","msg":"trace[1191930215] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:592; }","duration":"189.587453ms","start":"2026-06-27T19:02:35.17927Z","end":"2026-06-27T19:02:35.368858Z","steps":["trace[1191930215] 'range keys from in-memory index tree' (duration: 189.354637ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:02:35.369251Z","caller":"traceutil/trace.go:171","msg":"trace[420292046] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"119.829336ms","start":"2026-06-27T19:02:35.249393Z","end":"2026-06-27T19:02:35.369223Z","steps":["trace[420292046] 'process raft request' (duration: 117.94532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:02:50.740697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.332039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-27T19:02:50.740864Z","caller":"traceutil/trace.go:171","msg":"trace[1968437808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"208.525364ms","start":"2026-06-27T19:02:50.532294Z","end":"2026-06-27T19:02:50.740819Z","steps":["trace[1968437808] 'range keys from in-memory index tree' (duration: 208.119844ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:04:06.106279Z","caller":"traceutil/trace.go:171","msg":"trace[1583895458] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"273.365827ms","start":"2026-06-27T19:04:05.832901Z","end":"2026-06-27T19:04:06.106267Z","steps":["trace[1583895458] 'process raft request' (duration: 273.146522ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:04:06.106255Z","caller":"traceutil/trace.go:171","msg":"trace[313268823] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"273.408408ms","start":"2026-06-27T19:04:05.832805Z","end":"2026-06-27T19:04:06.106213Z","steps":["trace[313268823] 'process raft request' (duration: 273.020129ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:04:06.106258Z","caller":"traceutil/trace.go:171","msg":"trace[1471620676] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"272.444375ms","start":"2026-06-27T19:04:05.833784Z","end":"2026-06-27T19:04:06.106229Z","steps":["trace[1471620676] 'process raft request' (duration: 272.368534ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:04:06.422994Z","caller":"traceutil/trace.go:171","msg":"trace[647144566] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"197.727555ms","start":"2026-06-27T19:04:06.22523Z","end":"2026-06-27T19:04:06.422957Z","steps":["trace[647144566] 'process raft request' (duration: 115.81797ms)","trace[647144566] 'compare' (duration: 81.811342ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:04:55.354566Z","caller":"traceutil/trace.go:171","msg":"trace[734993642] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"115.05442ms","start":"2026-06-27T19:04:55.239483Z","end":"2026-06-27T19:04:55.354537Z","steps":["trace[734993642] 'process raft request' (duration: 114.892106ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:04:55.970987Z","caller":"traceutil/trace.go:171","msg":"trace[380499692] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"151.439478ms","start":"2026-06-27T19:04:55.819515Z","end":"2026-06-27T19:04:55.970954Z","steps":["trace[380499692] 'process raft request' (duration: 151.249994ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:04:56.080163Z","caller":"traceutil/trace.go:171","msg":"trace[217501271] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2227; }","duration":"236.7286ms","start":"2026-06-27T19:04:55.843397Z","end":"2026-06-27T19:04:56.080125Z","steps":["trace[217501271] 'read index received' (duration: 127.436771ms)","trace[217501271] 'applied index is now lower than readState.Index' (duration: 109.290149ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:04:56.080397Z","caller":"traceutil/trace.go:171","msg":"trace[624430341] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"257.810246ms","start":"2026-06-27T19:04:55.822542Z","end":"2026-06-27T19:04:56.080352Z","steps":["trace[624430341] 'process raft request' (duration: 256.957725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:04:56.081139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.690675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-27T19:04:56.081215Z","caller":"traceutil/trace.go:171","msg":"trace[540558164] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2151; }","duration":"237.840288ms","start":"2026-06-27T19:04:55.843335Z","end":"2026-06-27T19:04:56.081175Z","steps":["trace[540558164] 'agreement among raft nodes before linearized reading' (duration: 236.961296ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:06:19.051563Z","caller":"traceutil/trace.go:171","msg":"trace[1984537091] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"183.046588ms","start":"2026-06-27T19:06:18.868493Z","end":"2026-06-27T19:06:19.05154Z","steps":["trace[1984537091] 'process raft request' (duration: 182.847003ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:06:19.136917Z","caller":"traceutil/trace.go:171","msg":"trace[1178574209] linearizableReadLoop","detail":"{readStateIndex:2895; appliedIndex:2893; }","duration":"169.6626ms","start":"2026-06-27T19:06:18.967226Z","end":"2026-06-27T19:06:19.136889Z","steps":["trace[1178574209] 'read index received' (duration: 84.204309ms)","trace[1178574209] 'applied index is now lower than readState.Index' (duration: 85.456371ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:06:19.136932Z","caller":"traceutil/trace.go:171","msg":"trace[690971989] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"267.596087ms","start":"2026-06-27T19:06:18.869307Z","end":"2026-06-27T19:06:19.136903Z","steps":["trace[690971989] 'process raft request' (duration: 263.083626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:06:19.137213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.962768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-27T19:06:19.137309Z","caller":"traceutil/trace.go:171","msg":"trace[52742080] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2795; }","duration":"170.116022ms","start":"2026-06-27T19:06:18.967172Z","end":"2026-06-27T19:06:19.137288Z","steps":["trace[52742080] 'agreement among raft nodes before linearized reading' (duration: 169.830195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:08:43.926573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.190408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T19:08:43.926912Z","caller":"traceutil/trace.go:171","msg":"trace[1613853864] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:3758; }","duration":"123.581357ms","start":"2026-06-27T19:08:43.803304Z","end":"2026-06-27T19:08:43.926886Z","steps":["trace[1613853864] 'count revisions from in-memory index tree' (duration: 123.098976ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:08:44.035237Z","caller":"traceutil/trace.go:171","msg":"trace[2040966602] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"103.874926ms","start":"2026-06-27T19:08:43.931332Z","end":"2026-06-27T19:08:44.035207Z","steps":["trace[2040966602] 'process raft request' (duration: 103.691012ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:08:44.059001Z","caller":"traceutil/trace.go:171","msg":"trace[2144384017] transaction","detail":"{read_only:false; response_revision:3760; number_of_response:1; }","duration":"127.611115ms","start":"2026-06-27T19:08:43.931361Z","end":"2026-06-27T19:08:44.058972Z","steps":["trace[2144384017] 'process raft request' (duration: 127.415611ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:08:44.059125Z","caller":"traceutil/trace.go:171","msg":"trace[1394173625] transaction","detail":"{read_only:false; response_revision:3761; number_of_response:1; }","duration":"127.666257ms","start":"2026-06-27T19:08:43.931436Z","end":"2026-06-27T19:08:44.059103Z","steps":["trace[1394173625] 'process raft request' (duration: 127.491872ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:10:08.023394Z","caller":"traceutil/trace.go:171","msg":"trace[989628844] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"100.977174ms","start":"2026-06-27T19:10:07.922384Z","end":"2026-06-27T19:10:08.023361Z","steps":["trace[989628844] 'process raft request' (duration: 100.881222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:10:08.10027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.265509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T19:10:08.100325Z","caller":"traceutil/trace.go:171","msg":"trace[1723590460] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:4949; }","duration":"126.33077ms","start":"2026-06-27T19:10:07.97398Z","end":"2026-06-27T19:10:08.100311Z","steps":["trace[1723590460] 'agreement among raft nodes before linearized reading' (duration: 126.211417ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:10:19.242273Z","caller":"traceutil/trace.go:171","msg":"trace[452373308] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"100.79376ms","start":"2026-06-27T19:10:19.141455Z","end":"2026-06-27T19:10:19.242249Z","steps":["trace[452373308] 'process raft request' (duration: 100.581285ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:10:19.752854Z","caller":"traceutil/trace.go:171","msg":"trace[2068767476] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"118.006248ms","start":"2026-06-27T19:10:19.634823Z","end":"2026-06-27T19:10:19.752829Z","steps":["trace[2068767476] 'process raft request' (duration: 117.824503ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:10:19.753593Z","caller":"traceutil/trace.go:171","msg":"trace[1919649131] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"114.314698ms","start":"2026-06-27T19:10:19.639259Z","end":"2026-06-27T19:10:19.753573Z","steps":["trace[1919649131] 'process raft request' (duration: 114.210436ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:10:27.325736Z","caller":"traceutil/trace.go:171","msg":"trace[869417189] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"171.342855ms","start":"2026-06-27T19:10:27.154366Z","end":"2026-06-27T19:10:27.325709Z","steps":["trace[869417189] 'process raft request' (duration: 171.200101ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:10:27.449981Z","caller":"traceutil/trace.go:171","msg":"trace[1899096013] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"168.993777ms","start":"2026-06-27T19:10:27.280958Z","end":"2026-06-27T19:10:27.449952Z","steps":["trace[1899096013] 'process raft request' (duration: 117.945236ms)","trace[1899096013] 'compare' (duration: 50.882407ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:11:52.124039Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-06-27T19:11:52.197761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"71.93479ms","hash":4091032475,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":34582528,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-27T19:11:52.19782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4091032475,"revision":2977,"compact-revision":-1} {"level":"warn","ts":"2026-06-27T19:16:42.787682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.073731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T19:16:42.787833Z","caller":"traceutil/trace.go:171","msg":"trace[1886512299] linearizableReadLoop","detail":"{readStateIndex:8513; appliedIndex:8511; }","duration":"178.080696ms","start":"2026-06-27T19:16:42.609742Z","end":"2026-06-27T19:16:42.787823Z","steps":["trace[1886512299] 'read index received' (duration: 29.888471ms)","trace[1886512299] 'applied index is now lower than readState.Index' (duration: 148.191535ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:16:42.787881Z","caller":"traceutil/trace.go:171","msg":"trace[978182590] transaction","detail":"{read_only:false; response_revision:8230; number_of_response:1; }","duration":"406.335611ms","start":"2026-06-27T19:16:42.381541Z","end":"2026-06-27T19:16:42.787876Z","steps":["trace[978182590] 'process raft request' (duration: 406.226478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:16:42.787916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T19:16:42.381531Z","time spent":"406.367591ms","remote":"127.0.0.1:50866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T19:16:42.788003Z","caller":"traceutil/trace.go:171","msg":"trace[1367767371] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"407.598631ms","start":"2026-06-27T19:16:42.380377Z","end":"2026-06-27T19:16:42.787976Z","steps":["trace[1367767371] 'process raft request' (duration: 259.152091ms)","trace[1367767371] 'compare' (duration: 147.865047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T19:16:42.788036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.28514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T19:16:42.788078Z","caller":"traceutil/trace.go:171","msg":"trace[1373960698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8230; }","duration":"178.354032ms","start":"2026-06-27T19:16:42.609711Z","end":"2026-06-27T19:16:42.788065Z","steps":["trace[1373960698] 'agreement among raft nodes before linearized reading' (duration: 178.26839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:16:42.788135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T19:16:42.380362Z","time spent":"407.703564ms","remote":"127.0.0.1:50866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T19:16:52.131377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6115} {"level":"info","ts":"2026-06-27T19:16:52.229561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6115,"took":"96.2028ms","hash":19520536,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":23384064,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-27T19:16:52.229617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":19520536,"revision":6115,"compact-revision":2977} {"level":"info","ts":"2026-06-27T19:16:52.976897Z","caller":"traceutil/trace.go:171","msg":"trace[599958692] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"123.739965ms","start":"2026-06-27T19:16:52.853125Z","end":"2026-06-27T19:16:52.976865Z","steps":["trace[599958692] 'process raft request' (duration: 116.308295ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:17:23.125529Z","caller":"traceutil/trace.go:171","msg":"trace[687036503] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"189.417599ms","start":"2026-06-27T19:17:22.936088Z","end":"2026-06-27T19:17:23.125505Z","steps":["trace[687036503] 'process raft request' (duration: 189.258605ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:17:23.127107Z","caller":"traceutil/trace.go:171","msg":"trace[1264361844] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"131.194005ms","start":"2026-06-27T19:17:22.995898Z","end":"2026-06-27T19:17:23.127092Z","steps":["trace[1264361844] 'process raft request' (duration: 130.854796ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:17:23.126989Z","caller":"traceutil/trace.go:171","msg":"trace[1219678721] transaction","detail":"{read_only:false; response_revision:8499; number_of_response:1; }","duration":"135.332274ms","start":"2026-06-27T19:17:22.991641Z","end":"2026-06-27T19:17:23.126973Z","steps":["trace[1219678721] 'process raft request' (duration: 135.025786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:17:35.589349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.682849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T19:17:35.589447Z","caller":"traceutil/trace.go:171","msg":"trace[122961196] linearizableReadLoop","detail":"{readStateIndex:8911; appliedIndex:8910; }","duration":"154.943861ms","start":"2026-06-27T19:17:35.434489Z","end":"2026-06-27T19:17:35.589433Z","steps":["trace[122961196] 'read index received' (duration: 47.941µs)","trace[122961196] 'applied index is now lower than readState.Index' (duration: 154.89482ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:17:35.589577Z","caller":"traceutil/trace.go:171","msg":"trace[191260562] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"288.91196ms","start":"2026-06-27T19:17:35.300634Z","end":"2026-06-27T19:17:35.589546Z","steps":["trace[191260562] 'process raft request' (duration: 107.953964ms)","trace[191260562] 'compare' (duration: 180.503265ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T19:17:35.58989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.406273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-27T19:17:35.589929Z","caller":"traceutil/trace.go:171","msg":"trace[1444388652] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8611; }","duration":"155.482135ms","start":"2026-06-27T19:17:35.434437Z","end":"2026-06-27T19:17:35.589919Z","steps":["trace[1444388652] 'agreement among raft nodes before linearized reading' (duration: 155.038044ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:17:36.016999Z","caller":"traceutil/trace.go:171","msg":"trace[2127361027] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"154.570092ms","start":"2026-06-27T19:17:35.862402Z","end":"2026-06-27T19:17:36.016972Z","steps":["trace[2127361027] 'process raft request' (duration: 78.507325ms)","trace[2127361027] 'compare' (duration: 75.934904ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:17:50.935221Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000235e.wal"} {"level":"info","ts":"2026-06-27T19:19:35.186096Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-27T19:19:35.191756Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-27T19:19:35.191888Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-27T19:21:52.138698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8280} {"level":"info","ts":"2026-06-27T19:21:52.21657Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8280,"took":"75.836393ms","hash":3094655226,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":24133632,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-27T19:21:52.216648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3094655226,"revision":8280,"compact-revision":6115} {"level":"info","ts":"2026-06-27T19:24:18.917963Z","caller":"traceutil/trace.go:171","msg":"trace[1996279328] transaction","detail":"{read_only:false; response_revision:11729; number_of_response:1; }","duration":"179.679283ms","start":"2026-06-27T19:24:18.738256Z","end":"2026-06-27T19:24:18.917935Z","steps":["trace[1996279328] 'process raft request' (duration: 179.506939ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:24:19.006872Z","caller":"traceutil/trace.go:171","msg":"trace[1075348258] linearizableReadLoop","detail":"{readStateIndex:12124; appliedIndex:12122; }","duration":"167.852717ms","start":"2026-06-27T19:24:18.838988Z","end":"2026-06-27T19:24:19.00684Z","steps":["trace[1075348258] 'read index received' (duration: 78.820525ms)","trace[1075348258] 'applied index is now lower than readState.Index' (duration: 89.030912ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:24:19.007028Z","caller":"traceutil/trace.go:171","msg":"trace[2145944777] transaction","detail":"{read_only:false; response_revision:11730; number_of_response:1; }","duration":"180.143575ms","start":"2026-06-27T19:24:18.82687Z","end":"2026-06-27T19:24:19.007014Z","steps":["trace[2145944777] 'process raft request' (duration: 128.287592ms)","trace[2145944777] 'compare' (duration: 51.527965ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T19:24:19.007201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.189078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T19:24:19.007241Z","caller":"traceutil/trace.go:171","msg":"trace[184172841] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11730; }","duration":"166.28172ms","start":"2026-06-27T19:24:18.84095Z","end":"2026-06-27T19:24:19.007232Z","steps":["trace[184172841] 'agreement among raft nodes before linearized reading' (duration: 166.207338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:24:19.007229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.239637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T19:24:19.007255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.080045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T19:24:19.007296Z","caller":"traceutil/trace.go:171","msg":"trace[1794593694] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11730; }","duration":"168.34275ms","start":"2026-06-27T19:24:18.838933Z","end":"2026-06-27T19:24:19.007276Z","steps":["trace[1794593694] 'agreement among raft nodes before linearized reading' (duration: 168.243827ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:24:19.007324Z","caller":"traceutil/trace.go:171","msg":"trace[1827710531] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11730; }","duration":"166.174397ms","start":"2026-06-27T19:24:18.841133Z","end":"2026-06-27T19:24:19.007308Z","steps":["trace[1827710531] 'agreement among raft nodes before linearized reading' (duration: 166.065284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:24:19.007323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.946531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-06-27T19:24:19.007354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.31469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T19:24:19.007395Z","caller":"traceutil/trace.go:171","msg":"trace[1408650932] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11730; }","duration":"166.047054ms","start":"2026-06-27T19:24:18.841329Z","end":"2026-06-27T19:24:19.007376Z","steps":["trace[1408650932] 'agreement among raft nodes before linearized reading' (duration: 165.845029ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:24:19.007443Z","caller":"traceutil/trace.go:171","msg":"trace[1289052731] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11730; }","duration":"166.457553ms","start":"2026-06-27T19:24:18.840964Z","end":"2026-06-27T19:24:19.007422Z","steps":["trace[1289052731] 'agreement among raft nodes before linearized reading' (duration: 166.297139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:24:19.007476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.279109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-27T19:24:19.007505Z","caller":"traceutil/trace.go:171","msg":"trace[2086826410] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11730; }","duration":"166.31942ms","start":"2026-06-27T19:24:18.841178Z","end":"2026-06-27T19:24:19.007497Z","steps":["trace[2086826410] 'agreement among raft nodes before linearized reading' (duration: 166.195657ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:24:19.994854Z","caller":"traceutil/trace.go:171","msg":"trace[1837999016] transaction","detail":"{read_only:false; response_revision:11736; number_of_response:1; }","duration":"188.514879ms","start":"2026-06-27T19:24:19.806319Z","end":"2026-06-27T19:24:19.994834Z","steps":["trace[1837999016] 'process raft request' (duration: 188.362515ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:26:52.144773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10502} {"level":"info","ts":"2026-06-27T19:26:52.22083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10502,"took":"73.50274ms","hash":3341699756,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":26365952,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-27T19:26:52.220882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3341699756,"revision":10502,"compact-revision":8280} {"level":"info","ts":"2026-06-27T19:30:22.709687Z","caller":"traceutil/trace.go:171","msg":"trace[1254785303] transaction","detail":"{read_only:false; response_revision:14196; number_of_response:1; }","duration":"113.849756ms","start":"2026-06-27T19:30:22.595806Z","end":"2026-06-27T19:30:22.709655Z","steps":["trace[1254785303] 'process raft request' (duration: 113.603411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:30:40.877379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.822847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-27T19:30:40.878058Z","caller":"traceutil/trace.go:171","msg":"trace[1406856320] transaction","detail":"{read_only:false; response_revision:14294; number_of_response:1; }","duration":"109.935709ms","start":"2026-06-27T19:30:40.768083Z","end":"2026-06-27T19:30:40.878019Z","steps":["trace[1406856320] 'process raft request' (duration: 109.450376ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:30:56.456877Z","caller":"traceutil/trace.go:171","msg":"trace[542265364] transaction","detail":"{read_only:false; response_revision:14391; number_of_response:1; }","duration":"194.983912ms","start":"2026-06-27T19:30:56.261865Z","end":"2026-06-27T19:30:56.456849Z","steps":["trace[542265364] 'process raft request' (duration: 194.792728ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:30:56.481056Z","caller":"traceutil/trace.go:171","msg":"trace[357941527] linearizableReadLoop","detail":"{readStateIndex:14880; appliedIndex:14878; }","duration":"162.878162ms","start":"2026-06-27T19:30:56.318158Z","end":"2026-06-27T19:30:56.481036Z","steps":["trace[357941527] 'read index received' (duration: 138.351748ms)","trace[357941527] 'applied index is now lower than readState.Index' (duration: 24.525694ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:30:56.481159Z","caller":"traceutil/trace.go:171","msg":"trace[1352156134] transaction","detail":"{read_only:false; response_revision:14392; number_of_response:1; }","duration":"164.252865ms","start":"2026-06-27T19:30:56.316877Z","end":"2026-06-27T19:30:56.48113Z","steps":["trace[1352156134] 'process raft request' (duration: 164.06213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:30:56.481218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.043226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T19:30:56.481243Z","caller":"traceutil/trace.go:171","msg":"trace[633355727] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:14392; }","duration":"163.087287ms","start":"2026-06-27T19:30:56.318149Z","end":"2026-06-27T19:30:56.481236Z","steps":["trace[633355727] 'agreement among raft nodes before linearized reading' (duration: 162.966514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:30:56.687099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.259418ms","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-06-27T19:30:56.687185Z","caller":"traceutil/trace.go:171","msg":"trace[852928057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14392; }","duration":"174.407732ms","start":"2026-06-27T19:30:56.512759Z","end":"2026-06-27T19:30:56.687167Z","steps":["trace[852928057] 'range keys from in-memory index tree' (duration: 174.063923ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:31:02.688761Z","caller":"traceutil/trace.go:171","msg":"trace[1110099550] transaction","detail":"{read_only:false; response_revision:14435; number_of_response:1; }","duration":"215.437768ms","start":"2026-06-27T19:31:02.473296Z","end":"2026-06-27T19:31:02.688733Z","steps":["trace[1110099550] 'process raft request' (duration: 215.272704ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:31:02.698825Z","caller":"traceutil/trace.go:171","msg":"trace[1861939368] transaction","detail":"{read_only:false; response_revision:14436; number_of_response:1; }","duration":"201.010908ms","start":"2026-06-27T19:31:02.497781Z","end":"2026-06-27T19:31:02.698792Z","steps":["trace[1861939368] 'process raft request' (duration: 200.906876ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:31:52.152597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12764} {"level":"info","ts":"2026-06-27T19:31:52.230734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12764,"took":"75.560394ms","hash":1885238741,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":28880896,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-27T19:31:52.230811Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1885238741,"revision":12764,"compact-revision":10502} {"level":"info","ts":"2026-06-27T19:32:39.259388Z","caller":"traceutil/trace.go:171","msg":"trace[1420287593] transaction","detail":"{read_only:false; response_revision:15481; number_of_response:1; }","duration":"113.241699ms","start":"2026-06-27T19:32:39.146119Z","end":"2026-06-27T19:32:39.259361Z","steps":["trace[1420287593] 'process raft request' (duration: 113.078325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:32:44.898633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.784065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-v4j28\" ","response":"range_response_count:1 size:22100"} {"level":"warn","ts":"2026-06-27T19:32:44.898727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.307963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-27T19:32:44.898808Z","caller":"traceutil/trace.go:171","msg":"trace[1468899708] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15509; }","duration":"217.429576ms","start":"2026-06-27T19:32:44.681365Z","end":"2026-06-27T19:32:44.898794Z","steps":["trace[1468899708] 'range keys from in-memory index tree' (duration: 217.192861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:32:44.899057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.1071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-27T19:32:44.899106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.903094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-27T19:32:44.899164Z","caller":"traceutil/trace.go:171","msg":"trace[1956356405] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15509; }","duration":"217.267963ms","start":"2026-06-27T19:32:44.681871Z","end":"2026-06-27T19:32:44.899139Z","steps":["trace[1956356405] 'range keys from in-memory index tree' (duration: 217.028647ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:32:44.899201Z","caller":"traceutil/trace.go:171","msg":"trace[844105421] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15509; }","duration":"210.009456ms","start":"2026-06-27T19:32:44.689171Z","end":"2026-06-27T19:32:44.899181Z","steps":["trace[844105421] 'range keys from in-memory index tree' (duration: 209.786671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:32:44.899568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.88679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737310"} {"level":"info","ts":"2026-06-27T19:32:44.899626Z","caller":"traceutil/trace.go:171","msg":"trace[2131948792] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15509; }","duration":"227.970792ms","start":"2026-06-27T19:32:44.671642Z","end":"2026-06-27T19:32:44.899613Z","steps":["trace[2131948792] 'range keys from in-memory index tree' (duration: 227.258904ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:32:44.898755Z","caller":"traceutil/trace.go:171","msg":"trace[1386838774] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-v4j28; range_end:; response_count:1; response_revision:15509; }","duration":"196.967909ms","start":"2026-06-27T19:32:44.701763Z","end":"2026-06-27T19:32:44.898731Z","steps":["trace[1386838774] 'range keys from in-memory index tree' (duration: 196.636932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:32:46.009173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.207294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T19:32:46.00929Z","caller":"traceutil/trace.go:171","msg":"trace[1412939260] linearizableReadLoop","detail":"{readStateIndex:16043; appliedIndex:16041; }","duration":"255.764536ms","start":"2026-06-27T19:32:45.753508Z","end":"2026-06-27T19:32:46.009273Z","steps":["trace[1412939260] 'read index received' (duration: 43.152837ms)","trace[1412939260] 'applied index is now lower than readState.Index' (duration: 212.610349ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T19:32:46.009457Z","caller":"traceutil/trace.go:171","msg":"trace[598674099] transaction","detail":"{read_only:false; response_revision:15518; number_of_response:1; }","duration":"276.416897ms","start":"2026-06-27T19:32:45.733027Z","end":"2026-06-27T19:32:46.009444Z","steps":["trace[598674099] 'process raft request' (duration: 144.841175ms)","trace[598674099] 'compare' (duration: 130.990438ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T19:32:46.009708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.439695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-06-27T19:32:46.009822Z","caller":"traceutil/trace.go:171","msg":"trace[1240921994] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15518; }","duration":"151.599649ms","start":"2026-06-27T19:32:45.858199Z","end":"2026-06-27T19:32:46.009799Z","steps":["trace[1240921994] 'agreement among raft nodes before linearized reading' (duration: 151.353803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T19:32:46.00976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.257298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-24zpc\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-06-27T19:32:46.010014Z","caller":"traceutil/trace.go:171","msg":"trace[413947470] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-24zpc; range_end:; response_count:1; response_revision:15518; }","duration":"256.523285ms","start":"2026-06-27T19:32:45.753464Z","end":"2026-06-27T19:32:46.009988Z","steps":["trace[413947470] 'agreement among raft nodes before linearized reading' (duration: 256.174706ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T19:36:52.158565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14913} {"level":"info","ts":"2026-06-27T19:36:52.259144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14913,"took":"97.327044ms","hash":309105953,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":31145984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-27T19:36:52.259207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":309105953,"revision":14913,"compact-revision":12764} {"level":"info","ts":"2026-06-27T19:41:52.166072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17405} {"level":"info","ts":"2026-06-27T19:41:52.258936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17405,"took":"89.76448ms","hash":1476113855,"current-db-size-bytes":59719680,"current-db-size":"60 MB","current-db-size-in-use-bytes":28999680,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-27T19:41:52.259002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1476113855,"revision":17405,"compact-revision":14913} {"level":"info","ts":"2026-06-27T19:42:34.753939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-27T19:42:34.759616Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-27T19:42:34.759743Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}