{"level":"warn","ts":"2026-08-13T18:28:47.980614Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T18:28:47.980693Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-08-13T18:28:47.980752Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T18:28:47.980766Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-13T18:28:47.980783Z","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-08-13T18:28:47.981374Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-08-13T18:28:47.98145Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-08-13T18:28:47.985078Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.423901ms"} {"level":"info","ts":"2026-08-13T18:28:47.990872Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-08-13T18:28:47.991002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T18:28:47.991037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-08-13T18:28:47.991188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T18:28:47.991206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-08-13T18:28:47.991484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-08-13T18:28:47.996505Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T18:28:47.997831Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T18:28:47.999078Z","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-08-13T18:28:48.002023Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T18:28:48.002483Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T18:28:48.002574Z","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-08-13T18:28:48.002705Z","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-08-13T18:28:48.002714Z","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-08-13T18:28:48.003235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-08-13T18:28:48.003391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-13T18:28:48.008939Z","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-08-13T18:28:48.009133Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-13T18:28:48.009236Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-13T18:28:48.009551Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T18:28:48.009645Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T18:28:48.792781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T18:28:48.792881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T18:28:48.792935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-08-13T18:28:48.792968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-08-13T18:28:48.79298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-13T18:28:48.792998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-08-13T18:28:48.793021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-13T18:28:48.794208Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:28:48.79558Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T18:28:48.795585Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T18:28:48.79575Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T18:28:48.796075Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:28:48.796246Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:28:48.796112Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T18:28:48.796311Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T18:28:48.796531Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T18:28:48.799541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-08-13T18:28:48.799714Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T18:29:32.39055Z","caller":"traceutil/trace.go:171","msg":"trace[1932768166] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"139.437963ms","start":"2026-08-13T18:29:32.251066Z","end":"2026-08-13T18:29:32.390504Z","steps":["trace[1932768166] 'process raft request' (duration: 138.785586ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:29:32.55474Z","caller":"traceutil/trace.go:171","msg":"trace[567279670] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"108.142211ms","start":"2026-08-13T18:29:32.446559Z","end":"2026-08-13T18:29:32.554701Z","steps":["trace[567279670] 'process raft request' (duration: 36.957345ms)","trace[567279670] 'compare' (duration: 71.002101ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:29:32.841394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.920309ms","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-08-13T18:29:32.841521Z","caller":"traceutil/trace.go:171","msg":"trace[474767899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"175.088563ms","start":"2026-08-13T18:29:32.66641Z","end":"2026-08-13T18:29:32.841499Z","steps":["trace[474767899] 'range keys from in-memory index tree' (duration: 174.811256ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:29:33.250803Z","caller":"traceutil/trace.go:171","msg":"trace[2087234151] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"360.83394ms","start":"2026-08-13T18:29:32.88994Z","end":"2026-08-13T18:29:33.250774Z","steps":["trace[2087234151] 'process raft request' (duration: 269.867527ms)","trace[2087234151] 'compare' (duration: 90.324166ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:29:33.251354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:29:32.889918Z","time spent":"360.955734ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:29:33.336262Z","caller":"traceutil/trace.go:171","msg":"trace[304942834] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"365.770982ms","start":"2026-08-13T18:29:32.970461Z","end":"2026-08-13T18:29:33.336232Z","steps":["trace[304942834] 'process raft request' (duration: 364.92623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:29:33.336506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:29:32.970422Z","time spent":"365.936697ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:29:33.336534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.01635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-54c8c\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-13T18:29:33.336242Z","caller":"traceutil/trace.go:171","msg":"trace[1427315792] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"237.63839ms","start":"2026-08-13T18:29:33.098508Z","end":"2026-08-13T18:29:33.336147Z","steps":["trace[1427315792] 'read index received' (duration: 61.335494ms)","trace[1427315792] 'applied index is now lower than readState.Index' (duration: 176.301576ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:29:33.336596Z","caller":"traceutil/trace.go:171","msg":"trace[1104203464] range","detail":"{range_begin:/registry/pods/kube-system/cilium-54c8c; range_end:; response_count:1; response_revision:592; }","duration":"238.127802ms","start":"2026-08-13T18:29:33.09845Z","end":"2026-08-13T18:29:33.336578Z","steps":["trace[1104203464] 'agreement among raft nodes before linearized reading' (duration: 237.870916ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:29:48.303309Z","caller":"traceutil/trace.go:171","msg":"trace[997541743] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"105.125176ms","start":"2026-08-13T18:29:48.198157Z","end":"2026-08-13T18:29:48.303282Z","steps":["trace[997541743] 'process raft request' (duration: 104.939651ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:30:43.664468Z","caller":"traceutil/trace.go:171","msg":"trace[1926117614] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"145.91565ms","start":"2026-08-13T18:30:43.518522Z","end":"2026-08-13T18:30:43.664438Z","steps":["trace[1926117614] 'process raft request' (duration: 145.729855ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:07.942232Z","caller":"traceutil/trace.go:171","msg":"trace[1278221221] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"126.344328ms","start":"2026-08-13T18:31:07.815852Z","end":"2026-08-13T18:31:07.942196Z","steps":["trace[1278221221] 'process raft request' (duration: 125.913207ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:31:54.387568Z","caller":"traceutil/trace.go:171","msg":"trace[1839860906] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"115.488034ms","start":"2026-08-13T18:31:54.272039Z","end":"2026-08-13T18:31:54.387527Z","steps":["trace[1839860906] 'process raft request' (duration: 71.296128ms)","trace[1839860906] 'compare' (duration: 43.913239ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:32:00.05725Z","caller":"traceutil/trace.go:171","msg":"trace[1425622971] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"130.488383ms","start":"2026-08-13T18:31:59.926729Z","end":"2026-08-13T18:32:00.057217Z","steps":["trace[1425622971] 'process raft request' (duration: 130.245707ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:00.356611Z","caller":"traceutil/trace.go:171","msg":"trace[1292568450] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"102.643763ms","start":"2026-08-13T18:32:00.253932Z","end":"2026-08-13T18:32:00.356576Z","steps":["trace[1292568450] 'process raft request' (duration: 102.346135ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:52.433042Z","caller":"traceutil/trace.go:171","msg":"trace[2019702611] linearizableReadLoop","detail":"{readStateIndex:2726; appliedIndex:2725; }","duration":"154.555325ms","start":"2026-08-13T18:32:52.278459Z","end":"2026-08-13T18:32:52.433015Z","steps":["trace[2019702611] 'read index received' (duration: 154.336399ms)","trace[2019702611] 'applied index is now lower than readState.Index' (duration: 218.006µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:32:52.433229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.73463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T18:32:52.433187Z","caller":"traceutil/trace.go:171","msg":"trace[30999650] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"165.068114ms","start":"2026-08-13T18:32:52.268069Z","end":"2026-08-13T18:32:52.433137Z","steps":["trace[30999650] 'process raft request' (duration: 164.785637ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:52.433294Z","caller":"traceutil/trace.go:171","msg":"trace[1628170834] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2624; }","duration":"154.844893ms","start":"2026-08-13T18:32:52.278427Z","end":"2026-08-13T18:32:52.433272Z","steps":["trace[1628170834] 'agreement among raft nodes before linearized reading' (duration: 154.72788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:32:52.433226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.257924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T18:32:52.433358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.968094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-13T18:32:52.433396Z","caller":"traceutil/trace.go:171","msg":"trace[531286843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2624; }","duration":"108.040667ms","start":"2026-08-13T18:32:52.325343Z","end":"2026-08-13T18:32:52.433384Z","steps":["trace[531286843] 'agreement among raft nodes before linearized reading' (duration: 107.916823ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:32:52.433386Z","caller":"traceutil/trace.go:171","msg":"trace[2120293942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2624; }","duration":"134.460609ms","start":"2026-08-13T18:32:52.298897Z","end":"2026-08-13T18:32:52.433358Z","steps":["trace[2120293942] 'agreement among raft nodes before linearized reading' (duration: 134.285624ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:36:02.668507Z","caller":"traceutil/trace.go:171","msg":"trace[616745825] linearizableReadLoop","detail":"{readStateIndex:4000; appliedIndex:3999; }","duration":"146.970817ms","start":"2026-08-13T18:36:02.521505Z","end":"2026-08-13T18:36:02.668476Z","steps":["trace[616745825] 'read index received' (duration: 146.818092ms)","trace[616745825] 'applied index is now lower than readState.Index' (duration: 151.315µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:36:02.668569Z","caller":"traceutil/trace.go:171","msg":"trace[1308212767] transaction","detail":"{read_only:false; response_revision:3853; number_of_response:1; }","duration":"148.796655ms","start":"2026-08-13T18:36:02.51972Z","end":"2026-08-13T18:36:02.668517Z","steps":["trace[1308212767] 'process raft request' (duration: 148.548468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:36:02.669901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.345343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:63157"} {"level":"info","ts":"2026-08-13T18:36:02.67002Z","caller":"traceutil/trace.go:171","msg":"trace[1087804867] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:3853; }","duration":"148.495257ms","start":"2026-08-13T18:36:02.5215Z","end":"2026-08-13T18:36:02.669995Z","steps":["trace[1087804867] 'agreement among raft nodes before linearized reading' (duration: 147.12422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:36:02.670995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.174305ms","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-08-13T18:36:02.671093Z","caller":"traceutil/trace.go:171","msg":"trace[812844720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3856; }","duration":"140.295479ms","start":"2026-08-13T18:36:02.530777Z","end":"2026-08-13T18:36:02.671073Z","steps":["trace[812844720] 'agreement among raft nodes before linearized reading' (duration: 140.035102ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:36:02.671443Z","caller":"traceutil/trace.go:171","msg":"trace[714886447] transaction","detail":"{read_only:false; response_revision:3854; number_of_response:1; }","duration":"148.416124ms","start":"2026-08-13T18:36:02.522992Z","end":"2026-08-13T18:36:02.671408Z","steps":["trace[714886447] 'process raft request' (duration: 147.583222ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:30.191788Z","caller":"traceutil/trace.go:171","msg":"trace[96320632] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"143.509389ms","start":"2026-08-13T18:37:30.048245Z","end":"2026-08-13T18:37:30.191755Z","steps":["trace[96320632] 'process raft request' (duration: 143.334534ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:30.341827Z","caller":"traceutil/trace.go:171","msg":"trace[71933484] linearizableReadLoop","detail":"{readStateIndex:5231; appliedIndex:5229; }","duration":"171.845439ms","start":"2026-08-13T18:37:30.169953Z","end":"2026-08-13T18:37:30.341799Z","steps":["trace[71933484] 'read index received' (duration: 21.609692ms)","trace[71933484] 'applied index is now lower than readState.Index' (duration: 150.235037ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:37:30.341929Z","caller":"traceutil/trace.go:171","msg":"trace[1910684713] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"281.06241ms","start":"2026-08-13T18:37:30.06083Z","end":"2026-08-13T18:37:30.341892Z","steps":["trace[1910684713] 'process raft request' (duration: 279.91813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:30.342083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.113127ms","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-08-13T18:37:30.342114Z","caller":"traceutil/trace.go:171","msg":"trace[881051424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5040; }","duration":"172.210209ms","start":"2026-08-13T18:37:30.169895Z","end":"2026-08-13T18:37:30.342105Z","steps":["trace[881051424] 'agreement among raft nodes before linearized reading' (duration: 172.010274ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:30.645032Z","caller":"traceutil/trace.go:171","msg":"trace[1137770244] linearizableReadLoop","detail":"{readStateIndex:5244; appliedIndex:5242; }","duration":"132.65275ms","start":"2026-08-13T18:37:30.512339Z","end":"2026-08-13T18:37:30.644992Z","steps":["trace[1137770244] 'read index received' (duration: 22.398773ms)","trace[1137770244] 'applied index is now lower than readState.Index' (duration: 110.248347ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:37:30.645056Z","caller":"traceutil/trace.go:171","msg":"trace[1585774129] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"141.834633ms","start":"2026-08-13T18:37:30.503141Z","end":"2026-08-13T18:37:30.644975Z","steps":["trace[1585774129] 'process raft request' (duration: 114.90847ms)","trace[1585774129] 'compare' (duration: 26.630365ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:37:30.645398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.0264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-jl9zt-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-08-13T18:37:30.645514Z","caller":"traceutil/trace.go:171","msg":"trace[1110535485] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-jl9zt-tls; range_end:; response_count:1; response_revision:5054; }","duration":"133.199275ms","start":"2026-08-13T18:37:30.512289Z","end":"2026-08-13T18:37:30.645489Z","steps":["trace[1110535485] 'agreement among raft nodes before linearized reading' (duration: 132.895297ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:44.029153Z","caller":"traceutil/trace.go:171","msg":"trace[681556560] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5501; }","duration":"162.472021ms","start":"2026-08-13T18:37:43.866654Z","end":"2026-08-13T18:37:44.029126Z","steps":["trace[681556560] 'read index received' (duration: 160.408446ms)","trace[681556560] 'applied index is now lower than readState.Index' (duration: 2.062565ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:37:44.029445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.762968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jl9zt\" ","response":"range_response_count:1 size:9938"} {"level":"info","ts":"2026-08-13T18:37:44.029489Z","caller":"traceutil/trace.go:171","msg":"trace[1126707063] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jl9zt; range_end:; response_count:1; response_revision:5302; }","duration":"162.867601ms","start":"2026-08-13T18:37:43.866608Z","end":"2026-08-13T18:37:44.029475Z","steps":["trace[1126707063] 'agreement among raft nodes before linearized reading' (duration: 162.630215ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:44.029753Z","caller":"traceutil/trace.go:171","msg":"trace[14877563] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"230.011989ms","start":"2026-08-13T18:37:43.79973Z","end":"2026-08-13T18:37:44.029741Z","steps":["trace[14877563] 'process raft request' (duration: 228.991592ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:52.592509Z","caller":"traceutil/trace.go:171","msg":"trace[375099073] linearizableReadLoop","detail":"{readStateIndex:5708; appliedIndex:5707; }","duration":"124.242619ms","start":"2026-08-13T18:37:52.46824Z","end":"2026-08-13T18:37:52.592483Z","steps":["trace[375099073] 'read index received' (duration: 124.051614ms)","trace[375099073] 'applied index is now lower than readState.Index' (duration: 189.805µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:37:52.592727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.464964ms","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":"info","ts":"2026-08-13T18:37:52.592774Z","caller":"traceutil/trace.go:171","msg":"trace[212932575] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5503; }","duration":"124.586208ms","start":"2026-08-13T18:37:52.468174Z","end":"2026-08-13T18:37:52.59276Z","steps":["trace[212932575] 'agreement among raft nodes before linearized reading' (duration: 124.451275ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:37:52.592878Z","caller":"traceutil/trace.go:171","msg":"trace[721003861] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"224.196874ms","start":"2026-08-13T18:37:52.368629Z","end":"2026-08-13T18:37:52.592826Z","steps":["trace[721003861] 'process raft request' (duration: 223.68207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:52.919225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.691731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:37:52.919347Z","caller":"traceutil/trace.go:171","msg":"trace[248572049] linearizableReadLoop","detail":"{readStateIndex:5709; appliedIndex:5708; }","duration":"326.732999ms","start":"2026-08-13T18:37:52.592596Z","end":"2026-08-13T18:37:52.919329Z","steps":["trace[248572049] 'read index received' (duration: 82.73955ms)","trace[248572049] 'applied index is now lower than readState.Index' (duration: 243.992279ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:37:52.919394Z","caller":"traceutil/trace.go:171","msg":"trace[777157435] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"435.479738ms","start":"2026-08-13T18:37:52.483871Z","end":"2026-08-13T18:37:52.919351Z","steps":["trace[777157435] 'process raft request' (duration: 191.432687ms)","trace[777157435] 'compare' (duration: 242.801568ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:37:52.919479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.950474ms","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-08-13T18:37:52.919512Z","caller":"traceutil/trace.go:171","msg":"trace[1078809626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5504; }","duration":"381.033197ms","start":"2026-08-13T18:37:52.538469Z","end":"2026-08-13T18:37:52.919502Z","steps":["trace[1078809626] 'agreement among raft nodes before linearized reading' (duration: 380.906573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:37:52.919554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:37:52.538444Z","time spent":"381.102849ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T18:37:52.919617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:37:52.483839Z","time spent":"435.655972ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T18:38:44.03798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.507016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-08-13T18:38:44.038076Z","caller":"traceutil/trace.go:171","msg":"trace[168857831] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5971; }","duration":"104.652831ms","start":"2026-08-13T18:38:43.933399Z","end":"2026-08-13T18:38:44.038052Z","steps":["trace[168857831] 'range keys from in-memory index tree' (duration: 104.332662ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:38:44.446857Z","caller":"traceutil/trace.go:171","msg":"trace[1355969723] transaction","detail":"{read_only:false; response_revision:5973; number_of_response:1; }","duration":"107.252619ms","start":"2026-08-13T18:38:44.339575Z","end":"2026-08-13T18:38:44.446828Z","steps":["trace[1355969723] 'process raft request' (duration: 107.027063ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:38:48.826114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2955} {"level":"info","ts":"2026-08-13T18:38:48.899047Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2955,"took":"70.805065ms","hash":3448818799,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":33992704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-13T18:38:48.899292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3448818799,"revision":2955,"compact-revision":-1} {"level":"info","ts":"2026-08-13T18:43:48.832789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6019} {"level":"info","ts":"2026-08-13T18:43:48.936524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6019,"took":"101.810312ms","hash":1483840032,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":21860352,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-13T18:43:48.936598Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1483840032,"revision":6019,"compact-revision":2955} {"level":"info","ts":"2026-08-13T18:44:17.308766Z","caller":"traceutil/trace.go:171","msg":"trace[73661237] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"260.607746ms","start":"2026-08-13T18:44:17.04813Z","end":"2026-08-13T18:44:17.308738Z","steps":["trace[73661237] 'process raft request' (duration: 260.40297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:44:17.499336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.425883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-13T18:44:17.499669Z","caller":"traceutil/trace.go:171","msg":"trace[1355776858] linearizableReadLoop","detail":"{readStateIndex:8695; appliedIndex:8692; }","duration":"200.576433ms","start":"2026-08-13T18:44:17.299072Z","end":"2026-08-13T18:44:17.499648Z","steps":["trace[1355776858] 'read index received' (duration: 9.462181ms)","trace[1355776858] 'applied index is now lower than readState.Index' (duration: 191.112972ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:44:17.499767Z","caller":"traceutil/trace.go:171","msg":"trace[120472196] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"346.3122ms","start":"2026-08-13T18:44:17.153444Z","end":"2026-08-13T18:44:17.499757Z","steps":["trace[120472196] 'process raft request' (duration: 346.009622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:44:17.500204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.138577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:44:17.500263Z","caller":"traceutil/trace.go:171","msg":"trace[686473472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8398; }","duration":"201.235951ms","start":"2026-08-13T18:44:17.299009Z","end":"2026-08-13T18:44:17.500245Z","steps":["trace[686473472] 'agreement among raft nodes before linearized reading' (duration: 201.156359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:44:17.500721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:44:17.153413Z","time spent":"346.383312ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:44:30.15075Z","caller":"traceutil/trace.go:171","msg":"trace[933328514] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"301.358418ms","start":"2026-08-13T18:44:29.849328Z","end":"2026-08-13T18:44:30.150686Z","steps":["trace[933328514] 'process raft request' (duration: 299.717454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:44:30.151306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:44:29.849306Z","time spent":"301.84832ms","remote":"127.0.0.1:42306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414454,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:44:30.752268Z","caller":"traceutil/trace.go:171","msg":"trace[204828482] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"101.955566ms","start":"2026-08-13T18:44:30.650239Z","end":"2026-08-13T18:44:30.752194Z","steps":["trace[204828482] 'process raft request' (duration: 48.325352ms)","trace[204828482] 'compare' (duration: 53.423898ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:44:54.294097Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022d0.wal"} {"level":"info","ts":"2026-08-13T18:45:03.102261Z","caller":"traceutil/trace.go:171","msg":"trace[16263728] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"218.92791ms","start":"2026-08-13T18:45:02.883305Z","end":"2026-08-13T18:45:03.102233Z","steps":["trace[16263728] 'process raft request' (duration: 218.744285ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:45:03.140417Z","caller":"traceutil/trace.go:171","msg":"trace[1307214566] linearizableReadLoop","detail":"{readStateIndex:9007; appliedIndex:9005; }","duration":"204.392094ms","start":"2026-08-13T18:45:02.936001Z","end":"2026-08-13T18:45:03.140394Z","steps":["trace[1307214566] 'read index received' (duration: 166.157099ms)","trace[1307214566] 'applied index is now lower than readState.Index' (duration: 38.234305ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:45:03.140563Z","caller":"traceutil/trace.go:171","msg":"trace[1735163443] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"250.987571ms","start":"2026-08-13T18:45:02.889517Z","end":"2026-08-13T18:45:03.140505Z","steps":["trace[1735163443] 'process raft request' (duration: 250.733044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:45:03.140618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.594119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T18:45:03.140652Z","caller":"traceutil/trace.go:171","msg":"trace[93865573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8698; }","duration":"204.695773ms","start":"2026-08-13T18:45:02.935951Z","end":"2026-08-13T18:45:03.140647Z","steps":["trace[93865573] 'agreement among raft nodes before linearized reading' (duration: 204.521638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:45:03.294449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.030053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T18:45:03.29459Z","caller":"traceutil/trace.go:171","msg":"trace[92628628] linearizableReadLoop","detail":"{readStateIndex:9008; appliedIndex:9007; }","duration":"105.568371ms","start":"2026-08-13T18:45:03.189002Z","end":"2026-08-13T18:45:03.29457Z","steps":["trace[92628628] 'read index received' (duration: 84.832µs)","trace[92628628] 'applied index is now lower than readState.Index' (duration: 105.482289ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:45:03.294606Z","caller":"traceutil/trace.go:171","msg":"trace[286023351] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"148.87751ms","start":"2026-08-13T18:45:03.145694Z","end":"2026-08-13T18:45:03.294571Z","steps":["trace[286023351] 'process raft request' (duration: 36.58242ms)","trace[286023351] 'compare' (duration: 111.851798ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T18:45:03.294767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.748876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T18:45:03.295107Z","caller":"traceutil/trace.go:171","msg":"trace[37941555] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8699; }","duration":"105.90003ms","start":"2026-08-13T18:45:03.188922Z","end":"2026-08-13T18:45:03.294823Z","steps":["trace[37941555] 'agreement among raft nodes before linearized reading' (duration: 105.750326ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:46:45.063385Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T18:46:45.069024Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-13T18:46:45.069136Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-13T18:48:48.841062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8071} {"level":"info","ts":"2026-08-13T18:48:48.899181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8071,"took":"56.221171ms","hash":839508240,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":28200960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-13T18:48:48.899243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":839508240,"revision":8071,"compact-revision":6019} {"level":"info","ts":"2026-08-13T18:52:12.355173Z","caller":"traceutil/trace.go:171","msg":"trace[808303706] linearizableReadLoop","detail":"{readStateIndex:12407; appliedIndex:12406; }","duration":"230.159555ms","start":"2026-08-13T18:52:12.124983Z","end":"2026-08-13T18:52:12.355143Z","steps":["trace[808303706] 'read index received' (duration: 230.022652ms)","trace[808303706] 'applied index is now lower than readState.Index' (duration: 135.193µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:52:12.355292Z","caller":"traceutil/trace.go:171","msg":"trace[1094812958] transaction","detail":"{read_only:false; response_revision:11995; number_of_response:1; }","duration":"257.84876ms","start":"2026-08-13T18:52:12.097404Z","end":"2026-08-13T18:52:12.355253Z","steps":["trace[1094812958] 'process raft request' (duration: 257.535562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:52:12.355481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.149307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-13T18:52:12.355484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.473425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T18:52:12.355589Z","caller":"traceutil/trace.go:171","msg":"trace[1121881842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11995; }","duration":"230.593808ms","start":"2026-08-13T18:52:12.124973Z","end":"2026-08-13T18:52:12.355567Z","steps":["trace[1121881842] 'agreement among raft nodes before linearized reading' (duration: 230.28816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:52:12.355604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.266843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-08-13T18:52:12.355674Z","caller":"traceutil/trace.go:171","msg":"trace[1708936857] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11995; }","duration":"148.356775ms","start":"2026-08-13T18:52:12.207307Z","end":"2026-08-13T18:52:12.355664Z","steps":["trace[1708936857] 'agreement among raft nodes before linearized reading' (duration: 148.203811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:52:12.355675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.390936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:52:12.355536Z","caller":"traceutil/trace.go:171","msg":"trace[1599094177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11995; }","duration":"224.260539ms","start":"2026-08-13T18:52:12.13126Z","end":"2026-08-13T18:52:12.35552Z","steps":["trace[1599094177] 'agreement among raft nodes before linearized reading' (duration: 224.071284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:52:12.355675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.475199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:52:12.35575Z","caller":"traceutil/trace.go:171","msg":"trace[619686406] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11995; }","duration":"148.51757ms","start":"2026-08-13T18:52:12.207212Z","end":"2026-08-13T18:52:12.35573Z","steps":["trace[619686406] 'agreement among raft nodes before linearized reading' (duration: 148.370296ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:52:12.355794Z","caller":"traceutil/trace.go:171","msg":"trace[1638664850] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11995; }","duration":"148.628443ms","start":"2026-08-13T18:52:12.207138Z","end":"2026-08-13T18:52:12.355766Z","steps":["trace[1638664850] 'agreement among raft nodes before linearized reading' (duration: 148.421158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:52:12.355959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.015253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T18:52:12.355968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.735956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T18:52:12.356Z","caller":"traceutil/trace.go:171","msg":"trace[2119657813] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11995; }","duration":"149.107726ms","start":"2026-08-13T18:52:12.206882Z","end":"2026-08-13T18:52:12.35599Z","steps":["trace[2119657813] 'agreement among raft nodes before linearized reading' (duration: 148.677445ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:52:12.356013Z","caller":"traceutil/trace.go:171","msg":"trace[2111592625] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11995; }","duration":"148.793177ms","start":"2026-08-13T18:52:12.207206Z","end":"2026-08-13T18:52:12.355999Z","steps":["trace[2111592625] 'agreement among raft nodes before linearized reading' (duration: 148.309164ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:53:48.848563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10481} {"level":"info","ts":"2026-08-13T18:53:48.932123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10481,"took":"81.174374ms","hash":2944989749,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":26513408,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-13T18:53:48.932203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2944989749,"revision":10481,"compact-revision":8071} {"level":"info","ts":"2026-08-13T18:58:48.855378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12643} {"level":"info","ts":"2026-08-13T18:58:48.932534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12643,"took":"74.373934ms","hash":3466819110,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":27062272,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-13T18:58:48.932615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3466819110,"revision":12643,"compact-revision":10481} {"level":"info","ts":"2026-08-13T18:59:02.120181Z","caller":"traceutil/trace.go:171","msg":"trace[839304090] transaction","detail":"{read_only:false; response_revision:14711; number_of_response:1; }","duration":"132.2156ms","start":"2026-08-13T18:59:01.987937Z","end":"2026-08-13T18:59:02.120153Z","steps":["trace[839304090] 'process raft request' (duration: 132.027945ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:59:02.135988Z","caller":"traceutil/trace.go:171","msg":"trace[1003182594] linearizableReadLoop","detail":"{readStateIndex:15220; appliedIndex:15218; }","duration":"108.129901ms","start":"2026-08-13T18:59:02.027824Z","end":"2026-08-13T18:59:02.135954Z","steps":["trace[1003182594] 'read index received' (duration: 92.185227ms)","trace[1003182594] 'applied index is now lower than readState.Index' (duration: 15.942854ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:59:02.136151Z","caller":"traceutil/trace.go:171","msg":"trace[330851578] transaction","detail":"{read_only:false; response_revision:14712; number_of_response:1; }","duration":"133.662008ms","start":"2026-08-13T18:59:02.002431Z","end":"2026-08-13T18:59:02.136093Z","steps":["trace[330851578] 'process raft request' (duration: 133.33921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:59:02.136228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.395208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-13T18:59:02.136292Z","caller":"traceutil/trace.go:171","msg":"trace[2100155845] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:14712; }","duration":"108.531481ms","start":"2026-08-13T18:59:02.027743Z","end":"2026-08-13T18:59:02.136275Z","steps":["trace[2100155845] 'agreement among raft nodes before linearized reading' (duration: 108.335316ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:59:03.092128Z","caller":"traceutil/trace.go:171","msg":"trace[938980783] linearizableReadLoop","detail":"{readStateIndex:15229; appliedIndex:15228; }","duration":"269.180637ms","start":"2026-08-13T18:59:02.822924Z","end":"2026-08-13T18:59:03.092104Z","steps":["trace[938980783] 'read index received' (duration: 269.099135ms)","trace[938980783] 'applied index is now lower than readState.Index' (duration: 80.672µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T18:59:03.092239Z","caller":"traceutil/trace.go:171","msg":"trace[1280963515] transaction","detail":"{read_only:false; response_revision:14720; number_of_response:1; }","duration":"270.512462ms","start":"2026-08-13T18:59:02.82168Z","end":"2026-08-13T18:59:03.092193Z","steps":["trace[1280963515] 'process raft request' (duration: 270.258175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:59:03.092444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.499485ms","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-08-13T18:59:03.0925Z","caller":"traceutil/trace.go:171","msg":"trace[654033847] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:14720; }","duration":"269.625279ms","start":"2026-08-13T18:59:02.822858Z","end":"2026-08-13T18:59:03.092483Z","steps":["trace[654033847] 'agreement among raft nodes before linearized reading' (duration: 269.369672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:59:03.099459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.004289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rfqxr\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-13T18:59:03.099561Z","caller":"traceutil/trace.go:171","msg":"trace[1675982020] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rfqxr; range_end:; response_count:1; response_revision:14721; }","duration":"212.164863ms","start":"2026-08-13T18:59:02.887373Z","end":"2026-08-13T18:59:03.099538Z","steps":["trace[1675982020] 'agreement among raft nodes before linearized reading' (duration: 211.835005ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:59:13.326629Z","caller":"traceutil/trace.go:171","msg":"trace[1354141359] transaction","detail":"{read_only:false; response_revision:14890; number_of_response:1; }","duration":"340.347537ms","start":"2026-08-13T18:59:12.986251Z","end":"2026-08-13T18:59:13.326599Z","steps":["trace[1354141359] 'process raft request' (duration: 340.188962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T18:59:13.326829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T18:59:12.986218Z","time spent":"340.518181ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3708,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T18:59:13.327411Z","caller":"traceutil/trace.go:171","msg":"trace[374476696] transaction","detail":"{read_only:false; response_revision:14891; number_of_response:1; }","duration":"123.357215ms","start":"2026-08-13T18:59:13.204015Z","end":"2026-08-13T18:59:13.327372Z","steps":["trace[374476696] 'process raft request' (duration: 123.097538ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T18:59:13.328107Z","caller":"traceutil/trace.go:171","msg":"trace[1415036964] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14891; }","duration":"100.095308ms","start":"2026-08-13T18:59:13.227978Z","end":"2026-08-13T18:59:13.328073Z","steps":["trace[1415036964] 'agreement among raft nodes before linearized reading' (duration: 99.390539ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:00:22.828877Z","caller":"traceutil/trace.go:171","msg":"trace[1402638102] transaction","detail":"{read_only:false; response_revision:15819; number_of_response:1; }","duration":"100.203171ms","start":"2026-08-13T19:00:22.728648Z","end":"2026-08-13T19:00:22.828851Z","steps":["trace[1402638102] 'process raft request' (duration: 100.043927ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:00:58.64617Z","caller":"traceutil/trace.go:171","msg":"trace[982592964] linearizableReadLoop","detail":"{readStateIndex:16549; appliedIndex:16548; }","duration":"159.151638ms","start":"2026-08-13T19:00:58.486982Z","end":"2026-08-13T19:00:58.646134Z","steps":["trace[982592964] 'read index received' (duration: 158.930362ms)","trace[982592964] 'applied index is now lower than readState.Index' (duration: 219.546µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:00:58.646286Z","caller":"traceutil/trace.go:171","msg":"trace[1641690715] transaction","detail":"{read_only:false; response_revision:16004; number_of_response:1; }","duration":"212.576787ms","start":"2026-08-13T19:00:58.433655Z","end":"2026-08-13T19:00:58.646232Z","steps":["trace[1641690715] 'process raft request' (duration: 212.277119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:00:58.646468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.462046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-13T19:00:58.646577Z","caller":"traceutil/trace.go:171","msg":"trace[1107957500] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16004; }","duration":"159.61913ms","start":"2026-08-13T19:00:58.48693Z","end":"2026-08-13T19:00:58.64655Z","steps":["trace[1107957500] 'agreement among raft nodes before linearized reading' (duration: 159.331353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:00:58.646626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.60671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-13T19:00:58.646813Z","caller":"traceutil/trace.go:171","msg":"trace[46838618] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16004; }","duration":"159.859207ms","start":"2026-08-13T19:00:58.486928Z","end":"2026-08-13T19:00:58.646787Z","steps":["trace[46838618] 'agreement among raft nodes before linearized reading' (duration: 159.401475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:00:58.646679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.62214ms","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-08-13T19:00:58.646968Z","caller":"traceutil/trace.go:171","msg":"trace[1952075262] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16004; }","duration":"159.941849ms","start":"2026-08-13T19:00:58.486979Z","end":"2026-08-13T19:00:58.646921Z","steps":["trace[1952075262] 'agreement among raft nodes before linearized reading' (duration: 159.552939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:00:59.317016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.645475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-13T19:00:59.317111Z","caller":"traceutil/trace.go:171","msg":"trace[873280969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16009; }","duration":"140.808731ms","start":"2026-08-13T19:00:59.176271Z","end":"2026-08-13T19:00:59.31708Z","steps":["trace[873280969] 'range keys from in-memory index tree' (duration: 140.333648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:00:59.317151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.304707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-13T19:00:59.317231Z","caller":"traceutil/trace.go:171","msg":"trace[1707431306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16009; }","duration":"140.4445ms","start":"2026-08-13T19:00:59.176771Z","end":"2026-08-13T19:00:59.317215Z","steps":["trace[1707431306] 'range keys from in-memory index tree' (duration: 140.158003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:00:59.317469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.732644ms","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-08-13T19:00:59.317555Z","caller":"traceutil/trace.go:171","msg":"trace[248099796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16009; }","duration":"176.870199ms","start":"2026-08-13T19:00:59.140669Z","end":"2026-08-13T19:00:59.317539Z","steps":["trace[248099796] 'range keys from in-memory index tree' (duration: 176.55761ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:00:59.537254Z","caller":"traceutil/trace.go:171","msg":"trace[510600896] linearizableReadLoop","detail":"{readStateIndex:16558; appliedIndex:16557; }","duration":"117.984654ms","start":"2026-08-13T19:00:59.419223Z","end":"2026-08-13T19:00:59.537208Z","steps":["trace[510600896] 'read index received' (duration: 106.731025ms)","trace[510600896] 'applied index is now lower than readState.Index' (duration: 11.251619ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:00:59.537305Z","caller":"traceutil/trace.go:171","msg":"trace[497004429] transaction","detail":"{read_only:false; response_revision:16013; number_of_response:1; }","duration":"133.411413ms","start":"2026-08-13T19:00:59.403852Z","end":"2026-08-13T19:00:59.537263Z","steps":["trace[497004429] 'process raft request' (duration: 122.173635ms)","trace[497004429] 'compare' (duration: 10.892179ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:00:59.537441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.1916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T19:00:59.537546Z","caller":"traceutil/trace.go:171","msg":"trace[1504766692] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:16013; }","duration":"118.347883ms","start":"2026-08-13T19:00:59.419173Z","end":"2026-08-13T19:00:59.537521Z","steps":["trace[1504766692] 'agreement among raft nodes before linearized reading' (duration: 118.172859ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:03:48.861641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14633} {"level":"info","ts":"2026-08-13T19:03:48.943266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14633,"took":"78.204728ms","hash":2036007047,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":32063488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-13T19:03:48.943355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2036007047,"revision":14633,"compact-revision":12643} {"level":"info","ts":"2026-08-13T19:06:31.066069Z","caller":"traceutil/trace.go:171","msg":"trace[1813290795] transaction","detail":"{read_only:false; response_revision:18441; number_of_response:1; }","duration":"196.388826ms","start":"2026-08-13T19:06:30.869657Z","end":"2026-08-13T19:06:31.066046Z","steps":["trace[1813290795] 'process raft request' (duration: 196.227262ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:33.284829Z","caller":"traceutil/trace.go:171","msg":"trace[1515661591] transaction","detail":"{read_only:false; response_revision:18494; number_of_response:1; }","duration":"127.569538ms","start":"2026-08-13T19:06:33.157226Z","end":"2026-08-13T19:06:33.284796Z","steps":["trace[1515661591] 'process raft request' (duration: 108.193883ms)","trace[1515661591] 'compare' (duration: 19.262052ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:06:33.319123Z","caller":"traceutil/trace.go:171","msg":"trace[1264012094] linearizableReadLoop","detail":"{readStateIndex:19123; appliedIndex:19121; }","duration":"123.011767ms","start":"2026-08-13T19:06:33.196085Z","end":"2026-08-13T19:06:33.319096Z","steps":["trace[1264012094] 'read index received' (duration: 69.465185ms)","trace[1264012094] 'applied index is now lower than readState.Index' (duration: 53.545582ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:06:33.319172Z","caller":"traceutil/trace.go:171","msg":"trace[835214296] transaction","detail":"{read_only:false; response_revision:18495; number_of_response:1; }","duration":"161.260673ms","start":"2026-08-13T19:06:33.157871Z","end":"2026-08-13T19:06:33.319132Z","steps":["trace[835214296] 'process raft request' (duration: 161.070508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:06:33.31945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.345677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-08-13T19:06:33.319544Z","caller":"traceutil/trace.go:171","msg":"trace[2146607296] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18495; }","duration":"123.476219ms","start":"2026-08-13T19:06:33.196045Z","end":"2026-08-13T19:06:33.319521Z","steps":["trace[2146607296] 'agreement among raft nodes before linearized reading' (duration: 123.155191ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:37.669112Z","caller":"traceutil/trace.go:171","msg":"trace[1649880590] linearizableReadLoop","detail":"{readStateIndex:19160; appliedIndex:19159; }","duration":"119.742651ms","start":"2026-08-13T19:06:37.549346Z","end":"2026-08-13T19:06:37.669089Z","steps":["trace[1649880590] 'read index received' (duration: 119.555866ms)","trace[1649880590] 'applied index is now lower than readState.Index' (duration: 185.765µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:06:37.66915Z","caller":"traceutil/trace.go:171","msg":"trace[1486807400] transaction","detail":"{read_only:false; response_revision:18531; number_of_response:1; }","duration":"120.824949ms","start":"2026-08-13T19:06:37.548305Z","end":"2026-08-13T19:06:37.66913Z","steps":["trace[1486807400] 'process raft request' (duration: 120.643344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:06:37.669642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.251124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-13T19:06:37.669818Z","caller":"traceutil/trace.go:171","msg":"trace[1512875439] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18531; }","duration":"120.45918ms","start":"2026-08-13T19:06:37.549289Z","end":"2026-08-13T19:06:37.669749Z","steps":["trace[1512875439] 'agreement among raft nodes before linearized reading' (duration: 119.894425ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:38.301745Z","caller":"traceutil/trace.go:171","msg":"trace[561216800] transaction","detail":"{read_only:false; response_revision:18532; number_of_response:1; }","duration":"278.123037ms","start":"2026-08-13T19:06:38.023602Z","end":"2026-08-13T19:06:38.301726Z","steps":["trace[561216800] 'process raft request' (duration: 278.007444ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:38.301888Z","caller":"traceutil/trace.go:171","msg":"trace[1925574588] linearizableReadLoop","detail":"{readStateIndex:19161; appliedIndex:19161; }","duration":"216.88062ms","start":"2026-08-13T19:06:38.084961Z","end":"2026-08-13T19:06:38.301841Z","steps":["trace[1925574588] 'read index received' (duration: 216.85795ms)","trace[1925574588] 'applied index is now lower than readState.Index' (duration: 18.76µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:06:38.302348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.336792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vnkfn\" ","response":"range_response_count:1 size:20736"} {"level":"info","ts":"2026-08-13T19:06:38.30247Z","caller":"traceutil/trace.go:171","msg":"trace[1872368833] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vnkfn; range_end:; response_count:1; response_revision:18532; }","duration":"217.535647ms","start":"2026-08-13T19:06:38.084913Z","end":"2026-08-13T19:06:38.302449Z","steps":["trace[1872368833] 'agreement among raft nodes before linearized reading' (duration: 217.201188ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:38.441253Z","caller":"traceutil/trace.go:171","msg":"trace[1536859861] transaction","detail":"{read_only:false; response_revision:18533; number_of_response:1; }","duration":"184.362957ms","start":"2026-08-13T19:06:38.256868Z","end":"2026-08-13T19:06:38.44123Z","steps":["trace[1536859861] 'process raft request' (duration: 122.443872ms)","trace[1536859861] 'compare' (duration: 61.75797ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:06:38.441658Z","caller":"traceutil/trace.go:171","msg":"trace[276677408] transaction","detail":"{read_only:false; response_revision:18534; number_of_response:1; }","duration":"184.670216ms","start":"2026-08-13T19:06:38.256968Z","end":"2026-08-13T19:06:38.441638Z","steps":["trace[276677408] 'process raft request' (duration: 184.222064ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:38.441742Z","caller":"traceutil/trace.go:171","msg":"trace[1988340293] transaction","detail":"{read_only:false; response_revision:18535; number_of_response:1; }","duration":"125.074842ms","start":"2026-08-13T19:06:38.316641Z","end":"2026-08-13T19:06:38.441715Z","steps":["trace[1988340293] 'process raft request' (duration: 124.943338ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:38.441788Z","caller":"traceutil/trace.go:171","msg":"trace[462995931] linearizableReadLoop","detail":"{readStateIndex:19163; appliedIndex:19161; }","duration":"139.704281ms","start":"2026-08-13T19:06:38.302072Z","end":"2026-08-13T19:06:38.441776Z","steps":["trace[462995931] 'read index received' (duration: 77.285933ms)","trace[462995931] 'applied index is now lower than readState.Index' (duration: 62.416518ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:06:38.442032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.521912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:06:38.44208Z","caller":"traceutil/trace.go:171","msg":"trace[1228533847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18535; }","duration":"143.604304ms","start":"2026-08-13T19:06:38.298464Z","end":"2026-08-13T19:06:38.442068Z","steps":["trace[1228533847] 'agreement among raft nodes before linearized reading' (duration: 143.478651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:06:38.442583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.81595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-08-13T19:06:38.442592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.334057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4dkbc\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-08-13T19:06:38.442627Z","caller":"traceutil/trace.go:171","msg":"trace[65443815] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18535; }","duration":"265.892293ms","start":"2026-08-13T19:06:38.176725Z","end":"2026-08-13T19:06:38.442617Z","steps":["trace[65443815] 'agreement among raft nodes before linearized reading' (duration: 265.747829ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:06:38.442635Z","caller":"traceutil/trace.go:171","msg":"trace[1938389742] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4dkbc; range_end:; response_count:1; response_revision:18535; }","duration":"236.41319ms","start":"2026-08-13T19:06:38.206211Z","end":"2026-08-13T19:06:38.442624Z","steps":["trace[1938389742] 'agreement among raft nodes before linearized reading' (duration: 236.240975ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:07:49.288038Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T19:07:49.294988Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-13T19:07:49.295137Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-13T19:08:48.86778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17222} {"level":"info","ts":"2026-08-13T19:08:48.974723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17222,"took":"102.762319ms","hash":3615247297,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":35065856,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-13T19:08:48.974801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3615247297,"revision":17222,"compact-revision":14633} {"level":"info","ts":"2026-08-13T19:12:49.938532Z","caller":"traceutil/trace.go:171","msg":"trace[471093186] transaction","detail":"{read_only:false; response_revision:21377; number_of_response:1; }","duration":"294.311857ms","start":"2026-08-13T19:12:49.644175Z","end":"2026-08-13T19:12:49.938486Z","steps":["trace[471093186] 'process raft request' (duration: 293.922147ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:12:50.027245Z","caller":"traceutil/trace.go:171","msg":"trace[1206070675] transaction","detail":"{read_only:false; response_revision:21379; number_of_response:1; }","duration":"187.829529ms","start":"2026-08-13T19:12:49.839395Z","end":"2026-08-13T19:12:50.027225Z","steps":["trace[1206070675] 'process raft request' (duration: 187.788998ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:12:50.027669Z","caller":"traceutil/trace.go:171","msg":"trace[2035671012] transaction","detail":"{read_only:false; response_revision:21378; number_of_response:1; }","duration":"189.162924ms","start":"2026-08-13T19:12:49.838456Z","end":"2026-08-13T19:12:50.027619Z","steps":["trace[2035671012] 'process raft request' (duration: 185.930389ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:13:06.108765Z","caller":"traceutil/trace.go:171","msg":"trace[1261805885] transaction","detail":"{read_only:false; response_revision:21458; number_of_response:1; }","duration":"113.493895ms","start":"2026-08-13T19:13:05.995251Z","end":"2026-08-13T19:13:06.108744Z","steps":["trace[1261805885] 'process raft request' (duration: 113.31146ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:13:06.70699Z","caller":"traceutil/trace.go:171","msg":"trace[1328973068] transaction","detail":"{read_only:false; response_revision:21461; number_of_response:1; }","duration":"144.20725ms","start":"2026-08-13T19:13:06.562757Z","end":"2026-08-13T19:13:06.706964Z","steps":["trace[1328973068] 'process raft request' (duration: 144.019645ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:13:06.707321Z","caller":"traceutil/trace.go:171","msg":"trace[426387579] linearizableReadLoop","detail":"{readStateIndex:22187; appliedIndex:22187; }","duration":"117.921813ms","start":"2026-08-13T19:13:06.589356Z","end":"2026-08-13T19:13:06.707277Z","steps":["trace[426387579] 'read index received' (duration: 117.878841ms)","trace[426387579] 'applied index is now lower than readState.Index' (duration: 40.441µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:13:06.707577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.19605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T19:13:06.707672Z","caller":"traceutil/trace.go:171","msg":"trace[1295797702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21461; }","duration":"118.332213ms","start":"2026-08-13T19:13:06.589319Z","end":"2026-08-13T19:13:06.707651Z","steps":["trace[1295797702] 'agreement among raft nodes before linearized reading' (duration: 118.049206ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:13:48.873474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19771} {"level":"info","ts":"2026-08-13T19:13:48.987417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19771,"took":"109.240222ms","hash":2022008002,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":30130176,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T19:13:48.987493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2022008002,"revision":19771,"compact-revision":17222} {"level":"warn","ts":"2026-08-13T19:15:09.437649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.730653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:09.437843Z","caller":"traceutil/trace.go:171","msg":"trace[1680795208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22515; }","duration":"138.942539ms","start":"2026-08-13T19:15:09.298823Z","end":"2026-08-13T19:15:09.437766Z","steps":["trace[1680795208] 'range keys from in-memory index tree' (duration: 138.64421ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:20.338348Z","caller":"traceutil/trace.go:171","msg":"trace[540907100] linearizableReadLoop","detail":"{readStateIndex:23346; appliedIndex:23345; }","duration":"302.450699ms","start":"2026-08-13T19:15:20.035845Z","end":"2026-08-13T19:15:20.338295Z","steps":["trace[540907100] 'read index received' (duration: 302.421079ms)","trace[540907100] 'applied index is now lower than readState.Index' (duration: 28.4µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:20.338552Z","caller":"traceutil/trace.go:171","msg":"trace[746456688] transaction","detail":"{read_only:false; response_revision:22580; number_of_response:1; }","duration":"303.760103ms","start":"2026-08-13T19:15:20.034722Z","end":"2026-08-13T19:15:20.338482Z","steps":["trace[746456688] 'process raft request' (duration: 303.415534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:20.338818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:20.034699Z","time spent":"303.98097ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:15:20.33931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.096246ms","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-08-13T19:15:20.339416Z","caller":"traceutil/trace.go:171","msg":"trace[1637244545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22580; }","duration":"303.590689ms","start":"2026-08-13T19:15:20.035794Z","end":"2026-08-13T19:15:20.339385Z","steps":["trace[1637244545] 'agreement among raft nodes before linearized reading' (duration: 302.674495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:20.339469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:20.035784Z","time spent":"303.673551ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T19:15:20.971924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.99345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:20.972031Z","caller":"traceutil/trace.go:171","msg":"trace[210490192] linearizableReadLoop","detail":"{readStateIndex:23351; appliedIndex:23350; }","duration":"122.962464ms","start":"2026-08-13T19:15:20.849052Z","end":"2026-08-13T19:15:20.972014Z","steps":["trace[210490192] 'read index received' (duration: 132.983µs)","trace[210490192] 'applied index is now lower than readState.Index' (duration: 122.828261ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:20.972273Z","caller":"traceutil/trace.go:171","msg":"trace[1299940365] transaction","detail":"{read_only:false; response_revision:22585; number_of_response:1; }","duration":"240.535726ms","start":"2026-08-13T19:15:20.731701Z","end":"2026-08-13T19:15:20.972237Z","steps":["trace[1299940365] 'process raft request' (duration: 98.122455ms)","trace[1299940365] 'compare' (duration: 141.789584ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:20.97243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.353285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:20.972556Z","caller":"traceutil/trace.go:171","msg":"trace[1391386418] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22585; }","duration":"123.543799ms","start":"2026-08-13T19:15:20.848978Z","end":"2026-08-13T19:15:20.972522Z","steps":["trace[1391386418] 'agreement among raft nodes before linearized reading' (duration: 123.20145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:20.972673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7374"} {"level":"info","ts":"2026-08-13T19:15:20.973012Z","caller":"traceutil/trace.go:171","msg":"trace[1711255168] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22585; }","duration":"121.108655ms","start":"2026-08-13T19:15:20.851652Z","end":"2026-08-13T19:15:20.972761Z","steps":["trace[1711255168] 'agreement among raft nodes before linearized reading' (duration: 120.610192ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:21.984784Z","caller":"traceutil/trace.go:171","msg":"trace[1479822996] transaction","detail":"{read_only:false; response_revision:22589; number_of_response:1; }","duration":"124.283739ms","start":"2026-08-13T19:15:21.86047Z","end":"2026-08-13T19:15:21.984754Z","steps":["trace[1479822996] 'process raft request' (duration: 124.090744ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:24.099417Z","caller":"traceutil/trace.go:171","msg":"trace[1700768862] transaction","detail":"{read_only:false; response_revision:22599; number_of_response:1; }","duration":"100.713633ms","start":"2026-08-13T19:15:23.998682Z","end":"2026-08-13T19:15:24.099395Z","steps":["trace[1700768862] 'process raft request' (duration: 100.58776ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:24.574753Z","caller":"traceutil/trace.go:171","msg":"trace[544458073] transaction","detail":"{read_only:false; response_revision:22601; number_of_response:1; }","duration":"111.86464ms","start":"2026-08-13T19:15:24.462863Z","end":"2026-08-13T19:15:24.574727Z","steps":["trace[544458073] 'process raft request' (duration: 111.713886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:25.949473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.332695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:25.949579Z","caller":"traceutil/trace.go:171","msg":"trace[447561991] linearizableReadLoop","detail":"{readStateIndex:23375; appliedIndex:23373; }","duration":"192.557171ms","start":"2026-08-13T19:15:25.757005Z","end":"2026-08-13T19:15:25.949562Z","steps":["trace[447561991] 'read index received' (duration: 7.91518ms)","trace[447561991] 'applied index is now lower than readState.Index' (duration: 184.641191ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:25.949634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.628003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:25.949653Z","caller":"traceutil/trace.go:171","msg":"trace[984992337] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:22608; }","duration":"192.694346ms","start":"2026-08-13T19:15:25.756952Z","end":"2026-08-13T19:15:25.949647Z","steps":["trace[984992337] 'agreement among raft nodes before linearized reading' (duration: 192.649514ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:25.949825Z","caller":"traceutil/trace.go:171","msg":"trace[2120396771] transaction","detail":"{read_only:false; response_revision:22608; number_of_response:1; }","duration":"220.864883ms","start":"2026-08-13T19:15:25.728923Z","end":"2026-08-13T19:15:25.949788Z","steps":["trace[2120396771] 'process raft request' (duration: 97.134249ms)","trace[2120396771] 'compare' (duration: 123.210761ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:27.309636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.284009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:27.309789Z","caller":"traceutil/trace.go:171","msg":"trace[1579612707] transaction","detail":"{read_only:false; response_revision:22614; number_of_response:1; }","duration":"159.858094ms","start":"2026-08-13T19:15:27.149918Z","end":"2026-08-13T19:15:27.309776Z","steps":["trace[1579612707] 'process raft request' (duration: 159.805493ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:27.309858Z","caller":"traceutil/trace.go:171","msg":"trace[1452842202] linearizableReadLoop","detail":"{readStateIndex:23380; appliedIndex:23379; }","duration":"322.739497ms","start":"2026-08-13T19:15:26.987083Z","end":"2026-08-13T19:15:27.309823Z","steps":["trace[1452842202] 'read index received' (duration: 166.199902ms)","trace[1452842202] 'applied index is now lower than readState.Index' (duration: 156.535495ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:27.309897Z","caller":"traceutil/trace.go:171","msg":"trace[1900635269] transaction","detail":"{read_only:false; response_revision:22613; number_of_response:1; }","duration":"395.990891ms","start":"2026-08-13T19:15:26.913869Z","end":"2026-08-13T19:15:27.30986Z","steps":["trace[1900635269] 'process raft request' (duration: 239.395644ms)","trace[1900635269] 'compare' (duration: 156.124765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:27.310073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:26.913846Z","time spent":"396.118205ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:15:27.310126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.026435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:27.310193Z","caller":"traceutil/trace.go:171","msg":"trace[1186693920] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22614; }","duration":"323.108987ms","start":"2026-08-13T19:15:26.987067Z","end":"2026-08-13T19:15:27.310176Z","steps":["trace[1186693920] 'agreement among raft nodes before linearized reading' (duration: 322.886662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:27.310245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:26.987045Z","time spent":"323.187509ms","remote":"127.0.0.1:42910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-13T19:15:27.323823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.605143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-13T19:15:27.323827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.576972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7374"} {"level":"info","ts":"2026-08-13T19:15:27.323883Z","caller":"traceutil/trace.go:171","msg":"trace[1873700189] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22615; }","duration":"215.680756ms","start":"2026-08-13T19:15:27.108186Z","end":"2026-08-13T19:15:27.323867Z","steps":["trace[1873700189] 'agreement among raft nodes before linearized reading' (duration: 215.513061ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:27.323899Z","caller":"traceutil/trace.go:171","msg":"trace[1777793210] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22615; }","duration":"331.658244ms","start":"2026-08-13T19:15:26.992222Z","end":"2026-08-13T19:15:27.32388Z","steps":["trace[1777793210] 'agreement among raft nodes before linearized reading' (duration: 331.451109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:27.323947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:26.992125Z","time spent":"331.813859ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7396,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"warn","ts":"2026-08-13T19:15:27.324016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.665276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:27.324123Z","caller":"traceutil/trace.go:171","msg":"trace[2109543726] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22615; }","duration":"215.811529ms","start":"2026-08-13T19:15:27.108278Z","end":"2026-08-13T19:15:27.32409Z","steps":["trace[2109543726] 'agreement among raft nodes before linearized reading' (duration: 215.514781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:27.324065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.896541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7374"} {"level":"info","ts":"2026-08-13T19:15:27.324258Z","caller":"traceutil/trace.go:171","msg":"trace[1070147771] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22615; }","duration":"216.129968ms","start":"2026-08-13T19:15:27.108106Z","end":"2026-08-13T19:15:27.324236Z","steps":["trace[1070147771] 'agreement among raft nodes before linearized reading' (duration: 215.637635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:27.324679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.543748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7374"} {"level":"info","ts":"2026-08-13T19:15:27.326264Z","caller":"traceutil/trace.go:171","msg":"trace[532529166] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22615; }","duration":"218.166301ms","start":"2026-08-13T19:15:27.108085Z","end":"2026-08-13T19:15:27.326251Z","steps":["trace[532529166] 'agreement among raft nodes before linearized reading' (duration: 216.204309ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:27.494794Z","caller":"traceutil/trace.go:171","msg":"trace[469957648] linearizableReadLoop","detail":"{readStateIndex:23383; appliedIndex:23382; }","duration":"102.83822ms","start":"2026-08-13T19:15:27.391928Z","end":"2026-08-13T19:15:27.494766Z","steps":["trace[469957648] 'read index received' (duration: 34.596818ms)","trace[469957648] 'applied index is now lower than readState.Index' (duration: 68.240242ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:27.49486Z","caller":"traceutil/trace.go:171","msg":"trace[52720653] transaction","detail":"{read_only:false; response_revision:22616; number_of_response:1; }","duration":"106.041865ms","start":"2026-08-13T19:15:27.388779Z","end":"2026-08-13T19:15:27.494821Z","steps":["trace[52720653] 'process raft request' (duration: 37.835864ms)","trace[52720653] 'compare' (duration: 67.929904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:27.494986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.039855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7374"} {"level":"info","ts":"2026-08-13T19:15:27.495018Z","caller":"traceutil/trace.go:171","msg":"trace[1916125399] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22616; }","duration":"103.121697ms","start":"2026-08-13T19:15:27.391888Z","end":"2026-08-13T19:15:27.49501Z","steps":["trace[1916125399] 'agreement among raft nodes before linearized reading' (duration: 102.970683ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:28.809352Z","caller":"traceutil/trace.go:171","msg":"trace[1448440896] linearizableReadLoop","detail":"{readStateIndex:23390; appliedIndex:23389; }","duration":"157.264975ms","start":"2026-08-13T19:15:28.652054Z","end":"2026-08-13T19:15:28.809319Z","steps":["trace[1448440896] 'read index received' (duration: 157.171773ms)","trace[1448440896] 'applied index is now lower than readState.Index' (duration: 92.022µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:28.809507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.43701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:28.809559Z","caller":"traceutil/trace.go:171","msg":"trace[1261102637] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22622; }","duration":"157.541492ms","start":"2026-08-13T19:15:28.652002Z","end":"2026-08-13T19:15:28.809543Z","steps":["trace[1261102637] 'agreement among raft nodes before linearized reading' (duration: 157.43943ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:28.809475Z","caller":"traceutil/trace.go:171","msg":"trace[905492079] transaction","detail":"{read_only:false; response_revision:22622; number_of_response:1; }","duration":"185.461374ms","start":"2026-08-13T19:15:28.623986Z","end":"2026-08-13T19:15:28.809447Z","steps":["trace[905492079] 'process raft request' (duration: 185.187766ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:28.87154Z","caller":"traceutil/trace.go:171","msg":"trace[588237015] transaction","detail":"{read_only:false; response_revision:22623; number_of_response:1; }","duration":"152.743265ms","start":"2026-08-13T19:15:28.718753Z","end":"2026-08-13T19:15:28.871496Z","steps":["trace[588237015] 'process raft request' (duration: 127.927276ms)","trace[588237015] 'compare' (duration: 24.48907ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:29.032495Z","caller":"traceutil/trace.go:171","msg":"trace[121912452] transaction","detail":"{read_only:false; response_revision:22624; number_of_response:1; }","duration":"111.592312ms","start":"2026-08-13T19:15:28.920876Z","end":"2026-08-13T19:15:29.032468Z","steps":["trace[121912452] 'process raft request' (duration: 63.439594ms)","trace[121912452] 'compare' (duration: 48.064596ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:29.999088Z","caller":"traceutil/trace.go:171","msg":"trace[106488222] transaction","detail":"{read_only:false; response_revision:22632; number_of_response:1; }","duration":"119.197534ms","start":"2026-08-13T19:15:29.879859Z","end":"2026-08-13T19:15:29.999056Z","steps":["trace[106488222] 'process raft request' (duration: 118.926937ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:30.27172Z","caller":"traceutil/trace.go:171","msg":"trace[712104111] transaction","detail":"{read_only:false; response_revision:22636; number_of_response:1; }","duration":"179.956397ms","start":"2026-08-13T19:15:30.09171Z","end":"2026-08-13T19:15:30.271666Z","steps":["trace[712104111] 'process raft request' (duration: 111.325265ms)","trace[712104111] 'compare' (duration: 68.411716ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:30.494141Z","caller":"traceutil/trace.go:171","msg":"trace[139194206] linearizableReadLoop","detail":"{readStateIndex:23406; appliedIndex:23405; }","duration":"208.960128ms","start":"2026-08-13T19:15:30.285153Z","end":"2026-08-13T19:15:30.494113Z","steps":["trace[139194206] 'read index received' (duration: 25.588089ms)","trace[139194206] 'applied index is now lower than readState.Index' (duration: 183.370589ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:30.494261Z","caller":"traceutil/trace.go:171","msg":"trace[1875333542] transaction","detail":"{read_only:false; response_revision:22638; number_of_response:1; }","duration":"216.182659ms","start":"2026-08-13T19:15:30.278039Z","end":"2026-08-13T19:15:30.494221Z","steps":["trace[1875333542] 'process raft request' (duration: 215.889122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:30.494419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.240655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:30.494522Z","caller":"traceutil/trace.go:171","msg":"trace[1263789522] range","detail":"{range_begin:/registry/jobs/openstack/octavia-bootstrap; range_end:; response_count:0; response_revision:22638; }","duration":"209.40888ms","start":"2026-08-13T19:15:30.285088Z","end":"2026-08-13T19:15:30.494497Z","steps":["trace[1263789522] 'agreement among raft nodes before linearized reading' (duration: 209.188274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:30.494647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.905081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:30.494795Z","caller":"traceutil/trace.go:171","msg":"trace[2052949785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22638; }","duration":"196.198539ms","start":"2026-08-13T19:15:30.298581Z","end":"2026-08-13T19:15:30.494779Z","steps":["trace[2052949785] 'agreement among raft nodes before linearized reading' (duration: 195.903431ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:30.74118Z","caller":"traceutil/trace.go:171","msg":"trace[1891019782] transaction","detail":"{read_only:false; response_revision:22641; number_of_response:1; }","duration":"140.692725ms","start":"2026-08-13T19:15:30.600453Z","end":"2026-08-13T19:15:30.741146Z","steps":["trace[1891019782] 'process raft request' (duration: 118.593749ms)","trace[1891019782] 'compare' (duration: 21.948842ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:30.872459Z","caller":"traceutil/trace.go:171","msg":"trace[849113047] linearizableReadLoop","detail":"{readStateIndex:23410; appliedIndex:23409; }","duration":"110.619787ms","start":"2026-08-13T19:15:30.761816Z","end":"2026-08-13T19:15:30.872436Z","steps":["trace[849113047] 'read index received' (duration: 81.327449ms)","trace[849113047] 'applied index is now lower than readState.Index' (duration: 29.291298ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:30.872569Z","caller":"traceutil/trace.go:171","msg":"trace[166192400] transaction","detail":"{read_only:false; response_revision:22642; number_of_response:1; }","duration":"121.732321ms","start":"2026-08-13T19:15:30.750804Z","end":"2026-08-13T19:15:30.872537Z","steps":["trace[166192400] 'process raft request' (duration: 92.420983ms)","trace[166192400] 'compare' (duration: 29.047591ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:30.872712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.881313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18374"} {"level":"info","ts":"2026-08-13T19:15:30.872755Z","caller":"traceutil/trace.go:171","msg":"trace[171096438] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:22642; }","duration":"111.085129ms","start":"2026-08-13T19:15:30.761658Z","end":"2026-08-13T19:15:30.872743Z","steps":["trace[171096438] 'agreement among raft nodes before linearized reading' (duration: 110.915044ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:31.036352Z","caller":"traceutil/trace.go:171","msg":"trace[479664186] transaction","detail":"{read_only:false; response_revision:22647; number_of_response:1; }","duration":"120.55758ms","start":"2026-08-13T19:15:30.915767Z","end":"2026-08-13T19:15:31.036325Z","steps":["trace[479664186] 'process raft request' (duration: 76.645765ms)","trace[479664186] 'compare' (duration: 43.139655ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:31.460361Z","caller":"traceutil/trace.go:171","msg":"trace[169194584] transaction","detail":"{read_only:false; response_revision:22652; number_of_response:1; }","duration":"122.978574ms","start":"2026-08-13T19:15:31.337345Z","end":"2026-08-13T19:15:31.460323Z","steps":["trace[169194584] 'process raft request' (duration: 93.755659ms)","trace[169194584] 'compare' (duration: 28.971779ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:31.460356Z","caller":"traceutil/trace.go:171","msg":"trace[855087963] linearizableReadLoop","detail":"{readStateIndex:23420; appliedIndex:23419; }","duration":"115.987919ms","start":"2026-08-13T19:15:31.344335Z","end":"2026-08-13T19:15:31.460323Z","steps":["trace[855087963] 'read index received' (duration: 86.750753ms)","trace[855087963] 'applied index is now lower than readState.Index' (duration: 29.235506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:31.460779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.625421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7471"} {"level":"info","ts":"2026-08-13T19:15:31.460865Z","caller":"traceutil/trace.go:171","msg":"trace[412113083] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22653; }","duration":"123.759755ms","start":"2026-08-13T19:15:31.337087Z","end":"2026-08-13T19:15:31.460847Z","steps":["trace[412113083] 'agreement among raft nodes before linearized reading' (duration: 123.288103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:31.460848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.328541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7471"} {"level":"warn","ts":"2026-08-13T19:15:31.460848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.107244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:31.460936Z","caller":"traceutil/trace.go:171","msg":"trace[1401783687] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22653; }","duration":"124.210347ms","start":"2026-08-13T19:15:31.336711Z","end":"2026-08-13T19:15:31.460921Z","steps":["trace[1401783687] 'agreement among raft nodes before linearized reading' (duration: 123.717984ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:31.460933Z","caller":"traceutil/trace.go:171","msg":"trace[1530940028] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22653; }","duration":"121.427874ms","start":"2026-08-13T19:15:31.339487Z","end":"2026-08-13T19:15:31.460915Z","steps":["trace[1530940028] 'agreement among raft nodes before linearized reading' (duration: 120.874679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:31.460806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.949537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:31.461331Z","caller":"traceutil/trace.go:171","msg":"trace[1111588317] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22653; }","duration":"122.539062ms","start":"2026-08-13T19:15:31.338772Z","end":"2026-08-13T19:15:31.461311Z","steps":["trace[1111588317] 'agreement among raft nodes before linearized reading' (duration: 121.558696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:31.719106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.85069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:31.719313Z","caller":"traceutil/trace.go:171","msg":"trace[1722839013] transaction","detail":"{read_only:false; response_revision:22655; number_of_response:1; }","duration":"169.218832ms","start":"2026-08-13T19:15:31.550074Z","end":"2026-08-13T19:15:31.719293Z","steps":["trace[1722839013] 'process raft request' (duration: 169.14473ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:31.719367Z","caller":"traceutil/trace.go:171","msg":"trace[391613981] transaction","detail":"{read_only:false; response_revision:22654; number_of_response:1; }","duration":"202.622169ms","start":"2026-08-13T19:15:31.516695Z","end":"2026-08-13T19:15:31.719317Z","steps":["trace[391613981] 'process raft request' (duration: 44.45356ms)","trace[391613981] 'compare' (duration: 157.677896ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:32.241878Z","caller":"traceutil/trace.go:171","msg":"trace[1820784770] linearizableReadLoop","detail":"{readStateIndex:23426; appliedIndex:23425; }","duration":"159.322599ms","start":"2026-08-13T19:15:32.082536Z","end":"2026-08-13T19:15:32.241859Z","steps":["trace[1820784770] 'read index received' (duration: 159.222227ms)","trace[1820784770] 'applied index is now lower than readState.Index' (duration: 98.892µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:32.242037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.481184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T19:15:32.242064Z","caller":"traceutil/trace.go:171","msg":"trace[1461642075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22657; }","duration":"159.559686ms","start":"2026-08-13T19:15:32.082498Z","end":"2026-08-13T19:15:32.242058Z","steps":["trace[1461642075] 'agreement among raft nodes before linearized reading' (duration: 159.433412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:34.21639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.097207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:34.216508Z","caller":"traceutil/trace.go:171","msg":"trace[693648633] linearizableReadLoop","detail":"{readStateIndex:23436; appliedIndex:23435; }","duration":"656.091626ms","start":"2026-08-13T19:15:33.560396Z","end":"2026-08-13T19:15:34.216487Z","steps":["trace[693648633] 'read index received' (duration: 31.833435ms)","trace[693648633] 'applied index is now lower than readState.Index' (duration: 624.256581ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:34.216699Z","caller":"traceutil/trace.go:171","msg":"trace[1747800472] transaction","detail":"{read_only:false; response_revision:22667; number_of_response:1; }","duration":"686.865033ms","start":"2026-08-13T19:15:33.529817Z","end":"2026-08-13T19:15:34.216682Z","steps":["trace[1747800472] 'process raft request' (duration: 62.392456ms)","trace[1747800472] 'compare' (duration: 623.304686ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:34.216764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:33.529797Z","time spent":"686.933955ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T19:15:34.216981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.562368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:34.217059Z","caller":"traceutil/trace.go:171","msg":"trace[1299232088] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:22667; }","duration":"656.703303ms","start":"2026-08-13T19:15:33.560338Z","end":"2026-08-13T19:15:34.217041Z","steps":["trace[1299232088] 'agreement among raft nodes before linearized reading' (duration: 656.530298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:34.217104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:33.560311Z","time spent":"656.782364ms","remote":"127.0.0.1:57748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-13T19:15:34.397704Z","caller":"traceutil/trace.go:171","msg":"trace[1032789789] linearizableReadLoop","detail":"{readStateIndex:23437; appliedIndex:23436; }","duration":"181.141979ms","start":"2026-08-13T19:15:34.216537Z","end":"2026-08-13T19:15:34.397679Z","steps":["trace[1032789789] 'read index received' (duration: 153.546537ms)","trace[1032789789] 'applied index is now lower than readState.Index' (duration: 27.594282ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:34.397974Z","caller":"traceutil/trace.go:171","msg":"trace[224544655] transaction","detail":"{read_only:false; response_revision:22668; number_of_response:1; }","duration":"672.275055ms","start":"2026-08-13T19:15:33.725685Z","end":"2026-08-13T19:15:34.397961Z","steps":["trace[224544655] 'process raft request' (duration: 644.383675ms)","trace[224544655] 'compare' (duration: 27.469679ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:34.398093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:33.725649Z","time spent":"672.353637ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:15:34.398397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.944105ms","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-08-13T19:15:34.398434Z","caller":"traceutil/trace.go:171","msg":"trace[1876995285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22668; }","duration":"441.014697ms","start":"2026-08-13T19:15:33.957407Z","end":"2026-08-13T19:15:34.398422Z","steps":["trace[1876995285] 'agreement among raft nodes before linearized reading' (duration: 440.865623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:34.398463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:33.957388Z","time spent":"441.068417ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T19:15:34.398648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.085757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-13T19:15:34.398676Z","caller":"traceutil/trace.go:171","msg":"trace[729871822] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22668; }","duration":"505.147439ms","start":"2026-08-13T19:15:33.893519Z","end":"2026-08-13T19:15:34.398667Z","steps":["trace[729871822] 'agreement among raft nodes before linearized reading' (duration: 505.061067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:34.398701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:33.893499Z","time spent":"505.1974ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-13T19:15:34.422611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.682618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:34.422663Z","caller":"traceutil/trace.go:171","msg":"trace[1882450121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22669; }","duration":"119.757829ms","start":"2026-08-13T19:15:34.302892Z","end":"2026-08-13T19:15:34.422649Z","steps":["trace[1882450121] 'agreement among raft nodes before linearized reading' (duration: 119.666797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:34.42286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.909411ms","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-08-13T19:15:34.422888Z","caller":"traceutil/trace.go:171","msg":"trace[2043332824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22669; }","duration":"163.939022ms","start":"2026-08-13T19:15:34.258941Z","end":"2026-08-13T19:15:34.42288Z","steps":["trace[2043332824] 'agreement among raft nodes before linearized reading' (duration: 163.84304ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:34.593613Z","caller":"traceutil/trace.go:171","msg":"trace[1645536934] transaction","detail":"{read_only:false; response_revision:22671; number_of_response:1; }","duration":"166.8606ms","start":"2026-08-13T19:15:34.426727Z","end":"2026-08-13T19:15:34.593588Z","steps":["trace[1645536934] 'process raft request' (duration: 166.804968ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:34.593731Z","caller":"traceutil/trace.go:171","msg":"trace[179143935] transaction","detail":"{read_only:false; response_revision:22670; number_of_response:1; }","duration":"189.257814ms","start":"2026-08-13T19:15:34.404445Z","end":"2026-08-13T19:15:34.593703Z","steps":["trace[179143935] 'process raft request' (duration: 93.221304ms)","trace[179143935] 'compare' (duration: 95.625199ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:34.593795Z","caller":"traceutil/trace.go:171","msg":"trace[364866338] linearizableReadLoop","detail":"{readStateIndex:23439; appliedIndex:23438; }","duration":"171.507503ms","start":"2026-08-13T19:15:34.422274Z","end":"2026-08-13T19:15:34.593782Z","steps":["trace[364866338] 'read index received' (duration: 75.312279ms)","trace[364866338] 'applied index is now lower than readState.Index' (duration: 96.194074ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:34.593933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.181302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:34.593962Z","caller":"traceutil/trace.go:171","msg":"trace[711482844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22671; }","duration":"169.237993ms","start":"2026-08-13T19:15:34.424717Z","end":"2026-08-13T19:15:34.593955Z","steps":["trace[711482844] 'agreement among raft nodes before linearized reading' (duration: 169.181071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:34.594057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.325609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-13T19:15:34.594096Z","caller":"traceutil/trace.go:171","msg":"trace[395540430] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22671; }","duration":"191.412891ms","start":"2026-08-13T19:15:34.402673Z","end":"2026-08-13T19:15:34.594086Z","steps":["trace[395540430] 'agreement among raft nodes before linearized reading' (duration: 191.185805ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:34.725869Z","caller":"traceutil/trace.go:171","msg":"trace[1342445302] transaction","detail":"{read_only:false; response_revision:22673; number_of_response:1; }","duration":"125.205114ms","start":"2026-08-13T19:15:34.600639Z","end":"2026-08-13T19:15:34.725844Z","steps":["trace[1342445302] 'process raft request' (duration: 93.229985ms)","trace[1342445302] 'compare' (duration: 31.62726ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:34.98887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.506853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:34.988974Z","caller":"traceutil/trace.go:171","msg":"trace[1265789300] transaction","detail":"{read_only:false; response_revision:22674; number_of_response:1; }","duration":"233.656114ms","start":"2026-08-13T19:15:34.755298Z","end":"2026-08-13T19:15:34.988954Z","steps":["trace[1265789300] 'process raft request' (duration: 116.992656ms)","trace[1265789300] 'compare' (duration: 116.257177ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:35.073672Z","caller":"traceutil/trace.go:171","msg":"trace[426623264] linearizableReadLoop","detail":"{readStateIndex:23444; appliedIndex:23442; }","duration":"297.693133ms","start":"2026-08-13T19:15:34.775957Z","end":"2026-08-13T19:15:35.07365Z","steps":["trace[426623264] 'read index received' (duration: 96.43458ms)","trace[426623264] 'applied index is now lower than readState.Index' (duration: 201.257423ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:35.074121Z","caller":"traceutil/trace.go:171","msg":"trace[72519771] transaction","detail":"{read_only:false; response_revision:22675; number_of_response:1; }","duration":"313.933274ms","start":"2026-08-13T19:15:34.760172Z","end":"2026-08-13T19:15:35.074105Z","steps":["trace[72519771] 'process raft request' (duration: 313.290657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.074236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:34.760165Z","time spent":"313.993595ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8776,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:15:35.074509Z","caller":"traceutil/trace.go:171","msg":"trace[1626872115] transaction","detail":"{read_only:false; response_revision:22676; number_of_response:1; }","duration":"245.933528ms","start":"2026-08-13T19:15:34.828563Z","end":"2026-08-13T19:15:35.074497Z","steps":["trace[1626872115] 'process raft request' (duration: 245.033884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.074746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.773131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:35.074799Z","caller":"traceutil/trace.go:171","msg":"trace[422346423] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:22676; }","duration":"298.851833ms","start":"2026-08-13T19:15:34.775931Z","end":"2026-08-13T19:15:35.074783Z","steps":["trace[422346423] 'agreement among raft nodes before linearized reading' (duration: 298.75773ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:35.28746Z","caller":"traceutil/trace.go:171","msg":"trace[1500955738] linearizableReadLoop","detail":"{readStateIndex:23447; appliedIndex:23445; }","duration":"213.60432ms","start":"2026-08-13T19:15:35.073827Z","end":"2026-08-13T19:15:35.287431Z","steps":["trace[1500955738] 'read index received' (duration: 213.375674ms)","trace[1500955738] 'applied index is now lower than readState.Index' (duration: 227.146µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:35.287581Z","caller":"traceutil/trace.go:171","msg":"trace[1654405733] transaction","detail":"{read_only:false; response_revision:22677; number_of_response:1; }","duration":"294.277772ms","start":"2026-08-13T19:15:34.993259Z","end":"2026-08-13T19:15:35.287537Z","steps":["trace[1654405733] 'process raft request' (duration: 293.957043ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:35.287597Z","caller":"traceutil/trace.go:171","msg":"trace[602156402] transaction","detail":"{read_only:false; response_revision:22678; number_of_response:1; }","duration":"283.481596ms","start":"2026-08-13T19:15:35.004082Z","end":"2026-08-13T19:15:35.287564Z","steps":["trace[602156402] 'process raft request' (duration: 283.28267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.287684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.37608ms","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-08-13T19:15:35.287722Z","caller":"traceutil/trace.go:171","msg":"trace[1664670527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22678; }","duration":"223.454651ms","start":"2026-08-13T19:15:35.064254Z","end":"2026-08-13T19:15:35.287709Z","steps":["trace[1664670527] 'agreement among raft nodes before linearized reading' (duration: 223.309418ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:35.333132Z","caller":"traceutil/trace.go:171","msg":"trace[2103984769] transaction","detail":"{read_only:false; response_revision:22679; number_of_response:1; }","duration":"212.140842ms","start":"2026-08-13T19:15:35.12097Z","end":"2026-08-13T19:15:35.333111Z","steps":["trace[2103984769] 'process raft request' (duration: 211.760382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.333376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.577388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/octavia-bootstrap-pgfrk\" ","response":"range_response_count:1 size:8277"} {"level":"info","ts":"2026-08-13T19:15:35.333434Z","caller":"traceutil/trace.go:171","msg":"trace[1537159765] range","detail":"{range_begin:/registry/pods/openstack/octavia-bootstrap-pgfrk; range_end:; response_count:1; response_revision:22679; }","duration":"254.680821ms","start":"2026-08-13T19:15:35.07874Z","end":"2026-08-13T19:15:35.333421Z","steps":["trace[1537159765] 'agreement among raft nodes before linearized reading' (duration: 254.474826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.333902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.186373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T19:15:35.333952Z","caller":"traceutil/trace.go:171","msg":"trace[42623837] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:22679; }","duration":"160.280435ms","start":"2026-08-13T19:15:35.173659Z","end":"2026-08-13T19:15:35.33394Z","steps":["trace[42623837] 'agreement among raft nodes before linearized reading' (duration: 159.420242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.770793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.622095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:35.770902Z","caller":"traceutil/trace.go:171","msg":"trace[1250547111] linearizableReadLoop","detail":"{readStateIndex:23449; appliedIndex:23448; }","duration":"437.856973ms","start":"2026-08-13T19:15:35.333024Z","end":"2026-08-13T19:15:35.770881Z","steps":["trace[1250547111] 'read index received' (duration: 228.102575ms)","trace[1250547111] 'applied index is now lower than readState.Index' (duration: 209.752788ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:35.771Z","caller":"traceutil/trace.go:171","msg":"trace[1996003575] transaction","detail":"{read_only:false; response_revision:22680; number_of_response:1; }","duration":"475.733399ms","start":"2026-08-13T19:15:35.295219Z","end":"2026-08-13T19:15:35.770952Z","steps":["trace[1996003575] 'process raft request' (duration: 265.831997ms)","trace[1996003575] 'compare' (duration: 209.208554ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:35.771284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.677162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:35.771317Z","caller":"traceutil/trace.go:171","msg":"trace[318014541] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22680; }","duration":"439.94776ms","start":"2026-08-13T19:15:35.331358Z","end":"2026-08-13T19:15:35.771306Z","steps":["trace[318014541] 'agreement among raft nodes before linearized reading' (duration: 439.603401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.771363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:35.295196Z","time spent":"475.886784ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:15:35.771367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:35.331331Z","time spent":"440.010941ms","remote":"127.0.0.1:42910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-13T19:15:35.812227Z","caller":"traceutil/trace.go:171","msg":"trace[537296968] transaction","detail":"{read_only:false; response_revision:22681; number_of_response:1; }","duration":"468.434645ms","start":"2026-08-13T19:15:35.343762Z","end":"2026-08-13T19:15:35.812196Z","steps":["trace[537296968] 'process raft request' (duration: 468.191799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.812405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:35.343727Z","time spent":"468.582309ms","remote":"127.0.0.1:42924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8502,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:15:35.812406Z","caller":"traceutil/trace.go:171","msg":"trace[1556860081] transaction","detail":"{read_only:false; response_revision:22682; number_of_response:1; }","duration":"427.507499ms","start":"2026-08-13T19:15:35.384885Z","end":"2026-08-13T19:15:35.812393Z","steps":["trace[1556860081] 'process raft request' (duration: 427.246572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.812582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:35.384863Z","time spent":"427.687973ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:15:35.812646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.545264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:35.812697Z","caller":"traceutil/trace.go:171","msg":"trace[457992078] transaction","detail":"{read_only:false; response_revision:22683; number_of_response:1; }","duration":"260.218138ms","start":"2026-08-13T19:15:35.552426Z","end":"2026-08-13T19:15:35.812644Z","steps":["trace[457992078] 'process raft request' (duration: 259.91424ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:35.812725Z","caller":"traceutil/trace.go:171","msg":"trace[1686433816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22683; }","duration":"446.677278ms","start":"2026-08-13T19:15:35.36603Z","end":"2026-08-13T19:15:35.812707Z","steps":["trace[1686433816] 'agreement among raft nodes before linearized reading' (duration: 446.523384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.812854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.488553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:35.812889Z","caller":"traceutil/trace.go:171","msg":"trace[1288395914] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22683; }","duration":"330.577716ms","start":"2026-08-13T19:15:35.482304Z","end":"2026-08-13T19:15:35.812882Z","steps":["trace[1288395914] 'agreement among raft nodes before linearized reading' (duration: 330.445873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.812897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.072549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-13T19:15:35.812896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:35.366012Z","time spent":"446.849563ms","remote":"127.0.0.1:42732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T19:15:35.812927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:35.482297Z","time spent":"330.622137ms","remote":"127.0.0.1:42910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-13T19:15:35.812941Z","caller":"traceutil/trace.go:171","msg":"trace[1778250088] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22683; }","duration":"331.163942ms","start":"2026-08-13T19:15:35.481766Z","end":"2026-08-13T19:15:35.81293Z","steps":["trace[1778250088] 'agreement among raft nodes before linearized reading' (duration: 331.025058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:35.812972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:35.48174Z","time spent":"331.223863ms","remote":"127.0.0.1:42910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-13T19:15:36.554337Z","caller":"traceutil/trace.go:171","msg":"trace[2014825734] transaction","detail":"{read_only:false; response_revision:22684; number_of_response:1; }","duration":"148.530953ms","start":"2026-08-13T19:15:36.405772Z","end":"2026-08-13T19:15:36.554303Z","steps":["trace[2014825734] 'process raft request' (duration: 148.395609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:36.829074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.49355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:36.829614Z","caller":"traceutil/trace.go:171","msg":"trace[91963922] transaction","detail":"{read_only:false; response_revision:22687; number_of_response:1; }","duration":"133.977407ms","start":"2026-08-13T19:15:36.695609Z","end":"2026-08-13T19:15:36.829586Z","steps":["trace[91963922] 'process raft request' (duration: 27.8725ms)","trace[91963922] 'compare' (duration: 105.249624ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:36.829996Z","caller":"traceutil/trace.go:171","msg":"trace[367209686] transaction","detail":"{read_only:false; response_revision:22688; number_of_response:1; }","duration":"122.677266ms","start":"2026-08-13T19:15:36.707308Z","end":"2026-08-13T19:15:36.829985Z","steps":["trace[367209686] 'process raft request' (duration: 121.910506ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:36.830185Z","caller":"traceutil/trace.go:171","msg":"trace[1633310471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22688; }","duration":"100.031526ms","start":"2026-08-13T19:15:36.730145Z","end":"2026-08-13T19:15:36.830177Z","steps":["trace[1633310471] 'agreement among raft nodes before linearized reading' (duration: 99.943263ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:36.991704Z","caller":"traceutil/trace.go:171","msg":"trace[188718632] transaction","detail":"{read_only:false; response_revision:22690; number_of_response:1; }","duration":"154.312426ms","start":"2026-08-13T19:15:36.837356Z","end":"2026-08-13T19:15:36.991669Z","steps":["trace[188718632] 'process raft request' (duration: 154.121521ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:36.991936Z","caller":"traceutil/trace.go:171","msg":"trace[1416028230] transaction","detail":"{read_only:false; response_revision:22689; number_of_response:1; }","duration":"155.063967ms","start":"2026-08-13T19:15:36.836838Z","end":"2026-08-13T19:15:36.991902Z","steps":["trace[1416028230] 'process raft request' (duration: 112.809855ms)","trace[1416028230] 'compare' (duration: 41.525732ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:38.836582Z","caller":"traceutil/trace.go:171","msg":"trace[440084994] linearizableReadLoop","detail":"{readStateIndex:23470; appliedIndex:23469; }","duration":"129.727744ms","start":"2026-08-13T19:15:38.706832Z","end":"2026-08-13T19:15:38.836559Z","steps":["trace[440084994] 'read index received' (duration: 129.549709ms)","trace[440084994] 'applied index is now lower than readState.Index' (duration: 177.305µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:38.836916Z","caller":"traceutil/trace.go:171","msg":"trace[540764652] transaction","detail":"{read_only:false; response_revision:22700; number_of_response:1; }","duration":"143.260143ms","start":"2026-08-13T19:15:38.69364Z","end":"2026-08-13T19:15:38.8369Z","steps":["trace[540764652] 'process raft request' (duration: 142.79691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:38.837767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.907705ms","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-08-13T19:15:38.837831Z","caller":"traceutil/trace.go:171","msg":"trace[1275358083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22700; }","duration":"131.020328ms","start":"2026-08-13T19:15:38.706796Z","end":"2026-08-13T19:15:38.837816Z","steps":["trace[1275358083] 'agreement among raft nodes before linearized reading' (duration: 130.872374ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:38.964977Z","caller":"traceutil/trace.go:171","msg":"trace[1276792977] transaction","detail":"{read_only:false; response_revision:22701; number_of_response:1; }","duration":"121.939237ms","start":"2026-08-13T19:15:38.842815Z","end":"2026-08-13T19:15:38.964754Z","steps":["trace[1276792977] 'process raft request' (duration: 94.341304ms)","trace[1276792977] 'compare' (duration: 27.443469ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:38.965678Z","caller":"traceutil/trace.go:171","msg":"trace[1700659611] transaction","detail":"{read_only:false; response_revision:22702; number_of_response:1; }","duration":"122.684637ms","start":"2026-08-13T19:15:38.842978Z","end":"2026-08-13T19:15:38.965663Z","steps":["trace[1700659611] 'process raft request' (duration: 122.335258ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:39.350546Z","caller":"traceutil/trace.go:171","msg":"trace[1300069119] transaction","detail":"{read_only:false; response_revision:22704; number_of_response:1; }","duration":"149.780987ms","start":"2026-08-13T19:15:39.200731Z","end":"2026-08-13T19:15:39.350512Z","steps":["trace[1300069119] 'process raft request' (duration: 149.079608ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:40.201576Z","caller":"traceutil/trace.go:171","msg":"trace[1574825448] linearizableReadLoop","detail":"{readStateIndex:23476; appliedIndex:23475; }","duration":"333.110753ms","start":"2026-08-13T19:15:39.868436Z","end":"2026-08-13T19:15:40.201546Z","steps":["trace[1574825448] 'read index received' (duration: 332.965199ms)","trace[1574825448] 'applied index is now lower than readState.Index' (duration: 144.244µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:40.201712Z","caller":"traceutil/trace.go:171","msg":"trace[1102842577] transaction","detail":"{read_only:false; response_revision:22706; number_of_response:1; }","duration":"350.180596ms","start":"2026-08-13T19:15:39.851519Z","end":"2026-08-13T19:15:40.2017Z","steps":["trace[1102842577] 'process raft request' (duration: 349.844387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:40.201863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:39.851501Z","time spent":"350.241928ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:15:40.202091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.597086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-13T19:15:40.202236Z","caller":"traceutil/trace.go:171","msg":"trace[1526089058] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22706; }","duration":"333.783871ms","start":"2026-08-13T19:15:39.86842Z","end":"2026-08-13T19:15:40.202204Z","steps":["trace[1526089058] 'agreement among raft nodes before linearized reading' (duration: 333.318249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:40.202339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:39.868412Z","time spent":"333.886424ms","remote":"127.0.0.1:42910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-13T19:15:40.405529Z","caller":"traceutil/trace.go:171","msg":"trace[359013808] transaction","detail":"{read_only:false; response_revision:22708; number_of_response:1; }","duration":"534.380856ms","start":"2026-08-13T19:15:39.871118Z","end":"2026-08-13T19:15:40.405499Z","steps":["trace[359013808] 'process raft request' (duration: 534.331645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:40.405858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:39.871111Z","time spent":"534.506109ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:15:40.40634Z","caller":"traceutil/trace.go:171","msg":"trace[304924191] transaction","detail":"{read_only:false; response_revision:22707; number_of_response:1; }","duration":"536.627255ms","start":"2026-08-13T19:15:39.869677Z","end":"2026-08-13T19:15:40.406304Z","steps":["trace[304924191] 'process raft request' (duration: 535.599518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:40.406402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:39.869665Z","time spent":"536.703497ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:15:40.41842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.879052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:40.418488Z","caller":"traceutil/trace.go:171","msg":"trace[348720660] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:22708; }","duration":"343.011985ms","start":"2026-08-13T19:15:40.07546Z","end":"2026-08-13T19:15:40.418472Z","steps":["trace[348720660] 'agreement among raft nodes before linearized reading' (duration: 342.891462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:40.418518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:40.075432Z","time spent":"343.079207ms","remote":"127.0.0.1:59724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-08-13T19:15:40.418603Z","caller":"traceutil/trace.go:171","msg":"trace[335584369] linearizableReadLoop","detail":"{readStateIndex:23478; appliedIndex:23478; }","duration":"204.845488ms","start":"2026-08-13T19:15:40.201655Z","end":"2026-08-13T19:15:40.4065Z","steps":["trace[335584369] 'read index received' (duration: 204.836137ms)","trace[335584369] 'applied index is now lower than readState.Index' (duration: 7.071µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:40.41881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.73946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T19:15:40.418821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.902143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:40.418841Z","caller":"traceutil/trace.go:171","msg":"trace[1476372538] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22708; }","duration":"527.786301ms","start":"2026-08-13T19:15:39.891047Z","end":"2026-08-13T19:15:40.418834Z","steps":["trace[1476372538] 'agreement among raft nodes before linearized reading' (duration: 527.238257ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:40.418858Z","caller":"traceutil/trace.go:171","msg":"trace[2017476931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22708; }","duration":"120.005425ms","start":"2026-08-13T19:15:40.298841Z","end":"2026-08-13T19:15:40.418847Z","steps":["trace[2017476931] 'agreement among raft nodes before linearized reading' (duration: 119.903843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:40.418876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:39.891041Z","time spent":"527.827372ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-13T19:15:40.535028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.261502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-13T19:15:40.535165Z","caller":"traceutil/trace.go:171","msg":"trace[1039852971] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:22708; }","duration":"100.451857ms","start":"2026-08-13T19:15:40.434679Z","end":"2026-08-13T19:15:40.53513Z","steps":["trace[1039852971] 'range keys from in-memory index tree' (duration: 99.995065ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:41.5152Z","caller":"traceutil/trace.go:171","msg":"trace[1479647709] transaction","detail":"{read_only:false; response_revision:22715; number_of_response:1; }","duration":"122.549793ms","start":"2026-08-13T19:15:41.39262Z","end":"2026-08-13T19:15:41.51517Z","steps":["trace[1479647709] 'process raft request' (duration: 74.842147ms)","trace[1479647709] 'compare' (duration: 47.055359ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:42.636383Z","caller":"traceutil/trace.go:171","msg":"trace[201547032] transaction","detail":"{read_only:false; response_revision:22720; number_of_response:1; }","duration":"208.725092ms","start":"2026-08-13T19:15:42.427637Z","end":"2026-08-13T19:15:42.636362Z","steps":["trace[201547032] 'process raft request' (duration: 208.695681ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:42.636559Z","caller":"traceutil/trace.go:171","msg":"trace[669743754] transaction","detail":"{read_only:false; response_revision:22719; number_of_response:1; }","duration":"212.017929ms","start":"2026-08-13T19:15:42.424493Z","end":"2026-08-13T19:15:42.636511Z","steps":["trace[669743754] 'process raft request' (duration: 211.70326ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:42.714507Z","caller":"traceutil/trace.go:171","msg":"trace[1213426768] transaction","detail":"{read_only:false; response_revision:22721; number_of_response:1; }","duration":"163.420458ms","start":"2026-08-13T19:15:42.551043Z","end":"2026-08-13T19:15:42.714463Z","steps":["trace[1213426768] 'process raft request' (duration: 123.454307ms)","trace[1213426768] 'compare' (duration: 39.705424ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:42.714821Z","caller":"traceutil/trace.go:171","msg":"trace[625534373] transaction","detail":"{read_only:false; response_revision:22722; number_of_response:1; }","duration":"134.940223ms","start":"2026-08-13T19:15:42.579842Z","end":"2026-08-13T19:15:42.714782Z","steps":["trace[625534373] 'process raft request' (duration: 134.545642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:42.933615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.137564ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T19:15:42.933765Z","caller":"traceutil/trace.go:171","msg":"trace[988020061] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:22723; }","duration":"110.307088ms","start":"2026-08-13T19:15:42.823436Z","end":"2026-08-13T19:15:42.933743Z","steps":["trace[988020061] 'range keys from in-memory index tree' (duration: 110.116123ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:43.077877Z","caller":"traceutil/trace.go:171","msg":"trace[492336174] transaction","detail":"{read_only:false; response_revision:22724; number_of_response:1; }","duration":"140.600483ms","start":"2026-08-13T19:15:42.937254Z","end":"2026-08-13T19:15:43.077854Z","steps":["trace[492336174] 'process raft request' (duration: 139.916075ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:43.276034Z","caller":"traceutil/trace.go:171","msg":"trace[2022548889] transaction","detail":"{read_only:false; response_revision:22726; number_of_response:1; }","duration":"157.319966ms","start":"2026-08-13T19:15:43.118684Z","end":"2026-08-13T19:15:43.276004Z","steps":["trace[2022548889] 'process raft request' (duration: 75.603947ms)","trace[2022548889] 'compare' (duration: 81.300388ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:43.276173Z","caller":"traceutil/trace.go:171","msg":"trace[196424079] transaction","detail":"{read_only:false; response_revision:22727; number_of_response:1; }","duration":"156.778872ms","start":"2026-08-13T19:15:43.119355Z","end":"2026-08-13T19:15:43.276134Z","steps":["trace[196424079] 'process raft request' (duration: 156.600397ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:15:44.757932Z","caller":"traceutil/trace.go:171","msg":"trace[278195633] transaction","detail":"{read_only:false; response_revision:22730; number_of_response:1; }","duration":"115.538137ms","start":"2026-08-13T19:15:44.642351Z","end":"2026-08-13T19:15:44.757889Z","steps":["trace[278195633] 'process raft request' (duration: 115.30854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:45.038414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.538285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:45.038728Z","caller":"traceutil/trace.go:171","msg":"trace[953228536] transaction","detail":"{read_only:false; response_revision:22731; number_of_response:1; }","duration":"395.531679ms","start":"2026-08-13T19:15:44.643129Z","end":"2026-08-13T19:15:45.03866Z","steps":["trace[953228536] 'process raft request' (duration: 180.526342ms)","trace[953228536] 'compare' (duration: 214.276488ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:45.038936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:44.64309Z","time spent":"395.704164ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:15:45.040113Z","caller":"traceutil/trace.go:171","msg":"trace[1654130688] transaction","detail":"{read_only:false; response_revision:22732; number_of_response:1; }","duration":"319.033798ms","start":"2026-08-13T19:15:44.721058Z","end":"2026-08-13T19:15:45.040092Z","steps":["trace[1654130688] 'process raft request' (duration: 318.852484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:45.040206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:15:44.721038Z","time spent":"319.125491ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:15:45.363091Z","caller":"traceutil/trace.go:171","msg":"trace[2081269623] transaction","detail":"{read_only:false; response_revision:22735; number_of_response:1; }","duration":"226.831052ms","start":"2026-08-13T19:15:45.136203Z","end":"2026-08-13T19:15:45.363034Z","steps":["trace[2081269623] 'process raft request' (duration: 226.525864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:47.254298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.994843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T19:15:47.254472Z","caller":"traceutil/trace.go:171","msg":"trace[430619463] linearizableReadLoop","detail":"{readStateIndex:23516; appliedIndex:23514; }","duration":"199.347761ms","start":"2026-08-13T19:15:47.055102Z","end":"2026-08-13T19:15:47.254449Z","steps":["trace[430619463] 'read index received' (duration: 5.212768ms)","trace[430619463] 'applied index is now lower than readState.Index' (duration: 194.133143ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:15:47.254518Z","caller":"traceutil/trace.go:171","msg":"trace[2086083801] transaction","detail":"{read_only:false; response_revision:22744; number_of_response:1; }","duration":"207.55738ms","start":"2026-08-13T19:15:47.04691Z","end":"2026-08-13T19:15:47.254467Z","steps":["trace[2086083801] 'process raft request' (duration: 95.28113ms)","trace[2086083801] 'compare' (duration: 111.848589ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:15:47.25471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.471811ms","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-08-13T19:15:47.254809Z","caller":"traceutil/trace.go:171","msg":"trace[59269718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22744; }","duration":"122.561904ms","start":"2026-08-13T19:15:47.132193Z","end":"2026-08-13T19:15:47.254755Z","steps":["trace[59269718] 'agreement among raft nodes before linearized reading' (duration: 122.392819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:47.254717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.607518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-08-13T19:15:47.255246Z","caller":"traceutil/trace.go:171","msg":"trace[1800545756] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:22744; }","duration":"200.155533ms","start":"2026-08-13T19:15:47.055071Z","end":"2026-08-13T19:15:47.255226Z","steps":["trace[1800545756] 'agreement among raft nodes before linearized reading' (duration: 199.465495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:15:50.791818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.533047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-13T19:15:50.791903Z","caller":"traceutil/trace.go:171","msg":"trace[2060213052] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22767; }","duration":"118.682401ms","start":"2026-08-13T19:15:50.673201Z","end":"2026-08-13T19:15:50.791884Z","steps":["trace[2060213052] 'range keys from in-memory index tree' (duration: 118.311051ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:16:22.009918Z","caller":"traceutil/trace.go:171","msg":"trace[1060923162] linearizableReadLoop","detail":"{readStateIndex:23864; appliedIndex:23863; }","duration":"149.53645ms","start":"2026-08-13T19:16:21.860347Z","end":"2026-08-13T19:16:22.009883Z","steps":["trace[1060923162] 'read index received' (duration: 149.258882ms)","trace[1060923162] 'applied index is now lower than readState.Index' (duration: 275.698µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:16:22.010059Z","caller":"traceutil/trace.go:171","msg":"trace[2044705929] transaction","detail":"{read_only:false; response_revision:23083; number_of_response:1; }","duration":"176.201698ms","start":"2026-08-13T19:16:21.833801Z","end":"2026-08-13T19:16:22.010003Z","steps":["trace[2044705929] 'process raft request' (duration: 175.814217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:16:22.01017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.806017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-13T19:16:22.010218Z","caller":"traceutil/trace.go:171","msg":"trace[1069430812] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:23083; }","duration":"149.92696ms","start":"2026-08-13T19:16:21.860274Z","end":"2026-08-13T19:16:22.010201Z","steps":["trace[1069430812] 'agreement among raft nodes before linearized reading' (duration: 149.738805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:16:25.721971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.151742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T19:16:25.72206Z","caller":"traceutil/trace.go:171","msg":"trace[863174983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23097; }","duration":"128.294736ms","start":"2026-08-13T19:16:25.593745Z","end":"2026-08-13T19:16:25.72204Z","steps":["trace[863174983] 'range keys from in-memory index tree' (duration: 127.902086ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:16:25.876571Z","caller":"traceutil/trace.go:171","msg":"trace[1645642723] linearizableReadLoop","detail":"{readStateIndex:23882; appliedIndex:23880; }","duration":"125.839091ms","start":"2026-08-13T19:16:25.75071Z","end":"2026-08-13T19:16:25.876549Z","steps":["trace[1645642723] 'read index received' (duration: 49.418822ms)","trace[1645642723] 'applied index is now lower than readState.Index' (duration: 76.418819ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:16:25.876603Z","caller":"traceutil/trace.go:171","msg":"trace[1154324007] transaction","detail":"{read_only:false; response_revision:23100; number_of_response:1; }","duration":"149.466738ms","start":"2026-08-13T19:16:25.727105Z","end":"2026-08-13T19:16:25.876572Z","steps":["trace[1154324007] 'process raft request' (duration: 149.090228ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:16:25.876288Z","caller":"traceutil/trace.go:171","msg":"trace[1932323228] transaction","detail":"{read_only:false; response_revision:23099; number_of_response:1; }","duration":"149.756416ms","start":"2026-08-13T19:16:25.726498Z","end":"2026-08-13T19:16:25.876254Z","steps":["trace[1932323228] 'process raft request' (duration: 108.796999ms)","trace[1932323228] 'compare' (duration: 40.778372ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:16:25.876751Z","caller":"traceutil/trace.go:171","msg":"trace[1644220224] transaction","detail":"{read_only:false; response_revision:23101; number_of_response:1; }","duration":"106.732263ms","start":"2026-08-13T19:16:25.769876Z","end":"2026-08-13T19:16:25.876608Z","steps":["trace[1644220224] 'process raft request' (duration: 106.582999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:16:25.876806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.068697ms","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-08-13T19:16:25.877041Z","caller":"traceutil/trace.go:171","msg":"trace[451087630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23101; }","duration":"126.319504ms","start":"2026-08-13T19:16:25.750683Z","end":"2026-08-13T19:16:25.877002Z","steps":["trace[451087630] 'agreement among raft nodes before linearized reading' (duration: 125.948454ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:16:29.994781Z","caller":"traceutil/trace.go:171","msg":"trace[1923059989] linearizableReadLoop","detail":"{readStateIndex:23905; appliedIndex:23904; }","duration":"101.199866ms","start":"2026-08-13T19:16:29.893561Z","end":"2026-08-13T19:16:29.994761Z","steps":["trace[1923059989] 'read index received' (duration: 96.537343ms)","trace[1923059989] 'applied index is now lower than readState.Index' (duration: 4.661373ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:16:29.994939Z","caller":"traceutil/trace.go:171","msg":"trace[2046152493] transaction","detail":"{read_only:false; response_revision:23122; number_of_response:1; }","duration":"103.800926ms","start":"2026-08-13T19:16:29.891091Z","end":"2026-08-13T19:16:29.994891Z","steps":["trace[2046152493] 'process raft request' (duration: 98.938287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:16:29.995046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.862707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T19:16:29.995079Z","caller":"traceutil/trace.go:171","msg":"trace[1034785603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23122; }","duration":"103.946059ms","start":"2026-08-13T19:16:29.891123Z","end":"2026-08-13T19:16:29.995069Z","steps":["trace[1034785603] 'agreement among raft nodes before linearized reading' (duration: 103.828206ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:16:30.000907Z","caller":"traceutil/trace.go:171","msg":"trace[833000038] transaction","detail":"{read_only:false; response_revision:23123; number_of_response:1; }","duration":"101.987257ms","start":"2026-08-13T19:16:29.8989Z","end":"2026-08-13T19:16:30.000888Z","steps":["trace[833000038] 'process raft request' (duration: 101.748111ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:01.888643Z","caller":"traceutil/trace.go:171","msg":"trace[1819019402] transaction","detail":"{read_only:false; response_revision:23895; number_of_response:1; }","duration":"181.049087ms","start":"2026-08-13T19:17:01.707554Z","end":"2026-08-13T19:17:01.888603Z","steps":["trace[1819019402] 'process raft request' (duration: 180.709918ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:01.933383Z","caller":"traceutil/trace.go:171","msg":"trace[1824420727] transaction","detail":"{read_only:false; response_revision:23896; number_of_response:1; }","duration":"225.790064ms","start":"2026-08-13T19:17:01.70756Z","end":"2026-08-13T19:17:01.93335Z","steps":["trace[1824420727] 'process raft request' (duration: 225.513306ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:01.933457Z","caller":"traceutil/trace.go:171","msg":"trace[1853686984] linearizableReadLoop","detail":"{readStateIndex:24698; appliedIndex:24694; }","duration":"173.578038ms","start":"2026-08-13T19:17:01.759854Z","end":"2026-08-13T19:17:01.933432Z","steps":["trace[1853686984] 'read index received' (duration: 128.493781ms)","trace[1853686984] 'applied index is now lower than readState.Index' (duration: 45.083047ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:01.933704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.837715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:87328"} {"level":"info","ts":"2026-08-13T19:17:01.933731Z","caller":"traceutil/trace.go:171","msg":"trace[1811785191] transaction","detail":"{read_only:false; response_revision:23897; number_of_response:1; }","duration":"221.269904ms","start":"2026-08-13T19:17:01.712443Z","end":"2026-08-13T19:17:01.933713Z","steps":["trace[1811785191] 'process raft request' (duration: 220.815122ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:01.933757Z","caller":"traceutil/trace.go:171","msg":"trace[1865190935] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23899; }","duration":"173.947088ms","start":"2026-08-13T19:17:01.759795Z","end":"2026-08-13T19:17:01.933742Z","steps":["trace[1865190935] 'agreement among raft nodes before linearized reading' (duration: 173.750253ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:01.933953Z","caller":"traceutil/trace.go:171","msg":"trace[1594086272] transaction","detail":"{read_only:false; response_revision:23899; number_of_response:1; }","duration":"158.341723ms","start":"2026-08-13T19:17:01.775595Z","end":"2026-08-13T19:17:01.933937Z","steps":["trace[1594086272] 'process raft request' (duration: 157.795409ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:01.934009Z","caller":"traceutil/trace.go:171","msg":"trace[1823048930] transaction","detail":"{read_only:false; response_revision:23898; number_of_response:1; }","duration":"209.288396ms","start":"2026-08-13T19:17:01.72465Z","end":"2026-08-13T19:17:01.933938Z","steps":["trace[1823048930] 'process raft request' (duration: 208.67731ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:03.108307Z","caller":"traceutil/trace.go:171","msg":"trace[789911055] transaction","detail":"{read_only:false; response_revision:23927; number_of_response:1; }","duration":"177.371449ms","start":"2026-08-13T19:17:02.930904Z","end":"2026-08-13T19:17:03.108276Z","steps":["trace[789911055] 'process raft request' (duration: 83.947179ms)","trace[789911055] 'compare' (duration: 93.143203ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:03.153708Z","caller":"traceutil/trace.go:171","msg":"trace[1004897869] transaction","detail":"{read_only:false; response_revision:23928; number_of_response:1; }","duration":"182.088024ms","start":"2026-08-13T19:17:02.971572Z","end":"2026-08-13T19:17:03.15366Z","steps":["trace[1004897869] 'process raft request' (duration: 181.814617ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:03.667973Z","caller":"traceutil/trace.go:171","msg":"trace[1676674456] transaction","detail":"{read_only:false; response_revision:23929; number_of_response:1; }","duration":"510.05917ms","start":"2026-08-13T19:17:03.15788Z","end":"2026-08-13T19:17:03.667939Z","steps":["trace[1676674456] 'process raft request' (duration: 462.150178ms)","trace[1676674456] 'compare' (duration: 47.446679ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:03.66814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.157849Z","time spent":"510.213284ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:04.58031Z","caller":"traceutil/trace.go:171","msg":"trace[1012346403] transaction","detail":"{read_only:false; response_revision:23930; number_of_response:1; }","duration":"1.392375272s","start":"2026-08-13T19:17:03.187898Z","end":"2026-08-13T19:17:04.580273Z","steps":["trace[1012346403] 'process raft request' (duration: 1.392078354s)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:04.580374Z","caller":"traceutil/trace.go:171","msg":"trace[2076061527] transaction","detail":"{read_only:false; response_revision:23932; number_of_response:1; }","duration":"1.109241306s","start":"2026-08-13T19:17:03.471104Z","end":"2026-08-13T19:17:04.580346Z","steps":["trace[2076061527] 'process raft request' (duration: 1.109140043s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.580522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.187868Z","time spent":"1.392539597s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":973,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T19:17:04.580563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.471078Z","time spent":"1.109378119s","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:04.580593Z","caller":"traceutil/trace.go:171","msg":"trace[1998572125] linearizableReadLoop","detail":"{readStateIndex:24731; appliedIndex:24729; }","duration":"1.372077923s","start":"2026-08-13T19:17:03.208468Z","end":"2026-08-13T19:17:04.580546Z","steps":["trace[1998572125] 'read index received' (duration: 411.618016ms)","trace[1998572125] 'applied index is now lower than readState.Index' (duration: 960.456707ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:04.580714Z","caller":"traceutil/trace.go:171","msg":"trace[2135447076] transaction","detail":"{read_only:false; response_revision:23933; number_of_response:1; }","duration":"1.064481508s","start":"2026-08-13T19:17:03.516224Z","end":"2026-08-13T19:17:04.580706Z","steps":["trace[2135447076] 'process raft request' (duration: 1.064079137s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.580815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.516196Z","time spent":"1.064536549s","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:04.580871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372404732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-08-13T19:17:04.580932Z","caller":"traceutil/trace.go:171","msg":"trace[519898130] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23933; }","duration":"1.372516735s","start":"2026-08-13T19:17:03.208398Z","end":"2026-08-13T19:17:04.580914Z","steps":["trace[519898130] 'agreement among raft nodes before linearized reading' (duration: 1.372294069s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.580979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.208371Z","time spent":"1.372599877s","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":648,"request content":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" "} {"level":"info","ts":"2026-08-13T19:17:04.580997Z","caller":"traceutil/trace.go:171","msg":"trace[116943049] transaction","detail":"{read_only:false; response_revision:23931; number_of_response:1; }","duration":"1.291568206s","start":"2026-08-13T19:17:03.289371Z","end":"2026-08-13T19:17:04.580939Z","steps":["trace[116943049] 'process raft request' (duration: 1.290791145s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.581154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.289338Z","time spent":"1.291755501s","remote":"127.0.0.1:42916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18433,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:04.716512Z","caller":"traceutil/trace.go:171","msg":"trace[2078526797] transaction","detail":"{read_only:false; response_revision:23934; number_of_response:1; }","duration":"774.888341ms","start":"2026-08-13T19:17:03.941575Z","end":"2026-08-13T19:17:04.716463Z","steps":["trace[2078526797] 'process raft request' (duration: 761.147646ms)","trace[2078526797] 'compare' (duration: 13.466167ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T19:17:04.716529Z","caller":"traceutil/trace.go:171","msg":"trace[1516721731] transaction","detail":"{read_only:false; response_revision:23935; number_of_response:1; }","duration":"709.704511ms","start":"2026-08-13T19:17:04.006796Z","end":"2026-08-13T19:17:04.7165Z","steps":["trace[1516721731] 'process raft request' (duration: 709.556917ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:04.716569Z","caller":"traceutil/trace.go:171","msg":"trace[1803121731] linearizableReadLoop","detail":"{readStateIndex:24738; appliedIndex:24734; }","duration":"135.876388ms","start":"2026-08-13T19:17:04.580655Z","end":"2026-08-13T19:17:04.716531Z","steps":["trace[1803121731] 'read index received' (duration: 122.083411ms)","trace[1803121731] 'applied index is now lower than readState.Index' (duration: 13.791457ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T19:17:04.716707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:04.006776Z","time spent":"709.836044ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T19:17:04.716726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.941537Z","time spent":"775.073226ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:04.716814Z","caller":"traceutil/trace.go:171","msg":"trace[1366261997] transaction","detail":"{read_only:false; response_revision:23937; number_of_response:1; }","duration":"140.405428ms","start":"2026-08-13T19:17:04.57639Z","end":"2026-08-13T19:17:04.716795Z","steps":["trace[1366261997] 'process raft request' (duration: 140.076609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.716713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416584754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T19:17:04.71684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.579737ms","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-08-13T19:17:04.71689Z","caller":"traceutil/trace.go:171","msg":"trace[647436366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23937; }","duration":"1.416841352s","start":"2026-08-13T19:17:03.300038Z","end":"2026-08-13T19:17:04.716879Z","steps":["trace[647436366] 'agreement among raft nodes before linearized reading' (duration: 1.416601015s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.71694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:03.300004Z","time spent":"1.416926454s","remote":"127.0.0.1:42732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T19:17:04.716942Z","caller":"traceutil/trace.go:171","msg":"trace[905271054] transaction","detail":"{read_only:false; response_revision:23936; number_of_response:1; }","duration":"537.438597ms","start":"2026-08-13T19:17:04.179493Z","end":"2026-08-13T19:17:04.716932Z","steps":["trace[905271054] 'process raft request' (duration: 536.935194ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T19:17:04.716952Z","caller":"traceutil/trace.go:171","msg":"trace[369834890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23937; }","duration":"408.757241ms","start":"2026-08-13T19:17:04.308168Z","end":"2026-08-13T19:17:04.716925Z","steps":["trace[369834890] 'agreement among raft nodes before linearized reading' (duration: 408.372411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.71699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.207933ms","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":"warn","ts":"2026-08-13T19:17:04.717006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:04.30815Z","time spent":"408.845784ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T19:17:04.717014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:04.179466Z","time spent":"537.505079ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T19:17:04.717045Z","caller":"traceutil/trace.go:171","msg":"trace[1945083122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23937; }","duration":"438.301236ms","start":"2026-08-13T19:17:04.278728Z","end":"2026-08-13T19:17:04.717029Z","steps":["trace[1945083122] 'agreement among raft nodes before linearized reading' (duration: 438.196173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.716901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.089482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-13T19:17:04.71713Z","caller":"traceutil/trace.go:171","msg":"trace[225871856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23937; }","duration":"423.380169ms","start":"2026-08-13T19:17:04.293713Z","end":"2026-08-13T19:17:04.717093Z","steps":["trace[225871856] 'agreement among raft nodes before linearized reading' (duration: 423.060501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.717207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.257718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T19:17:04.717094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:04.278699Z","time spent":"438.384988ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T19:17:04.717255Z","caller":"traceutil/trace.go:171","msg":"trace[473831485] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:23937; }","duration":"298.36411ms","start":"2026-08-13T19:17:04.418879Z","end":"2026-08-13T19:17:04.717243Z","steps":["trace[473831485] 'agreement among raft nodes before linearized reading' (duration: 298.270438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.717246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:04.293683Z","time spent":"423.524223ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T19:17:04.71767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.340843ms","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-08-13T19:17:04.717788Z","caller":"traceutil/trace.go:171","msg":"trace[1295576635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23937; }","duration":"468.486937ms","start":"2026-08-13T19:17:04.249274Z","end":"2026-08-13T19:17:04.717761Z","steps":["trace[1295576635] 'agreement among raft nodes before linearized reading' (duration: 467.756008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T19:17:04.71798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T19:17:04.249204Z","time spent":"468.718042ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T19:18:48.886449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21950} {"level":"info","ts":"2026-08-13T19:18:49.004965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21950,"took":"112.868407ms","hash":4025798015,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":43823104,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-13T19:18:49.005409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4025798015,"revision":21950,"compact-revision":19771} {"level":"info","ts":"2026-08-13T19:23:48.893908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24993} {"level":"info","ts":"2026-08-13T19:23:49.057309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24993,"took":"158.896341ms","hash":985905509,"current-db-size-bytes":58761216,"current-db-size":"59 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T19:23:49.059537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":985905509,"revision":24993,"compact-revision":21950} {"level":"info","ts":"2026-08-13T19:25:04.309019Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000007027.wal"}