{"level":"warn","ts":"2026-04-18T17:07:05.17811Z","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-18T17:07:05.178247Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-18T17:07:05.178346Z","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-18T17:07:05.178361Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-18T17:07:05.17838Z","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-18T17:07:05.179209Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-04-18T17:07:05.179296Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-18T17:07:05.184097Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.521897ms"} {"level":"info","ts":"2026-04-18T17:07:05.191668Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-18T17:07:05.191767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T17:07:05.191797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-18T17:07:05.191812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T17:07:05.191824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-18T17:07:05.191874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-18T17:07:05.200237Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T17:07:05.203318Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T17:07:05.207026Z","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-18T17:07:05.213462Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T17:07:05.213773Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T17:07:05.213825Z","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-18T17:07:05.213927Z","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-18T17:07:05.21394Z","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-18T17:07:05.21712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-18T17:07:05.217683Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-18T17:07:05.218813Z","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-18T17:07:05.219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-18T17:07:05.219066Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-18T17:07:05.219098Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T17:07:05.219153Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T17:07:05.992369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T17:07:05.992507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T17:07:05.992571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-18T17:07:05.992604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-18T17:07:05.992616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-18T17:07:05.992634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-18T17:07:05.992649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-18T17:07:05.994055Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T17:07:05.995222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T17:07:05.995218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T17:07:05.995277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T17:07:05.995601Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T17:07:05.995677Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T17:07:05.998Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-18T17:07:05.999193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T17:07:06.002084Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T17:07:06.002226Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T17:07:06.00226Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T17:07:32.846177Z","caller":"traceutil/trace.go:171","msg":"trace[468946807] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"127.493065ms","start":"2026-04-18T17:07:32.718659Z","end":"2026-04-18T17:07:32.846152Z","steps":["trace[468946807] 'process raft request' (duration: 127.289149ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858092Z","caller":"traceutil/trace.go:171","msg":"trace[1673070710] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"135.733501ms","start":"2026-04-18T17:07:32.72233Z","end":"2026-04-18T17:07:32.858064Z","steps":["trace[1673070710] 'process raft request' (duration: 135.463547ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858203Z","caller":"traceutil/trace.go:171","msg":"trace[1548307166] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"135.677224ms","start":"2026-04-18T17:07:32.722497Z","end":"2026-04-18T17:07:32.858175Z","steps":["trace[1548307166] 'process raft request' (duration: 135.49173ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858276Z","caller":"traceutil/trace.go:171","msg":"trace[578794299] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"128.426752ms","start":"2026-04-18T17:07:32.729816Z","end":"2026-04-18T17:07:32.858243Z","steps":["trace[578794299] 'process raft request' (duration: 128.240919ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858406Z","caller":"traceutil/trace.go:171","msg":"trace[702241854] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"128.32971ms","start":"2026-04-18T17:07:32.730044Z","end":"2026-04-18T17:07:32.858373Z","steps":["trace[702241854] 'process raft request' (duration: 128.073978ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858447Z","caller":"traceutil/trace.go:171","msg":"trace[1942819465] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"127.507697ms","start":"2026-04-18T17:07:32.730909Z","end":"2026-04-18T17:07:32.858417Z","steps":["trace[1942819465] 'process raft request' (duration: 127.341086ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858505Z","caller":"traceutil/trace.go:171","msg":"trace[1203383511] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"125.912926ms","start":"2026-04-18T17:07:32.732551Z","end":"2026-04-18T17:07:32.858464Z","steps":["trace[1203383511] 'process raft request' (duration: 125.775809ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858562Z","caller":"traceutil/trace.go:171","msg":"trace[1041508641] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"123.418683ms","start":"2026-04-18T17:07:32.735105Z","end":"2026-04-18T17:07:32.858524Z","steps":["trace[1041508641] 'process raft request' (duration: 123.288837ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858606Z","caller":"traceutil/trace.go:171","msg":"trace[155539653] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"111.256714ms","start":"2026-04-18T17:07:32.74734Z","end":"2026-04-18T17:07:32.858596Z","steps":["trace[155539653] 'process raft request' (duration: 111.113566ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:32.858695Z","caller":"traceutil/trace.go:171","msg":"trace[1806942978] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"105.670593ms","start":"2026-04-18T17:07:32.753008Z","end":"2026-04-18T17:07:32.858678Z","steps":["trace[1806942978] 'process raft request' (duration: 105.524484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:07:39.611852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.392982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:07:39.612398Z","caller":"traceutil/trace.go:171","msg":"trace[1517408532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"349.563617ms","start":"2026-04-18T17:07:39.262396Z","end":"2026-04-18T17:07:39.611959Z","steps":["trace[1517408532] 'range keys from in-memory index tree' (duration: 348.968503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:07:39.612632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:07:39.262379Z","time spent":"350.234971ms","remote":"127.0.0.1:37986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T17:07:39.643235Z","caller":"traceutil/trace.go:171","msg":"trace[1565165887] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"270.222068ms","start":"2026-04-18T17:07:39.372805Z","end":"2026-04-18T17:07:39.643027Z","steps":["trace[1565165887] 'process raft request' (duration: 269.596594ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:55.122039Z","caller":"traceutil/trace.go:171","msg":"trace[1214463149] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"158.691665ms","start":"2026-04-18T17:07:54.963325Z","end":"2026-04-18T17:07:55.122016Z","steps":["trace[1214463149] 'process raft request' (duration: 154.739393ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:07:55.122336Z","caller":"traceutil/trace.go:171","msg":"trace[1193546056] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"155.633575ms","start":"2026-04-18T17:07:54.966663Z","end":"2026-04-18T17:07:55.122297Z","steps":["trace[1193546056] 'process raft request' (duration: 154.901088ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:27.91689Z","caller":"traceutil/trace.go:171","msg":"trace[1337628045] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"216.485437ms","start":"2026-04-18T17:11:27.700361Z","end":"2026-04-18T17:11:27.916847Z","steps":["trace[1337628045] 'process raft request' (duration: 125.733102ms)","trace[1337628045] 'compare' (duration: 90.401178ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:11:27.916925Z","caller":"traceutil/trace.go:171","msg":"trace[575934494] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"156.226502ms","start":"2026-04-18T17:11:27.760666Z","end":"2026-04-18T17:11:27.916893Z","steps":["trace[575934494] 'process raft request' (duration: 156.177711ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:27.917102Z","caller":"traceutil/trace.go:171","msg":"trace[1443332476] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"200.586363ms","start":"2026-04-18T17:11:27.716504Z","end":"2026-04-18T17:11:27.917091Z","steps":["trace[1443332476] 'process raft request' (duration: 200.265797ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:27.917428Z","caller":"traceutil/trace.go:171","msg":"trace[93255712] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1943; }","duration":"184.720342ms","start":"2026-04-18T17:11:27.732535Z","end":"2026-04-18T17:11:27.917256Z","steps":["trace[93255712] 'read index received' (duration: 93.571179ms)","trace[93255712] 'applied index is now lower than readState.Index' (duration: 91.147273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:11:27.91764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.776901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:971"} {"level":"info","ts":"2026-04-18T17:11:27.917679Z","caller":"traceutil/trace.go:171","msg":"trace[1763170620] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1873; }","duration":"219.835561ms","start":"2026-04-18T17:11:27.69783Z","end":"2026-04-18T17:11:27.917666Z","steps":["trace[1763170620] 'agreement among raft nodes before linearized reading' (duration: 219.675968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:27.917942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.802582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-djrkr\" ","response":"range_response_count:1 size:7666"} {"level":"info","ts":"2026-04-18T17:11:27.917972Z","caller":"traceutil/trace.go:171","msg":"trace[1983547103] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-djrkr; range_end:; response_count:1; response_revision:1873; }","duration":"129.855253ms","start":"2026-04-18T17:11:27.788108Z","end":"2026-04-18T17:11:27.917963Z","steps":["trace[1983547103] 'agreement among raft nodes before linearized reading' (duration: 129.770402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:27.917989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.886495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-wgzrh\" ","response":"range_response_count:1 size:3667"} {"level":"info","ts":"2026-04-18T17:11:27.918045Z","caller":"traceutil/trace.go:171","msg":"trace[613603994] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-wgzrh; range_end:; response_count:1; response_revision:1873; }","duration":"183.964037ms","start":"2026-04-18T17:11:27.734061Z","end":"2026-04-18T17:11:27.918025Z","steps":["trace[613603994] 'agreement among raft nodes before linearized reading' (duration: 183.811524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:27.918186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.712555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-18T17:11:27.918243Z","caller":"traceutil/trace.go:171","msg":"trace[1717344869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1873; }","duration":"143.804916ms","start":"2026-04-18T17:11:27.774422Z","end":"2026-04-18T17:11:27.918227Z","steps":["trace[1717344869] 'agreement among raft nodes before linearized reading' (duration: 143.646953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:27.918531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.695898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:1 size:968"} {"level":"info","ts":"2026-04-18T17:11:27.918586Z","caller":"traceutil/trace.go:171","msg":"trace[1871166218] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1873; }","duration":"220.764089ms","start":"2026-04-18T17:11:27.697805Z","end":"2026-04-18T17:11:27.918569Z","steps":["trace[1871166218] 'agreement among raft nodes before linearized reading' (duration: 219.706949ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:35.095582Z","caller":"traceutil/trace.go:171","msg":"trace[689544828] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"197.004673ms","start":"2026-04-18T17:11:34.898554Z","end":"2026-04-18T17:11:35.095558Z","steps":["trace[689544828] 'read index received' (duration: 196.616545ms)","trace[689544828] 'applied index is now lower than readState.Index' (duration: 386.948µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:11:35.095644Z","caller":"traceutil/trace.go:171","msg":"trace[769493658] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"222.519364ms","start":"2026-04-18T17:11:34.873094Z","end":"2026-04-18T17:11:35.095613Z","steps":["trace[769493658] 'process raft request' (duration: 222.171587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:35.095782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.202216ms","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-18T17:11:35.095831Z","caller":"traceutil/trace.go:171","msg":"trace[1791663485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2104; }","duration":"197.269118ms","start":"2026-04-18T17:11:34.898547Z","end":"2026-04-18T17:11:35.095816Z","steps":["trace[1791663485] 'agreement among raft nodes before linearized reading' (duration: 197.111395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:35.498832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.812711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T17:11:35.498956Z","caller":"traceutil/trace.go:171","msg":"trace[860379079] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2180; }","duration":"398.879991ms","start":"2026-04-18T17:11:35.100058Z","end":"2026-04-18T17:11:35.498938Z","steps":["trace[860379079] 'read index received' (duration: 291.825036ms)","trace[860379079] 'applied index is now lower than readState.Index' (duration: 107.053425ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:11:35.499007Z","caller":"traceutil/trace.go:171","msg":"trace[1982935065] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"399.080665ms","start":"2026-04-18T17:11:35.099891Z","end":"2026-04-18T17:11:35.498972Z","steps":["trace[1982935065] 'process raft request' (duration: 292.055532ms)","trace[1982935065] 'compare' (duration: 105.687828ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:11:35.499169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:35.099868Z","time spent":"399.213587ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T17:11:35.499172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.114476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/node-feature-discovery-gc-6675cbb6d9-r7lj7\" ","response":"range_response_count:1 size:3328"} {"level":"warn","ts":"2026-04-18T17:11:35.499174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.813895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-18T17:11:35.499228Z","caller":"traceutil/trace.go:171","msg":"trace[1025405205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2105; }","duration":"295.931497ms","start":"2026-04-18T17:11:35.203283Z","end":"2026-04-18T17:11:35.499215Z","steps":["trace[1025405205] 'agreement among raft nodes before linearized reading' (duration: 295.768474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:35.499214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.435585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T17:11:35.499284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.461395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:5335"} {"level":"info","ts":"2026-04-18T17:11:35.499301Z","caller":"traceutil/trace.go:171","msg":"trace[71710220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2105; }","duration":"240.579118ms","start":"2026-04-18T17:11:35.2587Z","end":"2026-04-18T17:11:35.499279Z","steps":["trace[71710220] 'agreement among raft nodes before linearized reading' (duration: 240.406935ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:35.499246Z","caller":"traceutil/trace.go:171","msg":"trace[1306153546] range","detail":"{range_begin:/registry/pods/monitoring/node-feature-discovery-gc-6675cbb6d9-r7lj7; range_end:; response_count:1; response_revision:2105; }","duration":"399.197857ms","start":"2026-04-18T17:11:35.100008Z","end":"2026-04-18T17:11:35.499206Z","steps":["trace[1306153546] 'agreement among raft nodes before linearized reading' (duration: 399.008833ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:35.499342Z","caller":"traceutil/trace.go:171","msg":"trace[902034297] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:2105; }","duration":"218.554386ms","start":"2026-04-18T17:11:35.280772Z","end":"2026-04-18T17:11:35.499326Z","steps":["trace[902034297] 'agreement among raft nodes before linearized reading' (duration: 218.328802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:35.499396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:35.099988Z","time spent":"399.373961ms","remote":"127.0.0.1:38138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":3349,"request content":"key:\"/registry/pods/monitoring/node-feature-discovery-gc-6675cbb6d9-r7lj7\" "} {"level":"info","ts":"2026-04-18T17:11:35.890364Z","caller":"traceutil/trace.go:171","msg":"trace[408744187] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"383.482929ms","start":"2026-04-18T17:11:35.506863Z","end":"2026-04-18T17:11:35.890346Z","steps":["trace[408744187] 'process raft request' (duration: 383.372667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:35.89048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:35.506848Z","time spent":"383.576062ms","remote":"127.0.0.1:38138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T17:11:35.89837Z","caller":"traceutil/trace.go:171","msg":"trace[1183381132] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"339.646998ms","start":"2026-04-18T17:11:35.558703Z","end":"2026-04-18T17:11:35.89835Z","steps":["trace[1183381132] 'process raft request' (duration: 339.505925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:35.8985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:35.558686Z","time spent":"339.744959ms","remote":"127.0.0.1:38134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11592,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T17:11:41.63753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.505456ms","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-18T17:11:41.637694Z","caller":"traceutil/trace.go:171","msg":"trace[849698261] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2198; }","duration":"100.691459ms","start":"2026-04-18T17:11:41.536975Z","end":"2026-04-18T17:11:41.637667Z","steps":["trace[849698261] 'range keys from in-memory index tree' (duration: 100.480555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:41.637756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.642732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T17:11:41.637938Z","caller":"traceutil/trace.go:171","msg":"trace[419796990] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2276; }","duration":"112.186766ms","start":"2026-04-18T17:11:41.525734Z","end":"2026-04-18T17:11:41.637921Z","steps":["trace[419796990] 'read index received' (duration: 42.111µs)","trace[419796990] 'applied index is now lower than readState.Index' (duration: 112.143505ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:11:41.638038Z","caller":"traceutil/trace.go:171","msg":"trace[293817596] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"353.51955ms","start":"2026-04-18T17:11:41.284488Z","end":"2026-04-18T17:11:41.638008Z","steps":["trace[293817596] 'process raft request' (duration: 353.375077ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:41.63815Z","caller":"traceutil/trace.go:171","msg":"trace[165298957] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"353.57116ms","start":"2026-04-18T17:11:41.284416Z","end":"2026-04-18T17:11:41.637987Z","steps":["trace[165298957] 'process raft request' (duration: 115.619862ms)","trace[165298957] 'compare' (duration: 237.486739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:11:41.638193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:41.284475Z","time spent":"353.640532ms","remote":"127.0.0.1:38392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5862,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T17:11:41.638304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:41.284367Z","time spent":"353.868836ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":972,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T17:11:41.638191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.458781ms","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-18T17:11:41.638362Z","caller":"traceutil/trace.go:171","msg":"trace[1009886645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2200; }","duration":"112.675875ms","start":"2026-04-18T17:11:41.525671Z","end":"2026-04-18T17:11:41.638347Z","steps":["trace[1009886645] 'agreement among raft nodes before linearized reading' (duration: 112.349029ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:41.882025Z","caller":"traceutil/trace.go:171","msg":"trace[75504231] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"143.091283ms","start":"2026-04-18T17:11:41.738895Z","end":"2026-04-18T17:11:41.881986Z","steps":["trace[75504231] 'process raft request' (duration: 69.633469ms)","trace[75504231] 'compare' (duration: 73.21905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:11:42.101982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.135957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T17:11:42.102206Z","caller":"traceutil/trace.go:171","msg":"trace[1449419974] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2283; }","duration":"139.286158ms","start":"2026-04-18T17:11:41.962902Z","end":"2026-04-18T17:11:42.102188Z","steps":["trace[1449419974] 'read index received' (duration: 34.862905ms)","trace[1449419974] 'applied index is now lower than readState.Index' (duration: 104.422093ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:11:42.102311Z","caller":"traceutil/trace.go:171","msg":"trace[729284725] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"215.693601ms","start":"2026-04-18T17:11:41.886605Z","end":"2026-04-18T17:11:42.102299Z","steps":["trace[729284725] 'process raft request' (duration: 215.538607ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.102265Z","caller":"traceutil/trace.go:171","msg":"trace[1651797176] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"362.220091ms","start":"2026-04-18T17:11:41.739995Z","end":"2026-04-18T17:11:42.102215Z","steps":["trace[1651797176] 'process raft request' (duration: 362.094479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:42.102465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.547144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T17:11:42.102545Z","caller":"traceutil/trace.go:171","msg":"trace[1348833966] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2208; }","duration":"139.696706ms","start":"2026-04-18T17:11:41.962828Z","end":"2026-04-18T17:11:42.102525Z","steps":["trace[1348833966] 'agreement among raft nodes before linearized reading' (duration: 139.522273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:42.102473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:41.739976Z","time spent":"362.400846ms","remote":"127.0.0.1:38138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T17:11:42.102196Z","caller":"traceutil/trace.go:171","msg":"trace[135979762] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"362.527837ms","start":"2026-04-18T17:11:41.739633Z","end":"2026-04-18T17:11:42.102161Z","steps":["trace[135979762] 'process raft request' (duration: 258.136555ms)","trace[135979762] 'compare' (duration: 103.942224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:11:42.102758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:11:41.739603Z","time spent":"363.093029ms","remote":"127.0.0.1:38400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T17:11:42.361247Z","caller":"traceutil/trace.go:171","msg":"trace[809495626] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"254.737368ms","start":"2026-04-18T17:11:42.106478Z","end":"2026-04-18T17:11:42.361215Z","steps":["trace[809495626] 'process raft request' (duration: 159.186209ms)","trace[809495626] 'compare' (duration: 95.402826ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:11:42.399759Z","caller":"traceutil/trace.go:171","msg":"trace[1169049976] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"292.497721ms","start":"2026-04-18T17:11:42.107225Z","end":"2026-04-18T17:11:42.399723Z","steps":["trace[1169049976] 'process raft request' (duration: 292.227266ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.399886Z","caller":"traceutil/trace.go:171","msg":"trace[565983495] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"292.48377ms","start":"2026-04-18T17:11:42.10736Z","end":"2026-04-18T17:11:42.399843Z","steps":["trace[565983495] 'process raft request' (duration: 292.277006ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.400075Z","caller":"traceutil/trace.go:171","msg":"trace[2080868730] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"292.647524ms","start":"2026-04-18T17:11:42.107403Z","end":"2026-04-18T17:11:42.400051Z","steps":["trace[2080868730] 'process raft request' (duration: 292.302168ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.400223Z","caller":"traceutil/trace.go:171","msg":"trace[1083631596] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"292.754396ms","start":"2026-04-18T17:11:42.107458Z","end":"2026-04-18T17:11:42.400212Z","steps":["trace[1083631596] 'process raft request' (duration: 292.290337ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.400614Z","caller":"traceutil/trace.go:171","msg":"trace[186546150] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"291.727166ms","start":"2026-04-18T17:11:42.108858Z","end":"2026-04-18T17:11:42.400585Z","steps":["trace[186546150] 'process raft request' (duration: 291.45465ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.400864Z","caller":"traceutil/trace.go:171","msg":"trace[2007658645] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"290.257417ms","start":"2026-04-18T17:11:42.110586Z","end":"2026-04-18T17:11:42.400844Z","steps":["trace[2007658645] 'process raft request' (duration: 289.94125ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.40102Z","caller":"traceutil/trace.go:171","msg":"trace[1331897483] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2286; }","duration":"286.980532ms","start":"2026-04-18T17:11:42.114023Z","end":"2026-04-18T17:11:42.401003Z","steps":["trace[1331897483] 'read index received' (duration: 151.713073ms)","trace[1331897483] 'applied index is now lower than readState.Index' (duration: 135.265849ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:11:42.401176Z","caller":"traceutil/trace.go:171","msg":"trace[1921879668] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"202.637024ms","start":"2026-04-18T17:11:42.198525Z","end":"2026-04-18T17:11:42.401162Z","steps":["trace[1921879668] 'process raft request' (duration: 202.238596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:42.401278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.239176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:11:42.401373Z","caller":"traceutil/trace.go:171","msg":"trace[1433075655] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission; range_end:; response_count:0; response_revision:2216; }","duration":"287.360739ms","start":"2026-04-18T17:11:42.113992Z","end":"2026-04-18T17:11:42.401353Z","steps":["trace[1433075655] 'agreement among raft nodes before linearized reading' (duration: 287.233117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:42.41507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.013518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:11:42.415194Z","caller":"traceutil/trace.go:171","msg":"trace[1196339285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2217; }","duration":"154.11783ms","start":"2026-04-18T17:11:42.261004Z","end":"2026-04-18T17:11:42.415122Z","steps":["trace[1196339285] 'agreement among raft nodes before linearized reading' (duration: 154.029169ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.528652Z","caller":"traceutil/trace.go:171","msg":"trace[1736644869] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"108.8581ms","start":"2026-04-18T17:11:42.419765Z","end":"2026-04-18T17:11:42.528623Z","steps":["trace[1736644869] 'process raft request' (duration: 108.584495ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.528841Z","caller":"traceutil/trace.go:171","msg":"trace[1999506503] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"108.435832ms","start":"2026-04-18T17:11:42.420384Z","end":"2026-04-18T17:11:42.52882Z","steps":["trace[1999506503] 'process raft request' (duration: 108.189527ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:42.529036Z","caller":"traceutil/trace.go:171","msg":"trace[1376293333] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"105.348052ms","start":"2026-04-18T17:11:42.423673Z","end":"2026-04-18T17:11:42.529022Z","steps":["trace[1376293333] 'process raft request' (duration: 105.093947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:11:42.5292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.312789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:11:42.529393Z","caller":"traceutil/trace.go:171","msg":"trace[503575218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2224; }","duration":"111.554724ms","start":"2026-04-18T17:11:42.417817Z","end":"2026-04-18T17:11:42.529372Z","steps":["trace[503575218] 'agreement among raft nodes before linearized reading' (duration: 111.298829ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:11:54.773901Z","caller":"traceutil/trace.go:171","msg":"trace[2117537863] transaction","detail":"{read_only:false; response_revision:2371; number_of_response:1; }","duration":"108.474816ms","start":"2026-04-18T17:11:54.665399Z","end":"2026-04-18T17:11:54.773874Z","steps":["trace[2117537863] 'process raft request' (duration: 108.2052ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:12:30.714785Z","caller":"traceutil/trace.go:171","msg":"trace[1253314776] transaction","detail":"{read_only:false; response_revision:3216; number_of_response:1; }","duration":"118.156423ms","start":"2026-04-18T17:12:30.596589Z","end":"2026-04-18T17:12:30.714745Z","steps":["trace[1253314776] 'process raft request' (duration: 117.881016ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:12:36.858764Z","caller":"traceutil/trace.go:171","msg":"trace[1765241770] transaction","detail":"{read_only:false; response_revision:3311; number_of_response:1; }","duration":"123.856431ms","start":"2026-04-18T17:12:36.734874Z","end":"2026-04-18T17:12:36.85873Z","steps":["trace[1765241770] 'process raft request' (duration: 123.457522ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:12:37.077574Z","caller":"traceutil/trace.go:171","msg":"trace[931197011] linearizableReadLoop","detail":"{readStateIndex:3443; appliedIndex:3442; }","duration":"122.566113ms","start":"2026-04-18T17:12:36.954976Z","end":"2026-04-18T17:12:37.077542Z","steps":["trace[931197011] 'read index received' (duration: 113.59686ms)","trace[931197011] 'applied index is now lower than readState.Index' (duration: 8.968203ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:12:37.07759Z","caller":"traceutil/trace.go:171","msg":"trace[1797413881] transaction","detail":"{read_only:false; response_revision:3315; number_of_response:1; }","duration":"190.097756ms","start":"2026-04-18T17:12:36.887452Z","end":"2026-04-18T17:12:37.07755Z","steps":["trace[1797413881] 'process raft request' (duration: 181.177485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:12:37.077824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.832469ms","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-18T17:12:37.077867Z","caller":"traceutil/trace.go:171","msg":"trace[34869884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3315; }","duration":"122.920821ms","start":"2026-04-18T17:12:36.954934Z","end":"2026-04-18T17:12:37.077855Z","steps":["trace[34869884] 'agreement among raft nodes before linearized reading' (duration: 122.726056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:12:37.07786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.170366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5cbe4f78-6876-4b52-8cf3-057e73f3b6d8\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-04-18T17:12:37.0779Z","caller":"traceutil/trace.go:171","msg":"trace[347033379] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5cbe4f78-6876-4b52-8cf3-057e73f3b6d8; range_end:; response_count:1; response_revision:3315; }","duration":"111.266018ms","start":"2026-04-18T17:12:36.966623Z","end":"2026-04-18T17:12:37.077889Z","steps":["trace[347033379] 'agreement among raft nodes before linearized reading' (duration: 111.093474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:12:37.078091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.179779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T17:12:37.078118Z","caller":"traceutil/trace.go:171","msg":"trace[1092937932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3315; }","duration":"100.24545ms","start":"2026-04-18T17:12:36.977866Z","end":"2026-04-18T17:12:37.078112Z","steps":["trace[1092937932] 'agreement among raft nodes before linearized reading' (duration: 100.178649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:12:37.0783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.362366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa4c182c-bd31-4117-84e8-2211378f072c\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-04-18T17:12:37.078375Z","caller":"traceutil/trace.go:171","msg":"trace[1114306935] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa4c182c-bd31-4117-84e8-2211378f072c; range_end:; response_count:1; response_revision:3315; }","duration":"109.474618ms","start":"2026-04-18T17:12:36.96888Z","end":"2026-04-18T17:12:37.078355Z","steps":["trace[1114306935] 'agreement among raft nodes before linearized reading' (duration: 109.216232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:12:37.451136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.124513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3eb83319-f986-46f5-a8c2-1ef54546093f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-18T17:12:37.451238Z","caller":"traceutil/trace.go:171","msg":"trace[1661698095] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3eb83319-f986-46f5-a8c2-1ef54546093f; range_end:; response_count:1; response_revision:3317; }","duration":"150.257096ms","start":"2026-04-18T17:12:37.300955Z","end":"2026-04-18T17:12:37.451212Z","steps":["trace[1661698095] 'range keys from in-memory index tree' (duration: 149.99124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:12:38.472525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.752365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:12:38.472634Z","caller":"traceutil/trace.go:171","msg":"trace[1677553837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3320; }","duration":"212.897089ms","start":"2026-04-18T17:12:38.25971Z","end":"2026-04-18T17:12:38.472607Z","steps":["trace[1677553837] 'range keys from in-memory index tree' (duration: 212.687344ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:12:40.516186Z","caller":"traceutil/trace.go:171","msg":"trace[1871906134] transaction","detail":"{read_only:false; response_revision:3336; number_of_response:1; }","duration":"127.75913ms","start":"2026-04-18T17:12:40.388393Z","end":"2026-04-18T17:12:40.516152Z","steps":["trace[1871906134] 'process raft request' (duration: 108.413754ms)","trace[1871906134] 'compare' (duration: 19.178432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:13:27.27233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.315744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T17:13:27.272633Z","caller":"traceutil/trace.go:171","msg":"trace[1248007946] transaction","detail":"{read_only:false; response_revision:3794; number_of_response:1; }","duration":"193.401199ms","start":"2026-04-18T17:13:27.079199Z","end":"2026-04-18T17:13:27.2726Z","steps":["trace[1248007946] 'process raft request' (duration: 85.711657ms)","trace[1248007946] 'compare' (duration: 107.029158ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:13:27.27292Z","caller":"traceutil/trace.go:171","msg":"trace[960031552] linearizableReadLoop","detail":"{readStateIndex:3935; appliedIndex:3933; }","duration":"145.773828ms","start":"2026-04-18T17:13:27.127125Z","end":"2026-04-18T17:13:27.272899Z","steps":["trace[960031552] 'read index received' (duration: 37.862242ms)","trace[960031552] 'applied index is now lower than readState.Index' (duration: 107.908916ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:13:27.273129Z","caller":"traceutil/trace.go:171","msg":"trace[1739918147] transaction","detail":"{read_only:false; response_revision:3795; number_of_response:1; }","duration":"148.327106ms","start":"2026-04-18T17:13:27.124783Z","end":"2026-04-18T17:13:27.27311Z","steps":["trace[1739918147] 'process raft request' (duration: 147.820824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:13:27.273361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.222659ms","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-18T17:13:27.273456Z","caller":"traceutil/trace.go:171","msg":"trace[28579192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3795; }","duration":"146.327281ms","start":"2026-04-18T17:13:27.127109Z","end":"2026-04-18T17:13:27.273436Z","steps":["trace[28579192] 'agreement among raft nodes before linearized reading' (duration: 146.112886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:13:32.829309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.618771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T17:13:32.829444Z","caller":"traceutil/trace.go:171","msg":"trace[38904891] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:3828; }","duration":"224.807807ms","start":"2026-04-18T17:13:32.604615Z","end":"2026-04-18T17:13:32.829423Z","steps":["trace[38904891] 'count revisions from in-memory index tree' (duration: 224.54044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:13:32.830619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.597528ms","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-04-18T17:13:32.830713Z","caller":"traceutil/trace.go:171","msg":"trace[1174248419] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3828; }","duration":"239.741102ms","start":"2026-04-18T17:13:32.59095Z","end":"2026-04-18T17:13:32.830691Z","steps":["trace[1174248419] 'range keys from in-memory index tree' (duration: 238.343031ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:13:32.855818Z","caller":"traceutil/trace.go:171","msg":"trace[1686811974] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"103.099219ms","start":"2026-04-18T17:13:32.752687Z","end":"2026-04-18T17:13:32.855786Z","steps":["trace[1686811974] 'process raft request' (duration: 102.299852ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:13:33.50453Z","caller":"traceutil/trace.go:171","msg":"trace[1155376323] linearizableReadLoop","detail":"{readStateIndex:3976; appliedIndex:3975; }","duration":"123.880587ms","start":"2026-04-18T17:13:33.380616Z","end":"2026-04-18T17:13:33.504497Z","steps":["trace[1155376323] 'read index received' (duration: 102.124517ms)","trace[1155376323] 'applied index is now lower than readState.Index' (duration: 21.75516ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:13:33.504541Z","caller":"traceutil/trace.go:171","msg":"trace[1288027141] transaction","detail":"{read_only:false; response_revision:3834; number_of_response:1; }","duration":"150.892094ms","start":"2026-04-18T17:13:33.353618Z","end":"2026-04-18T17:13:33.50451Z","steps":["trace[1288027141] 'process raft request' (duration: 129.166565ms)","trace[1288027141] 'compare' (duration: 21.603165ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:13:33.504813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.180093ms","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-18T17:13:33.504894Z","caller":"traceutil/trace.go:171","msg":"trace[1271409223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3834; }","duration":"124.309086ms","start":"2026-04-18T17:13:33.380565Z","end":"2026-04-18T17:13:33.504874Z","steps":["trace[1271409223] 'agreement among raft nodes before linearized reading' (duration: 124.05169ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:15:07.474499Z","caller":"traceutil/trace.go:171","msg":"trace[1650074227] linearizableReadLoop","detail":"{readStateIndex:4578; appliedIndex:4577; }","duration":"169.087892ms","start":"2026-04-18T17:15:07.30538Z","end":"2026-04-18T17:15:07.474468Z","steps":["trace[1650074227] 'read index received' (duration: 168.976239ms)","trace[1650074227] 'applied index is now lower than readState.Index' (duration: 110.373µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:15:07.474633Z","caller":"traceutil/trace.go:171","msg":"trace[1383853198] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"183.704095ms","start":"2026-04-18T17:15:07.290879Z","end":"2026-04-18T17:15:07.474583Z","steps":["trace[1383853198] 'process raft request' (duration: 183.432109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:15:07.474689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.291986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:15:07.474745Z","caller":"traceutil/trace.go:171","msg":"trace[1984962114] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:4416; }","duration":"169.433079ms","start":"2026-04-18T17:15:07.3053Z","end":"2026-04-18T17:15:07.474734Z","steps":["trace[1984962114] 'agreement among raft nodes before linearized reading' (duration: 169.283785ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:15:07.569405Z","caller":"traceutil/trace.go:171","msg":"trace[627591723] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"159.167033ms","start":"2026-04-18T17:15:07.410202Z","end":"2026-04-18T17:15:07.569369Z","steps":["trace[627591723] 'process raft request' (duration: 152.94939ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:17:06.052004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2640} {"level":"info","ts":"2026-04-18T17:17:06.117984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2640,"took":"64.715878ms","hash":3101307382,"current-db-size-bytes":42631168,"current-db-size":"43 MB","current-db-size-in-use-bytes":21602304,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-18T17:17:06.118042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3101307382,"revision":2640,"compact-revision":-1} {"level":"info","ts":"2026-04-18T17:17:27.315022Z","caller":"traceutil/trace.go:171","msg":"trace[1450845233] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"112.344349ms","start":"2026-04-18T17:17:27.202652Z","end":"2026-04-18T17:17:27.314997Z","steps":["trace[1450845233] 'process raft request' (duration: 107.620107ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:17:27.315317Z","caller":"traceutil/trace.go:171","msg":"trace[2034537749] linearizableReadLoop","detail":"{readStateIndex:5532; appliedIndex:5528; }","duration":"101.79732ms","start":"2026-04-18T17:17:27.213496Z","end":"2026-04-18T17:17:27.315293Z","steps":["trace[2034537749] 'read index received' (duration: 44.906858ms)","trace[2034537749] 'applied index is now lower than readState.Index' (duration: 56.889602ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:17:27.315451Z","caller":"traceutil/trace.go:171","msg":"trace[2088969607] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"111.724745ms","start":"2026-04-18T17:17:27.203716Z","end":"2026-04-18T17:17:27.31544Z","steps":["trace[2088969607] 'process raft request' (duration: 111.239404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:17:27.315688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.137959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-18T17:17:27.315747Z","caller":"traceutil/trace.go:171","msg":"trace[852009793] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"111.180491ms","start":"2026-04-18T17:17:27.204552Z","end":"2026-04-18T17:17:27.315732Z","steps":["trace[852009793] 'process raft request' (duration: 110.642209ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:17:27.315769Z","caller":"traceutil/trace.go:171","msg":"trace[1151479380] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5335; }","duration":"102.278052ms","start":"2026-04-18T17:17:27.213472Z","end":"2026-04-18T17:17:27.31575Z","steps":["trace[1151479380] 'agreement among raft nodes before linearized reading' (duration: 101.995745ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:19:09.347628Z","caller":"traceutil/trace.go:171","msg":"trace[2000132602] linearizableReadLoop","detail":"{readStateIndex:7020; appliedIndex:7019; }","duration":"136.990276ms","start":"2026-04-18T17:19:09.210609Z","end":"2026-04-18T17:19:09.347599Z","steps":["trace[2000132602] 'read index received' (duration: 136.832912ms)","trace[2000132602] 'applied index is now lower than readState.Index' (duration: 155.904µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:19:09.347881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.244732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T17:19:09.348006Z","caller":"traceutil/trace.go:171","msg":"trace[915330329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6772; }","duration":"137.352424ms","start":"2026-04-18T17:19:09.210571Z","end":"2026-04-18T17:19:09.347924Z","steps":["trace[915330329] 'agreement among raft nodes before linearized reading' (duration: 137.16164ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:19:09.48241Z","caller":"traceutil/trace.go:171","msg":"trace[1099053093] transaction","detail":"{read_only:false; response_revision:6773; number_of_response:1; }","duration":"270.892129ms","start":"2026-04-18T17:19:09.211488Z","end":"2026-04-18T17:19:09.482381Z","steps":["trace[1099053093] 'process raft request' (duration: 246.064252ms)","trace[1099053093] 'compare' (duration: 24.506099ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:19:09.482508Z","caller":"traceutil/trace.go:171","msg":"trace[14946420] linearizableReadLoop","detail":"{readStateIndex:7021; appliedIndex:7020; }","duration":"134.766873ms","start":"2026-04-18T17:19:09.347709Z","end":"2026-04-18T17:19:09.482476Z","steps":["trace[14946420] 'read index received' (duration: 109.998798ms)","trace[14946420] 'applied index is now lower than readState.Index' (duration: 24.764355ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:19:09.482555Z","caller":"traceutil/trace.go:171","msg":"trace[310464307] transaction","detail":"{read_only:false; response_revision:6775; number_of_response:1; }","duration":"130.608846ms","start":"2026-04-18T17:19:09.35192Z","end":"2026-04-18T17:19:09.482528Z","steps":["trace[310464307] 'process raft request' (duration: 130.560354ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:19:09.482567Z","caller":"traceutil/trace.go:171","msg":"trace[1040530814] transaction","detail":"{read_only:false; response_revision:6774; number_of_response:1; }","duration":"133.650437ms","start":"2026-04-18T17:19:09.348892Z","end":"2026-04-18T17:19:09.482542Z","steps":["trace[1040530814] 'process raft request' (duration: 133.467883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:19:09.482785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.903837ms","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":"warn","ts":"2026-04-18T17:19:09.482801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.811372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T17:19:09.482836Z","caller":"traceutil/trace.go:171","msg":"trace[1382361222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6775; }","duration":"261.994159ms","start":"2026-04-18T17:19:09.220827Z","end":"2026-04-18T17:19:09.482821Z","steps":["trace[1382361222] 'agreement among raft nodes before linearized reading' (duration: 261.778194ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:19:09.482851Z","caller":"traceutil/trace.go:171","msg":"trace[971297862] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:6775; }","duration":"147.913915ms","start":"2026-04-18T17:19:09.334921Z","end":"2026-04-18T17:19:09.482835Z","steps":["trace[971297862] 'agreement among raft nodes before linearized reading' (duration: 147.825153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:19:09.482877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.202136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:19:09.482976Z","caller":"traceutil/trace.go:171","msg":"trace[1419440174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6775; }","duration":"224.325318ms","start":"2026-04-18T17:19:09.258625Z","end":"2026-04-18T17:19:09.48295Z","steps":["trace[1419440174] 'agreement among raft nodes before linearized reading' (duration: 224.183615ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:19:10.01158Z","caller":"traceutil/trace.go:171","msg":"trace[1100354817] transaction","detail":"{read_only:false; response_revision:6779; number_of_response:1; }","duration":"105.793868ms","start":"2026-04-18T17:19:09.905757Z","end":"2026-04-18T17:19:10.011551Z","steps":["trace[1100354817] 'process raft request' (duration: 104.727434ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:19:10.01185Z","caller":"traceutil/trace.go:171","msg":"trace[1216611255] transaction","detail":"{read_only:false; response_revision:6780; number_of_response:1; }","duration":"104.726214ms","start":"2026-04-18T17:19:09.907085Z","end":"2026-04-18T17:19:10.011812Z","steps":["trace[1216611255] 'process raft request' (duration: 104.375905ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:19:45.909831Z","caller":"traceutil/trace.go:171","msg":"trace[1376731583] transaction","detail":"{read_only:false; response_revision:7065; number_of_response:1; }","duration":"132.715696ms","start":"2026-04-18T17:19:45.77709Z","end":"2026-04-18T17:19:45.909805Z","steps":["trace[1376731583] 'process raft request' (duration: 132.556673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:19:46.224546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.127014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ns6sh\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-04-18T17:19:46.224619Z","caller":"traceutil/trace.go:171","msg":"trace[1331201538] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ns6sh; range_end:; response_count:1; response_revision:7065; }","duration":"205.248677ms","start":"2026-04-18T17:19:46.019354Z","end":"2026-04-18T17:19:46.224603Z","steps":["trace[1331201538] 'range keys from in-memory index tree' (duration: 204.95145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:19:46.224608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.693943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-18T17:19:46.224717Z","caller":"traceutil/trace.go:171","msg":"trace[53119861] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:7065; }","duration":"126.811405ms","start":"2026-04-18T17:19:46.097887Z","end":"2026-04-18T17:19:46.224698Z","steps":["trace[53119861] 'range keys from in-memory index tree' (duration: 126.601721ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:21:32.830591Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021b4.wal"} {"level":"info","ts":"2026-04-18T17:21:51.142149Z","caller":"traceutil/trace.go:171","msg":"trace[647850113] transaction","detail":"{read_only:false; response_revision:9078; number_of_response:1; }","duration":"122.294237ms","start":"2026-04-18T17:21:51.01983Z","end":"2026-04-18T17:21:51.142124Z","steps":["trace[647850113] 'process raft request' (duration: 122.128753ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:21:51.145919Z","caller":"traceutil/trace.go:171","msg":"trace[1567207003] transaction","detail":"{read_only:false; response_revision:9079; number_of_response:1; }","duration":"101.778237ms","start":"2026-04-18T17:21:51.044121Z","end":"2026-04-18T17:21:51.145899Z","steps":["trace[1567207003] 'process raft request' (duration: 101.674105ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:22:05.136496Z","caller":"traceutil/trace.go:171","msg":"trace[263269615] transaction","detail":"{read_only:false; response_revision:9240; number_of_response:1; }","duration":"126.330292ms","start":"2026-04-18T17:22:05.01014Z","end":"2026-04-18T17:22:05.13647Z","steps":["trace[263269615] 'process raft request' (duration: 126.178239ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:22:05.13748Z","caller":"traceutil/trace.go:171","msg":"trace[1249409588] linearizableReadLoop","detail":"{readStateIndex:9543; appliedIndex:9543; }","duration":"109.44366ms","start":"2026-04-18T17:22:05.028017Z","end":"2026-04-18T17:22:05.137461Z","steps":["trace[1249409588] 'read index received' (duration: 109.433819ms)","trace[1249409588] 'applied index is now lower than readState.Index' (duration: 8.351µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:22:05.137617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.579383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:22:05.137672Z","caller":"traceutil/trace.go:171","msg":"trace[1182127664] range","detail":"{range_begin:/registry/jobs/openstack/barbican-rabbit-init; range_end:; response_count:0; response_revision:9240; }","duration":"109.672425ms","start":"2026-04-18T17:22:05.027985Z","end":"2026-04-18T17:22:05.137657Z","steps":["trace[1182127664] 'agreement among raft nodes before linearized reading' (duration: 109.570543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:22:05.146454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.722837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:22:05.146512Z","caller":"traceutil/trace.go:171","msg":"trace[241215933] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:9241; }","duration":"117.903321ms","start":"2026-04-18T17:22:05.028591Z","end":"2026-04-18T17:22:05.146494Z","steps":["trace[241215933] 'agreement among raft nodes before linearized reading' (duration: 117.798969ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:22:05.146739Z","caller":"traceutil/trace.go:171","msg":"trace[846053666] transaction","detail":"{read_only:false; response_revision:9241; number_of_response:1; }","duration":"115.957584ms","start":"2026-04-18T17:22:05.030764Z","end":"2026-04-18T17:22:05.146721Z","steps":["trace[846053666] 'process raft request' (duration: 115.314539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:22:05.147053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.592854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-db-sync\" ","response":"range_response_count:1 size:5413"} {"level":"info","ts":"2026-04-18T17:22:05.147106Z","caller":"traceutil/trace.go:171","msg":"trace[1657759356] range","detail":"{range_begin:/registry/jobs/openstack/barbican-db-sync; range_end:; response_count:1; response_revision:9241; }","duration":"117.662876ms","start":"2026-04-18T17:22:05.029429Z","end":"2026-04-18T17:22:05.147091Z","steps":["trace[1657759356] 'agreement among raft nodes before linearized reading' (duration: 117.500052ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:22:06.062512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5136} {"level":"info","ts":"2026-04-18T17:22:06.136725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5136,"took":"71.571966ms","hash":784425257,"current-db-size-bytes":48914432,"current-db-size":"49 MB","current-db-size-in-use-bytes":42758144,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-18T17:22:06.136782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":784425257,"revision":5136,"compact-revision":2640} {"level":"info","ts":"2026-04-18T17:22:10.603307Z","caller":"traceutil/trace.go:171","msg":"trace[1112762962] transaction","detail":"{read_only:false; response_revision:9296; number_of_response:1; }","duration":"128.923685ms","start":"2026-04-18T17:22:10.47436Z","end":"2026-04-18T17:22:10.603283Z","steps":["trace[1112762962] 'process raft request' (duration: 128.772931ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:22:10.610714Z","caller":"traceutil/trace.go:171","msg":"trace[2023592502] transaction","detail":"{read_only:false; response_revision:9298; number_of_response:1; }","duration":"118.837473ms","start":"2026-04-18T17:22:10.491849Z","end":"2026-04-18T17:22:10.610687Z","steps":["trace[2023592502] 'process raft request' (duration: 118.783982ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:22:10.610877Z","caller":"traceutil/trace.go:171","msg":"trace[140770651] transaction","detail":"{read_only:false; response_revision:9297; number_of_response:1; }","duration":"136.058065ms","start":"2026-04-18T17:22:10.474793Z","end":"2026-04-18T17:22:10.610851Z","steps":["trace[140770651] 'process raft request' (duration: 135.708135ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:22:37.449706Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-18T17:22:37.46013Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-18T17:22:37.460238Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-18T17:22:45.615203Z","caller":"traceutil/trace.go:171","msg":"trace[1226208810] linearizableReadLoop","detail":"{readStateIndex:10057; appliedIndex:10056; }","duration":"107.455123ms","start":"2026-04-18T17:22:45.507721Z","end":"2026-04-18T17:22:45.615176Z","steps":["trace[1226208810] 'read index received' (duration: 48.016847ms)","trace[1226208810] 'applied index is now lower than readState.Index' (duration: 59.437156ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:22:45.615333Z","caller":"traceutil/trace.go:171","msg":"trace[793573076] transaction","detail":"{read_only:false; response_revision:9744; number_of_response:1; }","duration":"114.859944ms","start":"2026-04-18T17:22:45.500446Z","end":"2026-04-18T17:22:45.615306Z","steps":["trace[793573076] 'process raft request' (duration: 55.346146ms)","trace[793573076] 'compare' (duration: 59.146729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:22:45.615426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.685118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-18T17:22:45.615452Z","caller":"traceutil/trace.go:171","msg":"trace[1554951562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9744; }","duration":"107.755789ms","start":"2026-04-18T17:22:45.507688Z","end":"2026-04-18T17:22:45.615444Z","steps":["trace[1554951562] 'agreement among raft nodes before linearized reading' (duration: 107.620606ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:23:49.96985Z","caller":"traceutil/trace.go:171","msg":"trace[1668541989] linearizableReadLoop","detail":"{readStateIndex:10837; appliedIndex:10835; }","duration":"104.119311ms","start":"2026-04-18T17:23:49.865711Z","end":"2026-04-18T17:23:49.96983Z","steps":["trace[1668541989] 'read index received' (duration: 15.478909ms)","trace[1668541989] 'applied index is now lower than readState.Index' (duration: 88.639532ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:23:49.970009Z","caller":"traceutil/trace.go:171","msg":"trace[640480662] transaction","detail":"{read_only:false; response_revision:10505; number_of_response:1; }","duration":"110.408246ms","start":"2026-04-18T17:23:49.859564Z","end":"2026-04-18T17:23:49.969973Z","steps":["trace[640480662] 'process raft request' (duration: 79.526999ms)","trace[640480662] 'compare' (duration: 30.652131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:23:49.970036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.271605ms","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-18T17:23:49.97008Z","caller":"traceutil/trace.go:171","msg":"trace[34270938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10505; }","duration":"104.406549ms","start":"2026-04-18T17:23:49.865667Z","end":"2026-04-18T17:23:49.970074Z","steps":["trace[34270938] 'agreement among raft nodes before linearized reading' (duration: 104.199333ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:06.071776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9249} {"level":"info","ts":"2026-04-18T17:27:06.200189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9249,"took":"125.576119ms","hash":128462425,"current-db-size-bytes":57692160,"current-db-size":"58 MB","current-db-size-in-use-bytes":34762752,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-18T17:27:06.200249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":128462425,"revision":9249,"compact-revision":5136} {"level":"warn","ts":"2026-04-18T17:27:10.955864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.61195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-db-init\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-18T17:27:10.955942Z","caller":"traceutil/trace.go:171","msg":"trace[1742923702] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-db-init; range_end:; response_count:1; response_revision:12568; }","duration":"409.747934ms","start":"2026-04-18T17:27:10.546176Z","end":"2026-04-18T17:27:10.955924Z","steps":["trace[1742923702] 'range keys from in-memory index tree' (duration: 409.447037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:10.955971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:27:10.546158Z","time spent":"409.807385ms","remote":"127.0.0.1:38156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":545,"request content":"key:\"/registry/serviceaccounts/openstack/nova-db-init\" "} {"level":"warn","ts":"2026-04-18T17:27:10.955963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.699329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T17:27:10.956024Z","caller":"traceutil/trace.go:171","msg":"trace[1150195437] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:12568; }","duration":"403.8062ms","start":"2026-04-18T17:27:10.552204Z","end":"2026-04-18T17:27:10.95601Z","steps":["trace[1150195437] 'count revisions from in-memory index tree' (duration: 403.632956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:10.956058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:27:10.552188Z","time spent":"403.861542ms","remote":"127.0.0.1:38228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-18T17:27:10.956051Z","caller":"traceutil/trace.go:171","msg":"trace[1854839290] linearizableReadLoop","detail":"{readStateIndex:12961; appliedIndex:12960; }","duration":"308.145874ms","start":"2026-04-18T17:27:10.647878Z","end":"2026-04-18T17:27:10.956024Z","steps":["trace[1854839290] 'read index received' (duration: 304.810283ms)","trace[1854839290] 'applied index is now lower than readState.Index' (duration: 3.334781ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:27:10.956303Z","caller":"traceutil/trace.go:171","msg":"trace[1819767369] transaction","detail":"{read_only:false; response_revision:12569; number_of_response:1; }","duration":"394.449495ms","start":"2026-04-18T17:27:10.561828Z","end":"2026-04-18T17:27:10.956277Z","steps":["trace[1819767369] 'process raft request' (duration: 390.864098ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:10.956974Z","caller":"traceutil/trace.go:171","msg":"trace[803581683] transaction","detail":"{read_only:false; response_revision:12570; number_of_response:1; }","duration":"290.244411ms","start":"2026-04-18T17:27:10.666705Z","end":"2026-04-18T17:27:10.956949Z","steps":["trace[803581683] 'process raft request' (duration: 290.109168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:10.957022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.124437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-04-18T17:27:10.957065Z","caller":"traceutil/trace.go:171","msg":"trace[1549159430] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:12569; }","duration":"309.199078ms","start":"2026-04-18T17:27:10.647851Z","end":"2026-04-18T17:27:10.95705Z","steps":["trace[1549159430] 'agreement among raft nodes before linearized reading' (duration: 308.257126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:10.957105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:27:10.647838Z","time spent":"309.25561ms","remote":"127.0.0.1:38072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2211010,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-04-18T17:27:10.956374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:27:10.561786Z","time spent":"394.539367ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T17:27:24.588873Z","caller":"traceutil/trace.go:171","msg":"trace[802334926] transaction","detail":"{read_only:false; response_revision:12796; number_of_response:1; }","duration":"244.776275ms","start":"2026-04-18T17:27:24.344073Z","end":"2026-04-18T17:27:24.58885Z","steps":["trace[802334926] 'process raft request' (duration: 244.618261ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:24.588886Z","caller":"traceutil/trace.go:171","msg":"trace[1707225080] linearizableReadLoop","detail":"{readStateIndex:13194; appliedIndex:13194; }","duration":"209.78327ms","start":"2026-04-18T17:27:24.379075Z","end":"2026-04-18T17:27:24.588858Z","steps":["trace[1707225080] 'read index received' (duration: 209.77406ms)","trace[1707225080] 'applied index is now lower than readState.Index' (duration: 7.53µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:27:24.589108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.008926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T17:27:24.589154Z","caller":"traceutil/trace.go:171","msg":"trace[1015692560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12796; }","duration":"210.090878ms","start":"2026-04-18T17:27:24.379049Z","end":"2026-04-18T17:27:24.58914Z","steps":["trace[1015692560] 'agreement among raft nodes before linearized reading' (duration: 209.929193ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:24.59915Z","caller":"traceutil/trace.go:171","msg":"trace[2070386620] transaction","detail":"{read_only:false; response_revision:12797; number_of_response:1; }","duration":"218.856689ms","start":"2026-04-18T17:27:24.380274Z","end":"2026-04-18T17:27:24.599131Z","steps":["trace[2070386620] 'process raft request' (duration: 218.715516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:24.599333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.201686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:27:24.599384Z","caller":"traceutil/trace.go:171","msg":"trace[493038274] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12798; }","duration":"158.291638ms","start":"2026-04-18T17:27:24.441081Z","end":"2026-04-18T17:27:24.599373Z","steps":["trace[493038274] 'agreement among raft nodes before linearized reading' (duration: 158.191845ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:24.599383Z","caller":"traceutil/trace.go:171","msg":"trace[111789113] transaction","detail":"{read_only:false; response_revision:12798; number_of_response:1; }","duration":"213.337836ms","start":"2026-04-18T17:27:24.386011Z","end":"2026-04-18T17:27:24.599349Z","steps":["trace[111789113] 'process raft request' (duration: 213.07966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:24.599553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.94355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-18T17:27:24.599599Z","caller":"traceutil/trace.go:171","msg":"trace[1704275387] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12798; }","duration":"158.045092ms","start":"2026-04-18T17:27:24.441542Z","end":"2026-04-18T17:27:24.599587Z","steps":["trace[1704275387] 'agreement among raft nodes before linearized reading' (duration: 157.930329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:24.599602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.92667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-18T17:27:24.599687Z","caller":"traceutil/trace.go:171","msg":"trace[36449897] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12798; }","duration":"158.040692ms","start":"2026-04-18T17:27:24.441627Z","end":"2026-04-18T17:27:24.599668Z","steps":["trace[36449897] 'agreement among raft nodes before linearized reading' (duration: 157.798536ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:24.705483Z","caller":"traceutil/trace.go:171","msg":"trace[1003654666] linearizableReadLoop","detail":"{readStateIndex:13197; appliedIndex:13196; }","duration":"101.16463ms","start":"2026-04-18T17:27:24.604291Z","end":"2026-04-18T17:27:24.705456Z","steps":["trace[1003654666] 'read index received' (duration: 94.10101ms)","trace[1003654666] 'applied index is now lower than readState.Index' (duration: 7.06138ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:27:24.705489Z","caller":"traceutil/trace.go:171","msg":"trace[1416327924] transaction","detail":"{read_only:false; response_revision:12799; number_of_response:1; }","duration":"112.597886ms","start":"2026-04-18T17:27:24.592865Z","end":"2026-04-18T17:27:24.705463Z","steps":["trace[1416327924] 'process raft request' (duration: 105.515246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:24.805699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.31375ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-04-18T17:27:24.806096Z","caller":"traceutil/trace.go:171","msg":"trace[2050226322] linearizableReadLoop","detail":"{readStateIndex:13199; appliedIndex:13197; }","duration":"100.523335ms","start":"2026-04-18T17:27:24.705546Z","end":"2026-04-18T17:27:24.806069Z","steps":["trace[2050226322] 'read index received' (duration: 29.311µs)","trace[2050226322] 'applied index is now lower than readState.Index' (duration: 100.493024ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:27:24.80623Z","caller":"traceutil/trace.go:171","msg":"trace[720541372] transaction","detail":"{read_only:false; response_revision:12800; number_of_response:1; }","duration":"118.421446ms","start":"2026-04-18T17:27:24.687781Z","end":"2026-04-18T17:27:24.806202Z","steps":["trace[720541372] 'process raft request' (duration: 118.053147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:24.806343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.819981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-q6kdd\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-18T17:27:24.806386Z","caller":"traceutil/trace.go:171","msg":"trace[1632880735] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-q6kdd; range_end:; response_count:1; response_revision:12800; }","duration":"119.919633ms","start":"2026-04-18T17:27:24.686456Z","end":"2026-04-18T17:27:24.806375Z","steps":["trace[1632880735] 'agreement among raft nodes before linearized reading' (duration: 119.738799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:24.806574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.244779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:900516"} {"level":"info","ts":"2026-04-18T17:27:24.806664Z","caller":"traceutil/trace.go:171","msg":"trace[410508405] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:12799; }","duration":"202.395612ms","start":"2026-04-18T17:27:24.604245Z","end":"2026-04-18T17:27:24.806641Z","steps":["trace[410508405] 'agreement among raft nodes before linearized reading' (duration: 101.301454ms)","trace[410508405] 'range keys from in-memory index tree' (duration: 100.186487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:27:24.807314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.71647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:900516"} {"level":"info","ts":"2026-04-18T17:27:24.807357Z","caller":"traceutil/trace.go:171","msg":"trace[586451739] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:12800; }","duration":"202.775141ms","start":"2026-04-18T17:27:24.604572Z","end":"2026-04-18T17:27:24.807347Z","steps":["trace[586451739] 'agreement among raft nodes before linearized reading' (duration: 201.988202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:24.956333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.857712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T17:27:24.956481Z","caller":"traceutil/trace.go:171","msg":"trace[1025670956] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:12800; }","duration":"244.060267ms","start":"2026-04-18T17:27:24.712397Z","end":"2026-04-18T17:27:24.956458Z","steps":["trace[1025670956] 'agreement among raft nodes before linearized reading' (duration: 95.032781ms)","trace[1025670956] 'count revisions from in-memory index tree' (duration: 148.851022ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:27:25.023606Z","caller":"traceutil/trace.go:171","msg":"trace[1985265916] transaction","detail":"{read_only:false; response_revision:12801; number_of_response:1; }","duration":"204.826022ms","start":"2026-04-18T17:27:24.818737Z","end":"2026-04-18T17:27:25.023563Z","steps":["trace[1985265916] 'process raft request' (duration: 204.687338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:38.397423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.368007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T17:27:38.397559Z","caller":"traceutil/trace.go:171","msg":"trace[1901916467] linearizableReadLoop","detail":"{readStateIndex:13314; appliedIndex:13313; }","duration":"214.139436ms","start":"2026-04-18T17:27:38.183403Z","end":"2026-04-18T17:27:38.397542Z","steps":["trace[1901916467] 'read index received' (duration: 85.442452ms)","trace[1901916467] 'applied index is now lower than readState.Index' (duration: 128.695834ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:27:38.397569Z","caller":"traceutil/trace.go:171","msg":"trace[1226111963] transaction","detail":"{read_only:false; response_revision:12912; number_of_response:1; }","duration":"233.845031ms","start":"2026-04-18T17:27:38.163698Z","end":"2026-04-18T17:27:38.397543Z","steps":["trace[1226111963] 'process raft request' (duration: 105.210858ms)","trace[1226111963] 'compare' (duration: 127.916105ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:27:38.397675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.249941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:27:38.39771Z","caller":"traceutil/trace.go:171","msg":"trace[255717002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12912; }","duration":"137.313712ms","start":"2026-04-18T17:27:38.260388Z","end":"2026-04-18T17:27:38.397702Z","steps":["trace[255717002] 'agreement among raft nodes before linearized reading' (duration: 137.2504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:38.397762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.346471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-18T17:27:38.397846Z","caller":"traceutil/trace.go:171","msg":"trace[1970277043] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12912; }","duration":"214.474904ms","start":"2026-04-18T17:27:38.183354Z","end":"2026-04-18T17:27:38.397829Z","steps":["trace[1970277043] 'agreement among raft nodes before linearized reading' (duration: 214.264449ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:38.876625Z","caller":"traceutil/trace.go:171","msg":"trace[1324232144] linearizableReadLoop","detail":"{readStateIndex:13315; appliedIndex:13314; }","duration":"190.029914ms","start":"2026-04-18T17:27:38.686572Z","end":"2026-04-18T17:27:38.876602Z","steps":["trace[1324232144] 'read index received' (duration: 189.84097ms)","trace[1324232144] 'applied index is now lower than readState.Index' (duration: 187.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:27:38.876669Z","caller":"traceutil/trace.go:171","msg":"trace[1808859693] transaction","detail":"{read_only:false; response_revision:12913; number_of_response:1; }","duration":"192.966995ms","start":"2026-04-18T17:27:38.68367Z","end":"2026-04-18T17:27:38.876637Z","steps":["trace[1808859693] 'process raft request' (duration: 192.791931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:38.876913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.319651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-18T17:27:38.876956Z","caller":"traceutil/trace.go:171","msg":"trace[2094265173] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12913; }","duration":"190.398273ms","start":"2026-04-18T17:27:38.686546Z","end":"2026-04-18T17:27:38.876945Z","steps":["trace[2094265173] 'agreement among raft nodes before linearized reading' (duration: 190.159777ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:38.894255Z","caller":"traceutil/trace.go:171","msg":"trace[660458234] transaction","detail":"{read_only:false; response_revision:12914; number_of_response:1; }","duration":"205.367454ms","start":"2026-04-18T17:27:38.688849Z","end":"2026-04-18T17:27:38.894216Z","steps":["trace[660458234] 'process raft request' (duration: 205.156219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:38.894288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.964148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-18T17:27:38.894411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.301899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T17:27:38.894442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.48829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T17:27:38.894493Z","caller":"traceutil/trace.go:171","msg":"trace[932529794] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:12914; }","duration":"129.421772ms","start":"2026-04-18T17:27:38.765046Z","end":"2026-04-18T17:27:38.894468Z","steps":["trace[932529794] 'agreement among raft nodes before linearized reading' (duration: 129.267788ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:38.894478Z","caller":"traceutil/trace.go:171","msg":"trace[755093335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12914; }","duration":"201.165722ms","start":"2026-04-18T17:27:38.69328Z","end":"2026-04-18T17:27:38.894446Z","steps":["trace[755093335] 'agreement among raft nodes before linearized reading' (duration: 200.852575ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:38.894525Z","caller":"traceutil/trace.go:171","msg":"trace[1666790187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12914; }","duration":"116.624134ms","start":"2026-04-18T17:27:38.777879Z","end":"2026-04-18T17:27:38.894503Z","steps":["trace[1666790187] 'agreement among raft nodes before linearized reading' (duration: 116.356517ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:27:39.269004Z","caller":"traceutil/trace.go:171","msg":"trace[1335996685] linearizableReadLoop","detail":"{readStateIndex:13320; appliedIndex:13318; }","duration":"114.901412ms","start":"2026-04-18T17:27:39.154075Z","end":"2026-04-18T17:27:39.268976Z","steps":["trace[1335996685] 'read index received' (duration: 78.109535ms)","trace[1335996685] 'applied index is now lower than readState.Index' (duration: 36.790567ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:27:39.269088Z","caller":"traceutil/trace.go:171","msg":"trace[1638707007] transaction","detail":"{read_only:false; response_revision:12918; number_of_response:1; }","duration":"133.540752ms","start":"2026-04-18T17:27:39.135518Z","end":"2026-04-18T17:27:39.269058Z","steps":["trace[1638707007] 'process raft request' (duration: 133.332027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:39.269271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.24165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T17:27:39.26939Z","caller":"traceutil/trace.go:171","msg":"trace[1831103492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12918; }","duration":"115.431624ms","start":"2026-04-18T17:27:39.153937Z","end":"2026-04-18T17:27:39.269369Z","steps":["trace[1831103492] 'agreement among raft nodes before linearized reading' (duration: 115.154618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:27:39.269716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.388131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-18T17:27:39.269848Z","caller":"traceutil/trace.go:171","msg":"trace[245117774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12918; }","duration":"112.557695ms","start":"2026-04-18T17:27:39.157271Z","end":"2026-04-18T17:27:39.269829Z","steps":["trace[245117774] 'agreement among raft nodes before linearized reading' (duration: 112.084134ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:35.281491Z","caller":"traceutil/trace.go:171","msg":"trace[1009300606] transaction","detail":"{read_only:false; response_revision:13312; number_of_response:1; }","duration":"181.505748ms","start":"2026-04-18T17:28:35.099961Z","end":"2026-04-18T17:28:35.281467Z","steps":["trace[1009300606] 'process raft request' (duration: 94.036408ms)","trace[1009300606] 'compare' (duration: 87.178943ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:28:35.585286Z","caller":"traceutil/trace.go:171","msg":"trace[1290810368] linearizableReadLoop","detail":"{readStateIndex:13732; appliedIndex:13731; }","duration":"139.268619ms","start":"2026-04-18T17:28:35.445992Z","end":"2026-04-18T17:28:35.585261Z","steps":["trace[1290810368] 'read index received' (duration: 102.38976ms)","trace[1290810368] 'applied index is now lower than readState.Index' (duration: 36.878219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:28:35.585424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.406153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:28:35.585477Z","caller":"traceutil/trace.go:171","msg":"trace[1005459958] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13316; }","duration":"139.508365ms","start":"2026-04-18T17:28:35.445951Z","end":"2026-04-18T17:28:35.585459Z","steps":["trace[1005459958] 'agreement among raft nodes before linearized reading' (duration: 139.390722ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:35.585394Z","caller":"traceutil/trace.go:171","msg":"trace[871886556] transaction","detail":"{read_only:false; response_revision:13316; number_of_response:1; }","duration":"142.753073ms","start":"2026-04-18T17:28:35.442557Z","end":"2026-04-18T17:28:35.58531Z","steps":["trace[871886556] 'process raft request' (duration: 105.875154ms)","trace[871886556] 'compare' (duration: 36.693565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:28:35.585554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.324911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:1 size:4839"} {"level":"info","ts":"2026-04-18T17:28:35.585593Z","caller":"traceutil/trace.go:171","msg":"trace[595574591] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:1; response_revision:13316; }","duration":"139.394163ms","start":"2026-04-18T17:28:35.446189Z","end":"2026-04-18T17:28:35.585583Z","steps":["trace[595574591] 'agreement among raft nodes before linearized reading' (duration: 139.2589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:35.585739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.633314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-18T17:28:35.585835Z","caller":"traceutil/trace.go:171","msg":"trace[1802134071] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13316; }","duration":"138.779538ms","start":"2026-04-18T17:28:35.447035Z","end":"2026-04-18T17:28:35.585814Z","steps":["trace[1802134071] 'agreement among raft nodes before linearized reading' (duration: 138.470071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:35.586139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.707171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-04-18T17:28:35.586222Z","caller":"traceutil/trace.go:171","msg":"trace[1978920103] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:13316; }","duration":"137.815965ms","start":"2026-04-18T17:28:35.448389Z","end":"2026-04-18T17:28:35.586205Z","steps":["trace[1978920103] 'agreement among raft nodes before linearized reading' (duration: 137.074977ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:36.455875Z","caller":"traceutil/trace.go:171","msg":"trace[1617261661] linearizableReadLoop","detail":"{readStateIndex:13742; appliedIndex:13741; }","duration":"160.992354ms","start":"2026-04-18T17:28:36.294862Z","end":"2026-04-18T17:28:36.455854Z","steps":["trace[1617261661] 'read index received' (duration: 116.671985ms)","trace[1617261661] 'applied index is now lower than readState.Index' (duration: 44.319739ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:28:36.456222Z","caller":"traceutil/trace.go:171","msg":"trace[663603082] transaction","detail":"{read_only:false; response_revision:13326; number_of_response:1; }","duration":"163.396252ms","start":"2026-04-18T17:28:36.292799Z","end":"2026-04-18T17:28:36.456196Z","steps":["trace[663603082] 'process raft request' (duration: 118.781576ms)","trace[663603082] 'compare' (duration: 44.147815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:28:36.456528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.0695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:1 size:4839"} {"level":"warn","ts":"2026-04-18T17:28:36.456596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.731466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-18T17:28:36.456631Z","caller":"traceutil/trace.go:171","msg":"trace[1451098668] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:1; response_revision:13326; }","duration":"133.172783ms","start":"2026-04-18T17:28:36.32343Z","end":"2026-04-18T17:28:36.456603Z","steps":["trace[1451098668] 'agreement among raft nodes before linearized reading' (duration: 132.928167ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:36.456673Z","caller":"traceutil/trace.go:171","msg":"trace[754190006] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13326; }","duration":"133.867679ms","start":"2026-04-18T17:28:36.322788Z","end":"2026-04-18T17:28:36.456655Z","steps":["trace[754190006] 'agreement among raft nodes before linearized reading' (duration: 133.612453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:36.456871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.642931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:932401"} {"level":"info","ts":"2026-04-18T17:28:36.456908Z","caller":"traceutil/trace.go:171","msg":"trace[1371506819] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:13326; }","duration":"159.736143ms","start":"2026-04-18T17:28:36.297164Z","end":"2026-04-18T17:28:36.4569Z","steps":["trace[1371506819] 'agreement among raft nodes before linearized reading' (duration: 158.909403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:36.458765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.886624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:932401"} {"level":"info","ts":"2026-04-18T17:28:36.458912Z","caller":"traceutil/trace.go:171","msg":"trace[673994729] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:13326; }","duration":"164.045718ms","start":"2026-04-18T17:28:36.294818Z","end":"2026-04-18T17:28:36.458864Z","steps":["trace[673994729] 'agreement among raft nodes before linearized reading' (duration: 161.183589ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:37.301441Z","caller":"traceutil/trace.go:171","msg":"trace[1993017633] transaction","detail":"{read_only:false; response_revision:13333; number_of_response:1; }","duration":"124.81827ms","start":"2026-04-18T17:28:37.176589Z","end":"2026-04-18T17:28:37.301408Z","steps":["trace[1993017633] 'process raft request' (duration: 93.123756ms)","trace[1993017633] 'compare' (duration: 31.257144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:28:44.24232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.523514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:28:44.242445Z","caller":"traceutil/trace.go:171","msg":"trace[648180041] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13399; }","duration":"119.679017ms","start":"2026-04-18T17:28:44.122739Z","end":"2026-04-18T17:28:44.242418Z","steps":["trace[648180041] 'range keys from in-memory index tree' (duration: 119.461762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:44.242569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.137154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T17:28:44.24264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.938417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:28:44.242666Z","caller":"traceutil/trace.go:171","msg":"trace[876045352] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13399; }","duration":"313.282978ms","start":"2026-04-18T17:28:43.929365Z","end":"2026-04-18T17:28:44.242648Z","steps":["trace[876045352] 'range keys from in-memory index tree' (duration: 313.043272ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:44.242703Z","caller":"traceutil/trace.go:171","msg":"trace[342062847] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13399; }","duration":"121.04095ms","start":"2026-04-18T17:28:44.121643Z","end":"2026-04-18T17:28:44.242684Z","steps":["trace[342062847] 'range keys from in-memory index tree' (duration: 120.863006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:44.242711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:28:43.929341Z","time spent":"313.361749ms","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-04-18T17:28:44.242883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.467092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:28:44.242959Z","caller":"traceutil/trace.go:171","msg":"trace[459155778] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13399; }","duration":"313.595555ms","start":"2026-04-18T17:28:43.929341Z","end":"2026-04-18T17:28:44.242937Z","steps":["trace[459155778] 'range keys from in-memory index tree' (duration: 313.342489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:44.242999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:28:43.929296Z","time spent":"313.694358ms","remote":"127.0.0.1:38188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-04-18T17:28:49.199095Z","caller":"traceutil/trace.go:171","msg":"trace[1355788268] transaction","detail":"{read_only:false; response_revision:13428; number_of_response:1; }","duration":"173.268969ms","start":"2026-04-18T17:28:49.0258Z","end":"2026-04-18T17:28:49.199069Z","steps":["trace[1355788268] 'process raft request' (duration: 173.087774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:49.591649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.441238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T17:28:49.591743Z","caller":"traceutil/trace.go:171","msg":"trace[2142067796] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13428; }","duration":"162.590642ms","start":"2026-04-18T17:28:49.429125Z","end":"2026-04-18T17:28:49.591716Z","steps":["trace[2142067796] 'count revisions from in-memory index tree' (duration: 162.330556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:49.592002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.217044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:28:49.59207Z","caller":"traceutil/trace.go:171","msg":"trace[855463522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13428; }","duration":"332.320647ms","start":"2026-04-18T17:28:49.259734Z","end":"2026-04-18T17:28:49.592055Z","steps":["trace[855463522] 'range keys from in-memory index tree' (duration: 332.130992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:49.592125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:28:49.259713Z","time spent":"332.404189ms","remote":"127.0.0.1:37986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T17:28:49.592286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.233577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-04-18T17:28:49.592313Z","caller":"traceutil/trace.go:171","msg":"trace[1788799038] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:13428; }","duration":"352.845132ms","start":"2026-04-18T17:28:49.239457Z","end":"2026-04-18T17:28:49.592302Z","steps":["trace[1788799038] 'range keys from in-memory index tree' (duration: 352.096063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:49.592374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:28:49.23944Z","time spent":"352.886272ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-04-18T17:28:49.638457Z","caller":"traceutil/trace.go:171","msg":"trace[917241008] transaction","detail":"{read_only:false; response_revision:13429; number_of_response:1; }","duration":"176.541638ms","start":"2026-04-18T17:28:49.461888Z","end":"2026-04-18T17:28:49.638429Z","steps":["trace[917241008] 'process raft request' (duration: 176.316373ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:49.665888Z","caller":"traceutil/trace.go:171","msg":"trace[1446230160] transaction","detail":"{read_only:false; response_revision:13430; number_of_response:1; }","duration":"189.511342ms","start":"2026-04-18T17:28:49.47635Z","end":"2026-04-18T17:28:49.665861Z","steps":["trace[1446230160] 'process raft request' (duration: 189.096912ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:49.782341Z","caller":"traceutil/trace.go:171","msg":"trace[993450022] transaction","detail":"{read_only:false; response_revision:13432; number_of_response:1; }","duration":"104.693657ms","start":"2026-04-18T17:28:49.677626Z","end":"2026-04-18T17:28:49.782319Z","steps":["trace[993450022] 'process raft request' (duration: 40.867807ms)","trace[993450022] 'compare' (duration: 63.40795ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:28:49.782501Z","caller":"traceutil/trace.go:171","msg":"trace[1852850536] transaction","detail":"{read_only:false; response_revision:13433; number_of_response:1; }","duration":"104.778718ms","start":"2026-04-18T17:28:49.677693Z","end":"2026-04-18T17:28:49.782472Z","steps":["trace[1852850536] 'process raft request' (duration: 104.558083ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:49.78302Z","caller":"traceutil/trace.go:171","msg":"trace[1315508535] linearizableReadLoop","detail":"{readStateIndex:13852; appliedIndex:13850; }","duration":"101.445777ms","start":"2026-04-18T17:28:49.681525Z","end":"2026-04-18T17:28:49.782971Z","steps":["trace[1315508535] 'read index received' (duration: 6.337113ms)","trace[1315508535] 'applied index is now lower than readState.Index' (duration: 95.107634ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:28:49.783251Z","caller":"traceutil/trace.go:171","msg":"trace[1788910800] transaction","detail":"{read_only:false; response_revision:13434; number_of_response:1; }","duration":"101.688872ms","start":"2026-04-18T17:28:49.681536Z","end":"2026-04-18T17:28:49.783225Z","steps":["trace[1788910800] 'process raft request' (duration: 100.897223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:49.783485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.902047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-04-18T17:28:49.783369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.814746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T17:28:49.783508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.850576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-04-18T17:28:49.783536Z","caller":"traceutil/trace.go:171","msg":"trace[615111117] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13434; }","duration":"101.975659ms","start":"2026-04-18T17:28:49.681542Z","end":"2026-04-18T17:28:49.783518Z","steps":["trace[615111117] 'agreement among raft nodes before linearized reading' (duration: 101.797965ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:49.783584Z","caller":"traceutil/trace.go:171","msg":"trace[311031058] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:13434; }","duration":"101.973389ms","start":"2026-04-18T17:28:49.681593Z","end":"2026-04-18T17:28:49.783566Z","steps":["trace[311031058] 'agreement among raft nodes before linearized reading' (duration: 101.761404ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:49.783554Z","caller":"traceutil/trace.go:171","msg":"trace[1607749387] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13434; }","duration":"102.03454ms","start":"2026-04-18T17:28:49.681499Z","end":"2026-04-18T17:28:49.783534Z","steps":["trace[1607749387] 'agreement among raft nodes before linearized reading' (duration: 101.726873ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:50.174073Z","caller":"traceutil/trace.go:171","msg":"trace[1132229575] linearizableReadLoop","detail":"{readStateIndex:13856; appliedIndex:13855; }","duration":"153.610886ms","start":"2026-04-18T17:28:50.02044Z","end":"2026-04-18T17:28:50.174051Z","steps":["trace[1132229575] 'read index received' (duration: 139.034024ms)","trace[1132229575] 'applied index is now lower than readState.Index' (duration: 14.575902ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:28:50.174189Z","caller":"traceutil/trace.go:171","msg":"trace[2017103566] transaction","detail":"{read_only:false; response_revision:13437; number_of_response:1; }","duration":"155.572133ms","start":"2026-04-18T17:28:50.018606Z","end":"2026-04-18T17:28:50.174178Z","steps":["trace[2017103566] 'process raft request' (duration: 140.837407ms)","trace[2017103566] 'compare' (duration: 14.394897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:28:50.174366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.618579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-js46f\" ","response":"range_response_count:1 size:9709"} {"level":"info","ts":"2026-04-18T17:28:50.174437Z","caller":"traceutil/trace.go:171","msg":"trace[870293236] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-js46f; range_end:; response_count:1; response_revision:13437; }","duration":"149.724332ms","start":"2026-04-18T17:28:50.0247Z","end":"2026-04-18T17:28:50.174425Z","steps":["trace[870293236] 'agreement among raft nodes before linearized reading' (duration: 149.565578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:50.174597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.041561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ns6sh\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-04-18T17:28:50.174638Z","caller":"traceutil/trace.go:171","msg":"trace[1828624513] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ns6sh; range_end:; response_count:1; response_revision:13437; }","duration":"148.115053ms","start":"2026-04-18T17:28:50.026518Z","end":"2026-04-18T17:28:50.174633Z","steps":["trace[1828624513] 'agreement among raft nodes before linearized reading' (duration: 148.039451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:50.174718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.261491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-18T17:28:50.174766Z","caller":"traceutil/trace.go:171","msg":"trace[1737359165] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13437; }","duration":"154.340743ms","start":"2026-04-18T17:28:50.020411Z","end":"2026-04-18T17:28:50.174752Z","steps":["trace[1737359165] 'agreement among raft nodes before linearized reading' (duration: 154.188689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:50.486713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.105365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mk2xf\" ","response":"range_response_count:1 size:20668"} {"level":"warn","ts":"2026-04-18T17:28:50.48708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.320915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-18T17:28:50.487193Z","caller":"traceutil/trace.go:171","msg":"trace[1562746302] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:13438; }","duration":"208.488698ms","start":"2026-04-18T17:28:50.278677Z","end":"2026-04-18T17:28:50.487166Z","steps":["trace[1562746302] 'range keys from in-memory index tree' (duration: 208.084559ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:50.486825Z","caller":"traceutil/trace.go:171","msg":"trace[1407729794] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-mk2xf; range_end:; response_count:1; response_revision:13438; }","duration":"110.28774ms","start":"2026-04-18T17:28:50.376509Z","end":"2026-04-18T17:28:50.486796Z","steps":["trace[1407729794] 'range keys from in-memory index tree' (duration: 109.861419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:55.748452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.934931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-18T17:28:55.748534Z","caller":"traceutil/trace.go:171","msg":"trace[1925368113] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13458; }","duration":"253.069354ms","start":"2026-04-18T17:28:55.495446Z","end":"2026-04-18T17:28:55.748516Z","steps":["trace[1925368113] 'range keys from in-memory index tree' (duration: 252.738706ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:55.924237Z","caller":"traceutil/trace.go:171","msg":"trace[433528786] linearizableReadLoop","detail":"{readStateIndex:13879; appliedIndex:13878; }","duration":"172.122071ms","start":"2026-04-18T17:28:55.75209Z","end":"2026-04-18T17:28:55.924212Z","steps":["trace[433528786] 'read index received' (duration: 172.06209ms)","trace[433528786] 'applied index is now lower than readState.Index' (duration: 58.791µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:28:55.924422Z","caller":"traceutil/trace.go:171","msg":"trace[826939252] transaction","detail":"{read_only:false; response_revision:13459; number_of_response:1; }","duration":"179.393327ms","start":"2026-04-18T17:28:55.744997Z","end":"2026-04-18T17:28:55.924391Z","steps":["trace[826939252] 'process raft request' (duration: 179.051989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:55.924518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.422229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-18T17:28:55.924555Z","caller":"traceutil/trace.go:171","msg":"trace[138609498] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13459; }","duration":"172.512741ms","start":"2026-04-18T17:28:55.752033Z","end":"2026-04-18T17:28:55.924545Z","steps":["trace[138609498] 'agreement among raft nodes before linearized reading' (duration: 172.379438ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:56.07641Z","caller":"traceutil/trace.go:171","msg":"trace[1027757295] linearizableReadLoop","detail":"{readStateIndex:13882; appliedIndex:13879; }","duration":"151.998516ms","start":"2026-04-18T17:28:55.924376Z","end":"2026-04-18T17:28:56.076374Z","steps":["trace[1027757295] 'read index received' (duration: 151.666748ms)","trace[1027757295] 'applied index is now lower than readState.Index' (duration: 330.408µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:28:56.076518Z","caller":"traceutil/trace.go:171","msg":"trace[49400154] transaction","detail":"{read_only:false; response_revision:13461; number_of_response:1; }","duration":"238.247168ms","start":"2026-04-18T17:28:55.83823Z","end":"2026-04-18T17:28:56.076477Z","steps":["trace[49400154] 'process raft request' (duration: 238.031342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:56.076605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.081824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T17:28:56.076705Z","caller":"traceutil/trace.go:171","msg":"trace[1164084350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13462; }","duration":"158.227967ms","start":"2026-04-18T17:28:55.918455Z","end":"2026-04-18T17:28:56.076683Z","steps":["trace[1164084350] 'agreement among raft nodes before linearized reading' (duration: 157.966379ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:56.076613Z","caller":"traceutil/trace.go:171","msg":"trace[445045957] transaction","detail":"{read_only:false; response_revision:13460; number_of_response:1; }","duration":"323.281359ms","start":"2026-04-18T17:28:55.753291Z","end":"2026-04-18T17:28:56.076572Z","steps":["trace[445045957] 'process raft request' (duration: 322.738345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:56.077066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.533466ms","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-18T17:28:56.077161Z","caller":"traceutil/trace.go:171","msg":"trace[1125592435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13462; }","duration":"173.868775ms","start":"2026-04-18T17:28:55.903271Z","end":"2026-04-18T17:28:56.07714Z","steps":["trace[1125592435] 'agreement among raft nodes before linearized reading' (duration: 173.447855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:56.076783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.340562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-04-18T17:28:56.077233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:28:55.753271Z","time spent":"323.579756ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T17:28:56.077178Z","caller":"traceutil/trace.go:171","msg":"trace[907061012] transaction","detail":"{read_only:false; response_revision:13462; number_of_response:1; }","duration":"156.488855ms","start":"2026-04-18T17:28:55.920367Z","end":"2026-04-18T17:28:56.076856Z","steps":["trace[907061012] 'process raft request' (duration: 155.953521ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:28:56.077256Z","caller":"traceutil/trace.go:171","msg":"trace[221641885] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13462; }","duration":"280.801643ms","start":"2026-04-18T17:28:55.796433Z","end":"2026-04-18T17:28:56.077235Z","steps":["trace[221641885] 'agreement among raft nodes before linearized reading' (duration: 280.231869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:56.077086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.014103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18810"} {"level":"info","ts":"2026-04-18T17:28:56.077602Z","caller":"traceutil/trace.go:171","msg":"trace[436386895] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:13462; }","duration":"151.761551ms","start":"2026-04-18T17:28:55.925823Z","end":"2026-04-18T17:28:56.077585Z","steps":["trace[436386895] 'agreement among raft nodes before linearized reading' (duration: 150.894051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:56.076714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.068775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-04-18T17:28:56.077731Z","caller":"traceutil/trace.go:171","msg":"trace[69139533] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:13462; }","duration":"191.241313ms","start":"2026-04-18T17:28:55.886468Z","end":"2026-04-18T17:28:56.077709Z","steps":["trace[69139533] 'agreement among raft nodes before linearized reading' (duration: 190.007044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:28:56.076631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.340548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T17:28:56.077868Z","caller":"traceutil/trace.go:171","msg":"trace[817114515] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:13462; }","duration":"275.629248ms","start":"2026-04-18T17:28:55.802226Z","end":"2026-04-18T17:28:56.077855Z","steps":["trace[817114515] 'agreement among raft nodes before linearized reading' (duration: 274.331128ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:29:32.972776Z","caller":"traceutil/trace.go:171","msg":"trace[633920075] transaction","detail":"{read_only:false; response_revision:13724; number_of_response:1; }","duration":"104.854096ms","start":"2026-04-18T17:29:32.867864Z","end":"2026-04-18T17:29:32.972718Z","steps":["trace[633920075] 'process raft request' (duration: 103.73396ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:32:06.079914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12300} {"level":"info","ts":"2026-04-18T17:32:06.206494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12300,"took":"121.175247ms","hash":582933779,"current-db-size-bytes":57692160,"current-db-size":"58 MB","current-db-size-in-use-bytes":32567296,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-18T17:32:06.206606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":582933779,"revision":12300,"compact-revision":9249} {"level":"info","ts":"2026-04-18T17:33:47.925465Z","caller":"traceutil/trace.go:171","msg":"trace[504988052] linearizableReadLoop","detail":"{readStateIndex:16203; appliedIndex:16202; }","duration":"168.052129ms","start":"2026-04-18T17:33:47.757381Z","end":"2026-04-18T17:33:47.925434Z","steps":["trace[504988052] 'read index received' (duration: 122.545657ms)","trace[504988052] 'applied index is now lower than readState.Index' (duration: 45.505152ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:33:47.925589Z","caller":"traceutil/trace.go:171","msg":"trace[633585957] transaction","detail":"{read_only:false; response_revision:15710; number_of_response:1; }","duration":"168.716964ms","start":"2026-04-18T17:33:47.75677Z","end":"2026-04-18T17:33:47.925487Z","steps":["trace[633585957] 'process raft request' (duration: 123.13253ms)","trace[633585957] 'compare' (duration: 45.353399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:33:47.925709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.299035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T17:33:47.926579Z","caller":"traceutil/trace.go:171","msg":"trace[1740951147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15710; }","duration":"169.176244ms","start":"2026-04-18T17:33:47.757351Z","end":"2026-04-18T17:33:47.926527Z","steps":["trace[1740951147] 'agreement among raft nodes before linearized reading' (duration: 168.203872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:47.926636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.362217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-mk2xf\" ","response":"range_response_count:1 size:20769"} {"level":"info","ts":"2026-04-18T17:33:47.926695Z","caller":"traceutil/trace.go:171","msg":"trace[947554196] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-mk2xf; range_end:; response_count:1; response_revision:15710; }","duration":"160.452148ms","start":"2026-04-18T17:33:47.766224Z","end":"2026-04-18T17:33:47.926676Z","steps":["trace[947554196] 'agreement among raft nodes before linearized reading' (duration: 160.292973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:47.926704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.535541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-js46f\" ","response":"range_response_count:1 size:9709"} {"level":"info","ts":"2026-04-18T17:33:47.92677Z","caller":"traceutil/trace.go:171","msg":"trace[1120213556] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-js46f; range_end:; response_count:1; response_revision:15710; }","duration":"126.631734ms","start":"2026-04-18T17:33:47.800119Z","end":"2026-04-18T17:33:47.926751Z","steps":["trace[1120213556] 'agreement among raft nodes before linearized reading' (duration: 126.451268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:47.926786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.13121ms","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-18T17:33:47.92684Z","caller":"traceutil/trace.go:171","msg":"trace[326897536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15710; }","duration":"144.218761ms","start":"2026-04-18T17:33:47.782607Z","end":"2026-04-18T17:33:47.926825Z","steps":["trace[326897536] 'agreement among raft nodes before linearized reading' (duration: 144.023327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:48.193083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.542297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T17:33:48.193199Z","caller":"traceutil/trace.go:171","msg":"trace[1352452654] transaction","detail":"{read_only:false; response_revision:15711; number_of_response:1; }","duration":"260.346305ms","start":"2026-04-18T17:33:47.932829Z","end":"2026-04-18T17:33:48.193176Z","steps":["trace[1352452654] 'process raft request' (duration: 116.626635ms)","trace[1352452654] 'compare' (duration: 143.329841ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:33:48.288292Z","caller":"traceutil/trace.go:171","msg":"trace[1718147287] transaction","detail":"{read_only:false; response_revision:15712; number_of_response:1; }","duration":"355.344014ms","start":"2026-04-18T17:33:47.932916Z","end":"2026-04-18T17:33:48.28826Z","steps":["trace[1718147287] 'process raft request' (duration: 355.097708ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:33:48.288434Z","caller":"traceutil/trace.go:171","msg":"trace[634262434] linearizableReadLoop","detail":"{readStateIndex:16205; appliedIndex:16203; }","duration":"354.136837ms","start":"2026-04-18T17:33:47.934273Z","end":"2026-04-18T17:33:48.28841Z","steps":["trace[634262434] 'read index received' (duration: 115.15141ms)","trace[634262434] 'applied index is now lower than readState.Index' (duration: 238.983207ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:33:48.288548Z","caller":"traceutil/trace.go:171","msg":"trace[1554346700] transaction","detail":"{read_only:false; response_revision:15713; number_of_response:1; }","duration":"345.383469ms","start":"2026-04-18T17:33:47.943151Z","end":"2026-04-18T17:33:48.288534Z","steps":["trace[1554346700] 'process raft request' (duration: 345.01766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:48.288638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:47.932876Z","time spent":"355.508718ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T17:33:48.288646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:47.94314Z","time spent":"345.45008ms","remote":"127.0.0.1:38138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20888,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T17:33:48.28872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.23746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-18T17:33:48.288814Z","caller":"traceutil/trace.go:171","msg":"trace[1028839719] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15713; }","duration":"320.376844ms","start":"2026-04-18T17:33:47.968412Z","end":"2026-04-18T17:33:48.288789Z","steps":["trace[1028839719] 'agreement among raft nodes before linearized reading' (duration: 320.143798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:48.288863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:47.968393Z","time spent":"320.459985ms","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-18T17:33:48.289156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.467917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-18T17:33:48.289248Z","caller":"traceutil/trace.go:171","msg":"trace[1612870467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15713; }","duration":"236.60957ms","start":"2026-04-18T17:33:48.052619Z","end":"2026-04-18T17:33:48.289229Z","steps":["trace[1612870467] 'agreement among raft nodes before linearized reading' (duration: 236.401975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:48.290993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.702458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:113 size:1103779"} {"level":"info","ts":"2026-04-18T17:33:48.291058Z","caller":"traceutil/trace.go:171","msg":"trace[1549216244] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:113; response_revision:15713; }","duration":"356.793619ms","start":"2026-04-18T17:33:47.93425Z","end":"2026-04-18T17:33:48.291044Z","steps":["trace[1549216244] 'agreement among raft nodes before linearized reading' (duration: 354.795012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:48.291101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:47.934241Z","time spent":"356.846101ms","remote":"127.0.0.1:38138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":113,"response size":1103800,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-18T17:33:48.873214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.830256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T17:33:48.873383Z","caller":"traceutil/trace.go:171","msg":"trace[1826130606] linearizableReadLoop","detail":"{readStateIndex:16207; appliedIndex:16206; }","duration":"452.582067ms","start":"2026-04-18T17:33:48.420779Z","end":"2026-04-18T17:33:48.873361Z","steps":["trace[1826130606] 'read index received' (duration: 42.191µs)","trace[1826130606] 'applied index is now lower than readState.Index' (duration: 452.537746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:33:48.873644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.853525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dgfqz\" ","response":"range_response_count:1 size:22013"} {"level":"info","ts":"2026-04-18T17:33:48.873716Z","caller":"traceutil/trace.go:171","msg":"trace[1605989820] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dgfqz; range_end:; response_count:1; response_revision:15714; }","duration":"452.962227ms","start":"2026-04-18T17:33:48.420736Z","end":"2026-04-18T17:33:48.873699Z","steps":["trace[1605989820] 'agreement among raft nodes before linearized reading' (duration: 452.738142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:48.873768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:48.420717Z","time spent":"453.034238ms","remote":"127.0.0.1:38138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22034,"request content":"key:\"/registry/pods/openstack/nova-compute-default-dgfqz\" "} {"level":"info","ts":"2026-04-18T17:33:48.874023Z","caller":"traceutil/trace.go:171","msg":"trace[1935203382] transaction","detail":"{read_only:false; response_revision:15714; number_of_response:1; }","duration":"573.345041ms","start":"2026-04-18T17:33:48.300654Z","end":"2026-04-18T17:33:48.873999Z","steps":["trace[1935203382] 'process raft request' (duration: 26.614663ms)","trace[1935203382] 'compare' (duration: 545.55573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:33:48.874128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:48.300633Z","time spent":"573.443154ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T17:33:49.466023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.16202ms","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-18T17:33:49.466084Z","caller":"traceutil/trace.go:171","msg":"trace[91516136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15714; }","duration":"413.264753ms","start":"2026-04-18T17:33:49.052807Z","end":"2026-04-18T17:33:49.466072Z","steps":["trace[91516136] 'range keys from in-memory index tree' (duration: 412.939015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:49.466112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.052787Z","time spent":"413.318744ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T17:33:49.466987Z","caller":"traceutil/trace.go:171","msg":"trace[1870427631] transaction","detail":"{read_only:false; response_revision:15715; number_of_response:1; }","duration":"342.694423ms","start":"2026-04-18T17:33:49.12428Z","end":"2026-04-18T17:33:49.466975Z","steps":["trace[1870427631] 'process raft request' (duration: 342.373825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:49.467269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.124256Z","time spent":"342.769155ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T17:33:49.745174Z","caller":"traceutil/trace.go:171","msg":"trace[229606590] transaction","detail":"{read_only:false; response_revision:15716; number_of_response:1; }","duration":"520.212468ms","start":"2026-04-18T17:33:49.224922Z","end":"2026-04-18T17:33:49.745135Z","steps":["trace[229606590] 'process raft request' (duration: 489.379034ms)","trace[229606590] 'compare' (duration: 30.6645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:33:49.745361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.22489Z","time spent":"520.368242ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T17:33:49.745356Z","caller":"traceutil/trace.go:171","msg":"trace[1749781152] linearizableReadLoop","detail":"{readStateIndex:16209; appliedIndex:16207; }","duration":"482.737746ms","start":"2026-04-18T17:33:49.262564Z","end":"2026-04-18T17:33:49.745302Z","steps":["trace[1749781152] 'read index received' (duration: 203.977213ms)","trace[1749781152] 'applied index is now lower than readState.Index' (duration: 278.759483ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:33:49.745639Z","caller":"traceutil/trace.go:171","msg":"trace[1886400742] transaction","detail":"{read_only:false; response_revision:15717; number_of_response:1; }","duration":"328.958687ms","start":"2026-04-18T17:33:49.416668Z","end":"2026-04-18T17:33:49.745626Z","steps":["trace[1886400742] 'process raft request' (duration: 328.425215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:49.745703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.416641Z","time spent":"329.028749ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T17:33:49.745694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.104435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-04-18T17:33:49.745804Z","caller":"traceutil/trace.go:171","msg":"trace[141131512] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:15717; }","duration":"483.243928ms","start":"2026-04-18T17:33:49.26253Z","end":"2026-04-18T17:33:49.745774Z","steps":["trace[141131512] 'agreement among raft nodes before linearized reading' (duration: 482.965071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:49.745866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.262515Z","time spent":"483.336591ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-04-18T17:33:49.803503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.716394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T17:33:49.803599Z","caller":"traceutil/trace.go:171","msg":"trace[777506646] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:15720; }","duration":"300.844369ms","start":"2026-04-18T17:33:49.502732Z","end":"2026-04-18T17:33:49.803577Z","steps":["trace[777506646] 'agreement among raft nodes before linearized reading' (duration: 300.680654ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:33:49.803574Z","caller":"traceutil/trace.go:171","msg":"trace[1972805392] transaction","detail":"{read_only:false; response_revision:15719; number_of_response:1; }","duration":"300.88921ms","start":"2026-04-18T17:33:49.502656Z","end":"2026-04-18T17:33:49.803545Z","steps":["trace[1972805392] 'process raft request' (duration: 300.667034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:49.803641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.502725Z","time spent":"300.90487ms","remote":"127.0.0.1:50184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":7,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-18T17:33:49.80376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.502636Z","time spent":"301.024553ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T17:33:49.803833Z","caller":"traceutil/trace.go:171","msg":"trace[1182203298] transaction","detail":"{read_only:false; response_revision:15720; number_of_response:1; }","duration":"276.10329ms","start":"2026-04-18T17:33:49.527707Z","end":"2026-04-18T17:33:49.80381Z","steps":["trace[1182203298] 'process raft request' (duration: 275.663809ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:33:49.803574Z","caller":"traceutil/trace.go:171","msg":"trace[752597186] transaction","detail":"{read_only:false; response_revision:15718; number_of_response:1; }","duration":"333.494375ms","start":"2026-04-18T17:33:49.470021Z","end":"2026-04-18T17:33:49.803515Z","steps":["trace[752597186] 'process raft request' (duration: 333.174378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:33:49.804215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T17:33:49.470002Z","time spent":"334.079199ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T17:34:40.073051Z","caller":"traceutil/trace.go:171","msg":"trace[326693997] transaction","detail":"{read_only:false; response_revision:16350; number_of_response:1; }","duration":"113.909141ms","start":"2026-04-18T17:34:39.959125Z","end":"2026-04-18T17:34:40.073034Z","steps":["trace[326693997] 'process raft request' (duration: 113.785768ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:34:48.097397Z","caller":"traceutil/trace.go:171","msg":"trace[1538027975] transaction","detail":"{read_only:false; response_revision:16394; number_of_response:1; }","duration":"114.159875ms","start":"2026-04-18T17:34:47.983192Z","end":"2026-04-18T17:34:48.097352Z","steps":["trace[1538027975] 'process raft request' (duration: 113.702984ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:34:48.328598Z","caller":"traceutil/trace.go:171","msg":"trace[1990589131] transaction","detail":"{read_only:false; response_revision:16395; number_of_response:1; }","duration":"230.051553ms","start":"2026-04-18T17:34:48.098507Z","end":"2026-04-18T17:34:48.328559Z","steps":["trace[1990589131] 'process raft request' (duration: 179.215894ms)","trace[1990589131] 'compare' (duration: 50.506722ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:34:48.328669Z","caller":"traceutil/trace.go:171","msg":"trace[558728611] transaction","detail":"{read_only:false; response_revision:16396; number_of_response:1; }","duration":"187.084811ms","start":"2026-04-18T17:34:48.141552Z","end":"2026-04-18T17:34:48.328637Z","steps":["trace[558728611] 'process raft request' (duration: 186.882327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:34:48.32875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.223224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-18T17:34:48.328779Z","caller":"traceutil/trace.go:171","msg":"trace[1246902502] transaction","detail":"{read_only:false; response_revision:16397; number_of_response:1; }","duration":"187.068231ms","start":"2026-04-18T17:34:48.141692Z","end":"2026-04-18T17:34:48.32876Z","steps":["trace[1246902502] 'process raft request' (duration: 186.834986ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:34:48.328845Z","caller":"traceutil/trace.go:171","msg":"trace[379644074] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:16397; }","duration":"179.324417ms","start":"2026-04-18T17:34:48.149463Z","end":"2026-04-18T17:34:48.328787Z","steps":["trace[379644074] 'agreement among raft nodes before linearized reading' (duration: 179.193844ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:34:48.328602Z","caller":"traceutil/trace.go:171","msg":"trace[1131649477] linearizableReadLoop","detail":"{readStateIndex:16909; appliedIndex:16906; }","duration":"179.065801ms","start":"2026-04-18T17:34:48.14951Z","end":"2026-04-18T17:34:48.328576Z","steps":["trace[1131649477] 'read index received' (duration: 128.241522ms)","trace[1131649477] 'applied index is now lower than readState.Index' (duration: 50.823219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:34:48.32926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.252275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-18T17:34:48.329376Z","caller":"traceutil/trace.go:171","msg":"trace[1894592498] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:16397; }","duration":"129.362598ms","start":"2026-04-18T17:34:48.19994Z","end":"2026-04-18T17:34:48.329302Z","steps":["trace[1894592498] 'agreement among raft nodes before linearized reading' (duration: 129.245105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:34:48.329466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.719651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-18T17:34:48.329553Z","caller":"traceutil/trace.go:171","msg":"trace[1583615676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16397; }","duration":"154.882415ms","start":"2026-04-18T17:34:48.174644Z","end":"2026-04-18T17:34:48.329527Z","steps":["trace[1583615676] 'agreement among raft nodes before linearized reading' (duration: 154.522527ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:34:48.57223Z","caller":"traceutil/trace.go:171","msg":"trace[279773537] transaction","detail":"{read_only:false; response_revision:16398; number_of_response:1; }","duration":"237.008269ms","start":"2026-04-18T17:34:48.335182Z","end":"2026-04-18T17:34:48.57219Z","steps":["trace[279773537] 'process raft request' (duration: 199.173239ms)","trace[279773537] 'compare' (duration: 36.9504ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:34:48.573388Z","caller":"traceutil/trace.go:171","msg":"trace[1905378666] transaction","detail":"{read_only:false; response_revision:16399; number_of_response:1; }","duration":"238.007363ms","start":"2026-04-18T17:34:48.335359Z","end":"2026-04-18T17:34:48.573366Z","steps":["trace[1905378666] 'process raft request' (duration: 237.588913ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:26.61381Z","caller":"traceutil/trace.go:171","msg":"trace[93075201] transaction","detail":"{read_only:false; response_revision:16792; number_of_response:1; }","duration":"130.954976ms","start":"2026-04-18T17:35:26.48283Z","end":"2026-04-18T17:35:26.613785Z","steps":["trace[93075201] 'process raft request' (duration: 101.147747ms)","trace[93075201] 'compare' (duration: 29.697617ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:35:50.975799Z","caller":"traceutil/trace.go:171","msg":"trace[822131462] linearizableReadLoop","detail":"{readStateIndex:17454; appliedIndex:17453; }","duration":"159.482224ms","start":"2026-04-18T17:35:50.816286Z","end":"2026-04-18T17:35:50.975768Z","steps":["trace[822131462] 'read index received' (duration: 135.632737ms)","trace[822131462] 'applied index is now lower than readState.Index' (duration: 23.848437ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:35:50.97609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.770272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-18T17:35:50.976191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.02151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-18T17:35:50.976268Z","caller":"traceutil/trace.go:171","msg":"trace[199228459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16927; }","duration":"111.109703ms","start":"2026-04-18T17:35:50.865144Z","end":"2026-04-18T17:35:50.976253Z","steps":["trace[199228459] 'agreement among raft nodes before linearized reading' (duration: 110.890877ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:50.976272Z","caller":"traceutil/trace.go:171","msg":"trace[1051804912] transaction","detail":"{read_only:false; response_revision:16927; number_of_response:1; }","duration":"272.092244ms","start":"2026-04-18T17:35:50.704164Z","end":"2026-04-18T17:35:50.976256Z","steps":["trace[1051804912] 'process raft request' (duration: 247.844337ms)","trace[1051804912] 'compare' (duration: 23.340445ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:35:50.976188Z","caller":"traceutil/trace.go:171","msg":"trace[886552382] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:16927; }","duration":"159.918134ms","start":"2026-04-18T17:35:50.816244Z","end":"2026-04-18T17:35:50.976162Z","steps":["trace[886552382] 'agreement among raft nodes before linearized reading' (duration: 159.72913ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:51.19455Z","caller":"traceutil/trace.go:171","msg":"trace[409127146] transaction","detail":"{read_only:false; response_revision:16929; number_of_response:1; }","duration":"208.387348ms","start":"2026-04-18T17:35:50.986132Z","end":"2026-04-18T17:35:51.194519Z","steps":["trace[409127146] 'process raft request' (duration: 177.132225ms)","trace[409127146] 'compare' (duration: 31.073659ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:35:51.1948Z","caller":"traceutil/trace.go:171","msg":"trace[1390658303] transaction","detail":"{read_only:false; response_revision:16931; number_of_response:1; }","duration":"208.401378ms","start":"2026-04-18T17:35:50.986369Z","end":"2026-04-18T17:35:51.19477Z","steps":["trace[1390658303] 'process raft request' (duration: 208.288165ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:51.194834Z","caller":"traceutil/trace.go:171","msg":"trace[1058777111] transaction","detail":"{read_only:false; response_revision:16930; number_of_response:1; }","duration":"208.533171ms","start":"2026-04-18T17:35:50.986279Z","end":"2026-04-18T17:35:51.194812Z","steps":["trace[1058777111] 'process raft request' (duration: 208.207193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.335539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.998583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-18T17:35:57.335715Z","caller":"traceutil/trace.go:171","msg":"trace[984523829] linearizableReadLoop","detail":"{readStateIndex:17490; appliedIndex:17488; }","duration":"112.689661ms","start":"2026-04-18T17:35:57.223013Z","end":"2026-04-18T17:35:57.335702Z","steps":["trace[984523829] 'read index received' (duration: 110.782987ms)","trace[984523829] 'applied index is now lower than readState.Index' (duration: 1.905984ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T17:35:57.335785Z","caller":"traceutil/trace.go:171","msg":"trace[574472554] transaction","detail":"{read_only:false; response_revision:16961; number_of_response:1; }","duration":"251.595816ms","start":"2026-04-18T17:35:57.084166Z","end":"2026-04-18T17:35:57.335762Z","steps":["trace[574472554] 'process raft request' (duration: 108.00433ms)","trace[574472554] 'compare' (duration: 142.752226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:35:57.335857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.856775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-18T17:35:57.335818Z","caller":"traceutil/trace.go:171","msg":"trace[365508973] transaction","detail":"{read_only:false; response_revision:16962; number_of_response:1; }","duration":"232.691967ms","start":"2026-04-18T17:35:57.103071Z","end":"2026-04-18T17:35:57.335763Z","steps":["trace[365508973] 'process raft request' (duration: 232.554004ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:57.335885Z","caller":"traceutil/trace.go:171","msg":"trace[376160084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16962; }","duration":"112.881985ms","start":"2026-04-18T17:35:57.222992Z","end":"2026-04-18T17:35:57.335874Z","steps":["trace[376160084] 'agreement among raft nodes before linearized reading' (duration: 112.781363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.336094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.575378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-18T17:35:57.33613Z","caller":"traceutil/trace.go:171","msg":"trace[992007628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16962; }","duration":"112.64328ms","start":"2026-04-18T17:35:57.223476Z","end":"2026-04-18T17:35:57.336119Z","steps":["trace[992007628] 'agreement among raft nodes before linearized reading' (duration: 112.520317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.3362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.286031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T17:35:57.336288Z","caller":"traceutil/trace.go:171","msg":"trace[1478026576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16962; }","duration":"112.409906ms","start":"2026-04-18T17:35:57.223856Z","end":"2026-04-18T17:35:57.336266Z","steps":["trace[1478026576] 'agreement among raft nodes before linearized reading' (duration: 112.132948ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:57.596053Z","caller":"traceutil/trace.go:171","msg":"trace[762397987] linearizableReadLoop","detail":"{readStateIndex:17493; appliedIndex:17490; }","duration":"117.968786ms","start":"2026-04-18T17:35:57.478061Z","end":"2026-04-18T17:35:57.59603Z","steps":["trace[762397987] 'read index received' (duration: 78.863386ms)","trace[762397987] 'applied index is now lower than readState.Index' (duration: 39.10428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:35:57.596234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.156181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-18T17:35:57.596268Z","caller":"traceutil/trace.go:171","msg":"trace[2002701274] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:16965; }","duration":"118.246553ms","start":"2026-04-18T17:35:57.478011Z","end":"2026-04-18T17:35:57.596258Z","steps":["trace[2002701274] 'agreement among raft nodes before linearized reading' (duration: 118.152981ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:57.596254Z","caller":"traceutil/trace.go:171","msg":"trace[1591620199] transaction","detail":"{read_only:false; response_revision:16965; number_of_response:1; }","duration":"255.201822ms","start":"2026-04-18T17:35:57.341011Z","end":"2026-04-18T17:35:57.596213Z","steps":["trace[1591620199] 'process raft request' (duration: 254.956556ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:57.596409Z","caller":"traceutil/trace.go:171","msg":"trace[704953588] transaction","detail":"{read_only:false; response_revision:16963; number_of_response:1; }","duration":"255.433668ms","start":"2026-04-18T17:35:57.340948Z","end":"2026-04-18T17:35:57.596381Z","steps":["trace[704953588] 'process raft request' (duration: 215.872067ms)","trace[704953588] 'compare' (duration: 38.874865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T17:35:57.596471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.016988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-18T17:35:57.596471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.094669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-18T17:35:57.596505Z","caller":"traceutil/trace.go:171","msg":"trace[1483069157] transaction","detail":"{read_only:false; response_revision:16964; number_of_response:1; }","duration":"255.508959ms","start":"2026-04-18T17:35:57.340971Z","end":"2026-04-18T17:35:57.59648Z","steps":["trace[1483069157] 'process raft request' (duration: 254.950426ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:57.596534Z","caller":"traceutil/trace.go:171","msg":"trace[387267256] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:16965; }","duration":"118.1141ms","start":"2026-04-18T17:35:57.4784Z","end":"2026-04-18T17:35:57.596515Z","steps":["trace[387267256] 'agreement among raft nodes before linearized reading' (duration: 117.973177ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:35:57.596536Z","caller":"traceutil/trace.go:171","msg":"trace[1475626450] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:16965; }","duration":"118.196352ms","start":"2026-04-18T17:35:57.478326Z","end":"2026-04-18T17:35:57.596523Z","steps":["trace[1475626450] 'agreement among raft nodes before linearized reading' (duration: 117.924116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.596664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.743278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-18T17:35:57.596752Z","caller":"traceutil/trace.go:171","msg":"trace[1960473512] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:16965; }","duration":"116.868141ms","start":"2026-04-18T17:35:57.479863Z","end":"2026-04-18T17:35:57.596732Z","steps":["trace[1960473512] 'agreement among raft nodes before linearized reading' (duration: 116.470171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.946787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.627908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-18T17:35:57.946868Z","caller":"traceutil/trace.go:171","msg":"trace[1560028941] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:16966; }","duration":"238.7423ms","start":"2026-04-18T17:35:57.708106Z","end":"2026-04-18T17:35:57.946848Z","steps":["trace[1560028941] 'range keys from in-memory index tree' (duration: 238.532176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.947181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.038428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-18T17:35:57.947279Z","caller":"traceutil/trace.go:171","msg":"trace[1114605576] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:16966; }","duration":"239.189462ms","start":"2026-04-18T17:35:57.708066Z","end":"2026-04-18T17:35:57.947256Z","steps":["trace[1114605576] 'range keys from in-memory index tree' (duration: 238.903125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.947301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.254925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-18T17:35:57.947376Z","caller":"traceutil/trace.go:171","msg":"trace[1681417918] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:16966; }","duration":"237.368538ms","start":"2026-04-18T17:35:57.709989Z","end":"2026-04-18T17:35:57.947358Z","steps":["trace[1681417918] 'range keys from in-memory index tree' (duration: 237.04493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T17:35:57.947217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.907485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-18T17:35:57.94748Z","caller":"traceutil/trace.go:171","msg":"trace[1749117578] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:16966; }","duration":"239.182062ms","start":"2026-04-18T17:35:57.708242Z","end":"2026-04-18T17:35:57.947424Z","steps":["trace[1749117578] 'range keys from in-memory index tree' (duration: 238.815243ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T17:37:06.085651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14978} {"level":"info","ts":"2026-04-18T17:37:06.197781Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14978,"took":"108.40782ms","hash":3198314579,"current-db-size-bytes":57692160,"current-db-size":"58 MB","current-db-size-in-use-bytes":35467264,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-18T17:37:06.19786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3198314579,"revision":14978,"compact-revision":12300} {"level":"info","ts":"2026-04-18T17:40:57.120431Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-18T17:40:57.127194Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-18T17:40:57.127291Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-18T17:42:06.090425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17735} {"level":"info","ts":"2026-04-18T17:42:06.220693Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17735,"took":"127.580267ms","hash":10826408,"current-db-size-bytes":57692160,"current-db-size":"58 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-18T17:42:06.2208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":10826408,"revision":17735,"compact-revision":14978}