{"level":"warn","ts":"2026-04-08T12:56:43.101494Z","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-08T12:56:43.101585Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.117: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.117:2380","--initial-cluster=instance=https://199.19.213.117:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.117:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.117: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-08T12:56:43.101666Z","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-08T12:56:43.101677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-08T12:56:43.101697Z","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-08T12:56:43.102314Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"]} {"level":"info","ts":"2026-04-08T12:56:43.102407Z","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":8,"max-cpu-available":8,"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.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.117: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-08T12:56:43.106117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.499139ms"} {"level":"info","ts":"2026-04-08T12:56:43.113178Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f9f905057b5b268","cluster-id":"293172f52aeb3f03"} {"level":"info","ts":"2026-04-08T12:56:43.113307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T12:56:43.11335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 0"} {"level":"info","ts":"2026-04-08T12:56:43.113365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f9f905057b5b268 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T12:56:43.113374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 1"} {"level":"info","ts":"2026-04-08T12:56:43.113417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"warn","ts":"2026-04-08T12:56:43.119796Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T12:56:43.121086Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T12:56:43.122094Z","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-08T12:56:43.123877Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f9f905057b5b268","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T12:56:43.123999Z","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-08T12:56:43.124089Z","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-08T12:56:43.124102Z","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-08T12:56:43.12416Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f9f905057b5b268","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T12:56:43.12516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"info","ts":"2026-04-08T12:56:43.125436Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","added-peer-id":"5f9f905057b5b268","added-peer-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-04-08T12:56:43.1274Z","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-08T12:56:43.127517Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-08T12:56:43.127574Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-04-08T12:56:43.127697Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f9f905057b5b268","initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T12:56:43.127739Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T12:56:43.413806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T12:56:43.413987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T12:56:43.414052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgPreVoteResp from 5f9f905057b5b268 at term 1"} {"level":"info","ts":"2026-04-08T12:56:43.41414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became candidate at term 2"} {"level":"info","ts":"2026-04-08T12:56:43.41427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgVoteResp from 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-08T12:56:43.414342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became leader at term 2"} {"level":"info","ts":"2026-04-08T12:56:43.414379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f9f905057b5b268 elected leader 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-04-08T12:56:43.415405Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f9f905057b5b268","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.117:2379]}","request-path":"/0/members/5f9f905057b5b268/attributes","cluster-id":"293172f52aeb3f03","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T12:56:43.415431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:56:43.415876Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T12:56:43.415941Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T12:56:43.415814Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:56:43.416889Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:56:43.417166Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:56:43.417324Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:56:43.419342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.117:2379"} {"level":"info","ts":"2026-04-08T12:56:43.419493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:56:43.421743Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T12:57:19.831196Z","caller":"traceutil/trace.go:171","msg":"trace[1306111254] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"163.5957ms","start":"2026-04-08T12:57:19.667568Z","end":"2026-04-08T12:57:19.831164Z","steps":["trace[1306111254] 'process raft request' (duration: 163.215834ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:22.959191Z","caller":"traceutil/trace.go:171","msg":"trace[1130424593] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"131.802ms","start":"2026-04-08T12:57:22.827363Z","end":"2026-04-08T12:57:22.959165Z","steps":["trace[1130424593] 'read index received' (duration: 131.684516ms)","trace[1130424593] 'applied index is now lower than readState.Index' (duration: 116.334µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:22.959325Z","caller":"traceutil/trace.go:171","msg":"trace[601653732] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"265.742027ms","start":"2026-04-08T12:57:22.693571Z","end":"2026-04-08T12:57:22.959313Z","steps":["trace[601653732] 'process raft request' (duration: 265.411844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:22.959546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.131683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:22.959605Z","caller":"traceutil/trace.go:171","msg":"trace[1498760392] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:594; }","duration":"132.272599ms","start":"2026-04-08T12:57:22.82732Z","end":"2026-04-08T12:57:22.959593Z","steps":["trace[1498760392] 'agreement among raft nodes before linearized reading' (duration: 132.140964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:22.959877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.434256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:22.960018Z","caller":"traceutil/trace.go:171","msg":"trace[1850881981] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:594; }","duration":"132.599713ms","start":"2026-04-08T12:57:22.827395Z","end":"2026-04-08T12:57:22.959994Z","steps":["trace[1850881981] 'agreement among raft nodes before linearized reading' (duration: 132.378274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:22.961079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.177154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:57:22.96114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.220568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:57:22.961171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.224215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:22.961182Z","caller":"traceutil/trace.go:171","msg":"trace[99386632] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:594; }","duration":"101.301379ms","start":"2026-04-08T12:57:22.859865Z","end":"2026-04-08T12:57:22.961166Z","steps":["trace[99386632] 'agreement among raft nodes before linearized reading' (duration: 101.173624ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:22.961206Z","caller":"traceutil/trace.go:171","msg":"trace[1551075993] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:594; }","duration":"133.318141ms","start":"2026-04-08T12:57:22.827871Z","end":"2026-04-08T12:57:22.961189Z","steps":["trace[1551075993] 'agreement among raft nodes before linearized reading' (duration: 133.223947ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:22.961223Z","caller":"traceutil/trace.go:171","msg":"trace[671142629] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:594; }","duration":"101.307759ms","start":"2026-04-08T12:57:22.859901Z","end":"2026-04-08T12:57:22.961209Z","steps":["trace[671142629] 'agreement among raft nodes before linearized reading' (duration: 101.235586ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:21.945476Z","caller":"traceutil/trace.go:171","msg":"trace[2062164163] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"216.948661ms","start":"2026-04-08T12:58:21.72849Z","end":"2026-04-08T12:58:21.945438Z","steps":["trace[2062164163] 'process raft request' (duration: 142.212456ms)","trace[2062164163] 'compare' (duration: 73.515193ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:58:21.945714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.373798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:58:21.945968Z","caller":"traceutil/trace.go:171","msg":"trace[1746041202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"167.484011ms","start":"2026-04-08T12:58:21.778277Z","end":"2026-04-08T12:58:21.945761Z","steps":["trace[1746041202] 'agreement among raft nodes before linearized reading' (duration: 167.381018ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:58:21.945363Z","caller":"traceutil/trace.go:171","msg":"trace[2110594227] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"167.008589ms","start":"2026-04-08T12:58:21.778327Z","end":"2026-04-08T12:58:21.945336Z","steps":["trace[2110594227] 'read index received' (duration: 92.273783ms)","trace[2110594227] 'applied index is now lower than readState.Index' (duration: 74.733686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:32.149759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.323705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T12:59:32.149856Z","caller":"traceutil/trace.go:171","msg":"trace[440707913] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2068; }","duration":"100.485051ms","start":"2026-04-08T12:59:32.049352Z","end":"2026-04-08T12:59:32.149837Z","steps":["trace[440707913] 'count revisions from in-memory index tree' (duration: 100.200381ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:59.711863Z","caller":"traceutil/trace.go:171","msg":"trace[2136006246] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2881; }","duration":"110.579954ms","start":"2026-04-08T13:00:59.601258Z","end":"2026-04-08T13:00:59.711838Z","steps":["trace[2136006246] 'read index received' (duration: 110.435799ms)","trace[2136006246] 'applied index is now lower than readState.Index' (duration: 143.195µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:00:59.712159Z","caller":"traceutil/trace.go:171","msg":"trace[762411014] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"113.472179ms","start":"2026-04-08T13:00:59.598675Z","end":"2026-04-08T13:00:59.712147Z","steps":["trace[762411014] 'process raft request' (duration: 113.016683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:59.712449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.170225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-04-08T13:00:59.712498Z","caller":"traceutil/trace.go:171","msg":"trace[929928303] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2772; }","duration":"111.244408ms","start":"2026-04-08T13:00:59.601238Z","end":"2026-04-08T13:00:59.712482Z","steps":["trace[929928303] 'agreement among raft nodes before linearized reading' (duration: 111.088743ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:59.926447Z","caller":"traceutil/trace.go:171","msg":"trace[1543619508] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"163.708667ms","start":"2026-04-08T13:00:59.762714Z","end":"2026-04-08T13:00:59.926423Z","steps":["trace[1543619508] 'process raft request' (duration: 163.568131ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:59.926493Z","caller":"traceutil/trace.go:171","msg":"trace[1998201232] linearizableReadLoop","detail":"{readStateIndex:2884; appliedIndex:2884; }","duration":"148.580523ms","start":"2026-04-08T13:00:59.777852Z","end":"2026-04-08T13:00:59.926433Z","steps":["trace[1998201232] 'read index received' (duration: 148.554852ms)","trace[1998201232] 'applied index is now lower than readState.Index' (duration: 21.521µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:00:59.926602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.750319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:00:59.926663Z","caller":"traceutil/trace.go:171","msg":"trace[1928612863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2774; }","duration":"148.883974ms","start":"2026-04-08T13:00:59.777769Z","end":"2026-04-08T13:00:59.926653Z","steps":["trace[1928612863] 'agreement among raft nodes before linearized reading' (duration: 148.77142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:54.514342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.237093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:03:54.514428Z","caller":"traceutil/trace.go:171","msg":"trace[1695752080] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4693; }","duration":"115.403147ms","start":"2026-04-08T13:03:54.399007Z","end":"2026-04-08T13:03:54.51441Z","steps":["trace[1695752080] 'count revisions from in-memory index tree' (duration: 115.13052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:54.514533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.671922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T13:03:54.514584Z","caller":"traceutil/trace.go:171","msg":"trace[1261202322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4693; }","duration":"242.738703ms","start":"2026-04-08T13:03:54.271826Z","end":"2026-04-08T13:03:54.514565Z","steps":["trace[1261202322] 'range keys from in-memory index tree' (duration: 242.491367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:54.514321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.068266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:03:54.514688Z","caller":"traceutil/trace.go:171","msg":"trace[227312301] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4693; }","duration":"138.517847ms","start":"2026-04-08T13:03:54.376163Z","end":"2026-04-08T13:03:54.514681Z","steps":["trace[227312301] 'count revisions from in-memory index tree' (duration: 137.935832ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:56.820351Z","caller":"traceutil/trace.go:171","msg":"trace[885063564] linearizableReadLoop","detail":"{readStateIndex:4929; appliedIndex:4928; }","duration":"144.807936ms","start":"2026-04-08T13:03:56.67551Z","end":"2026-04-08T13:03:56.820318Z","steps":["trace[885063564] 'read index received' (duration: 118.0352ms)","trace[885063564] 'applied index is now lower than readState.Index' (duration: 26.771426ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:56.820761Z","caller":"traceutil/trace.go:171","msg":"trace[156573188] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"165.772466ms","start":"2026-04-08T13:03:56.654933Z","end":"2026-04-08T13:03:56.820706Z","steps":["trace[156573188] 'process raft request' (duration: 138.682892ms)","trace[156573188] 'compare' (duration: 26.567911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:03:56.821013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.489884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-08T13:03:56.821091Z","caller":"traceutil/trace.go:171","msg":"trace[92972141] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4718; }","duration":"145.642677ms","start":"2026-04-08T13:03:56.675428Z","end":"2026-04-08T13:03:56.821071Z","steps":["trace[92972141] 'agreement among raft nodes before linearized reading' (duration: 145.38539ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:07.63437Z","caller":"traceutil/trace.go:171","msg":"trace[368022589] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"111.328044ms","start":"2026-04-08T13:04:07.523016Z","end":"2026-04-08T13:04:07.634344Z","steps":["trace[368022589] 'process raft request' (duration: 97.930645ms)","trace[368022589] 'compare' (duration: 13.147283ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:07.940566Z","caller":"traceutil/trace.go:171","msg":"trace[1435566287] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"152.513864ms","start":"2026-04-08T13:04:07.788021Z","end":"2026-04-08T13:04:07.940535Z","steps":["trace[1435566287] 'process raft request' (duration: 80.073601ms)","trace[1435566287] 'compare' (duration: 72.232167ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:06:43.771798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3148} {"level":"info","ts":"2026-04-08T13:06:43.857726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3148,"took":"84.560353ms","hash":4201301086,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":30785536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-08T13:06:43.85779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4201301086,"revision":3148,"compact-revision":-1}