{"level":"warn","ts":"2026-09-19T06:06:53.030218Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:06:53.030296Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.128: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://162.253.55.128:2380","--initial-cluster=instance=https://162.253.55.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.128: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-09-19T06:06:53.030363Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T06:06:53.030376Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.128:2380"]} {"level":"info","ts":"2026-09-19T06:06:53.030397Z","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-09-19T06:06:53.030969Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"]} {"level":"info","ts":"2026-09-19T06:06:53.031037Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.128:2380"],"listen-peer-urls":["https://162.253.55.128:2380"],"advertise-client-urls":["https://162.253.55.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.128: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-09-19T06:06:53.035708Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.470089ms"} {"level":"info","ts":"2026-09-19T06:06:53.045476Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3e8b7bea2e3a32b","cluster-id":"ca619600f96ab468"} {"level":"info","ts":"2026-09-19T06:06:53.04553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T06:06:53.045555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became follower at term 0"} {"level":"info","ts":"2026-09-19T06:06:53.045562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3e8b7bea2e3a32b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T06:06:53.045571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became follower at term 1"} {"level":"info","ts":"2026-09-19T06:06:53.045597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=(16422578070611010347)"} {"level":"warn","ts":"2026-09-19T06:06:53.050617Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T06:06:53.051834Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T06:06:53.053129Z","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-09-19T06:06:53.054983Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3e8b7bea2e3a32b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T06:06:53.057923Z","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-09-19T06:06:53.058423Z","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-09-19T06:06:53.05844Z","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-09-19T06:06:53.058031Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3e8b7bea2e3a32b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T06:06:53.060105Z","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-09-19T06:06:53.060215Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.128:2380"} {"level":"info","ts":"2026-09-19T06:06:53.060231Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.128:2380"} {"level":"info","ts":"2026-09-19T06:06:53.06075Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3e8b7bea2e3a32b","initial-advertise-peer-urls":["https://162.253.55.128:2380"],"listen-peer-urls":["https://162.253.55.128:2380"],"advertise-client-urls":["https://162.253.55.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T06:06:53.060878Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T06:06:53.062614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b switched to configuration voters=(16422578070611010347)"} {"level":"info","ts":"2026-09-19T06:06:53.062736Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca619600f96ab468","local-member-id":"e3e8b7bea2e3a32b","added-peer-id":"e3e8b7bea2e3a32b","added-peer-peer-urls":["https://162.253.55.128:2380"]} {"level":"info","ts":"2026-09-19T06:06:53.545756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T06:06:53.545802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T06:06:53.545827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b received MsgPreVoteResp from e3e8b7bea2e3a32b at term 1"} {"level":"info","ts":"2026-09-19T06:06:53.545839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became candidate at term 2"} {"level":"info","ts":"2026-09-19T06:06:53.545845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b received MsgVoteResp from e3e8b7bea2e3a32b at term 2"} {"level":"info","ts":"2026-09-19T06:06:53.545855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3e8b7bea2e3a32b became leader at term 2"} {"level":"info","ts":"2026-09-19T06:06:53.545864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3e8b7bea2e3a32b elected leader e3e8b7bea2e3a32b at term 2"} {"level":"info","ts":"2026-09-19T06:06:53.668116Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3e8b7bea2e3a32b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.128:2379]}","request-path":"/0/members/e3e8b7bea2e3a32b/attributes","cluster-id":"ca619600f96ab468","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T06:06:53.668127Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:06:53.668162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:06:53.668169Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T06:06:53.668489Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T06:06:53.668513Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T06:06:53.670362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T06:06:53.671055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.128:2379"} {"level":"info","ts":"2026-09-19T06:06:53.93616Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca619600f96ab468","local-member-id":"e3e8b7bea2e3a32b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:06:53.93638Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T06:06:53.936518Z","caller":"traceutil/trace.go:171","msg":"trace[506059320] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"142.665512ms","start":"2026-09-19T06:06:53.79383Z","end":"2026-09-19T06:06:53.936495Z","steps":["trace[506059320] 'read index received' (duration: 142.266528ms)","trace[506059320] 'applied index is now lower than readState.Index' (duration: 396.794µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:06:53.93659Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-19T06:06:53.936954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.050466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-19T06:06:53.93699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.893995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T06:06:53.937104Z","caller":"traceutil/trace.go:171","msg":"trace[10489745] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"143.256728ms","start":"2026-09-19T06:06:53.793824Z","end":"2026-09-19T06:06:53.937081Z","steps":["trace[10489745] 'agreement among raft nodes before linearized reading' (duration: 142.848644ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:06:53.937134Z","caller":"traceutil/trace.go:171","msg":"trace[588182942] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"143.049046ms","start":"2026-09-19T06:06:53.794029Z","end":"2026-09-19T06:06:53.937078Z","steps":["trace[588182942] 'agreement among raft nodes before linearized reading' (duration: 142.833804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:06:53.937883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.051038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T06:06:53.937939Z","caller":"traceutil/trace.go:171","msg":"trace[974455949] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"108.129579ms","start":"2026-09-19T06:06:53.829792Z","end":"2026-09-19T06:06:53.937922Z","steps":["trace[974455949] 'agreement among raft nodes before linearized reading' (duration: 108.061478ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:19.790678Z","caller":"traceutil/trace.go:171","msg":"trace[1484211291] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"201.497996ms","start":"2026-09-19T06:07:19.589149Z","end":"2026-09-19T06:07:19.790647Z","steps":["trace[1484211291] 'process raft request' (duration: 201.342394ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:34.105103Z","caller":"traceutil/trace.go:171","msg":"trace[1861674123] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"113.296942ms","start":"2026-09-19T06:07:33.991767Z","end":"2026-09-19T06:07:34.105064Z","steps":["trace[1861674123] 'process raft request' (duration: 110.693755ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:34.10514Z","caller":"traceutil/trace.go:171","msg":"trace[375807964] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"112.140161ms","start":"2026-09-19T06:07:33.99298Z","end":"2026-09-19T06:07:34.10512Z","steps":["trace[375807964] 'process raft request' (duration: 111.903598ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:34.111169Z","caller":"traceutil/trace.go:171","msg":"trace[1329359183] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"115.158973ms","start":"2026-09-19T06:07:33.995977Z","end":"2026-09-19T06:07:34.111136Z","steps":["trace[1329359183] 'process raft request' (duration: 115.008311ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:34.111218Z","caller":"traceutil/trace.go:171","msg":"trace[574610578] transaction","detail":"{read_only:false; number_of_response:0; response_revision:504; }","duration":"108.188649ms","start":"2026-09-19T06:07:34.002988Z","end":"2026-09-19T06:07:34.111177Z","steps":["trace[574610578] 'process raft request' (duration: 108.094408ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:40.595898Z","caller":"traceutil/trace.go:171","msg":"trace[1662199547] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"101.93087ms","start":"2026-09-19T06:07:40.49394Z","end":"2026-09-19T06:07:40.595871Z","steps":["trace[1662199547] 'read index received' (duration: 101.207633ms)","trace[1662199547] 'applied index is now lower than readState.Index' (duration: 722.097µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:40.596148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.167122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gcfxn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-19T06:07:40.596003Z","caller":"traceutil/trace.go:171","msg":"trace[803890792] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"242.941887ms","start":"2026-09-19T06:07:40.352983Z","end":"2026-09-19T06:07:40.595925Z","steps":["trace[803890792] 'process raft request' (duration: 242.329971ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:40.596199Z","caller":"traceutil/trace.go:171","msg":"trace[1201214447] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gcfxn; range_end:; response_count:1; response_revision:601; }","duration":"102.303213ms","start":"2026-09-19T06:07:40.493883Z","end":"2026-09-19T06:07:40.596187Z","steps":["trace[1201214447] 'agreement among raft nodes before linearized reading' (duration: 102.129282ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:41.09247Z","caller":"traceutil/trace.go:171","msg":"trace[457698128] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"460.993756ms","start":"2026-09-19T06:07:40.631436Z","end":"2026-09-19T06:07:41.09243Z","steps":["trace[457698128] 'read index received' (duration: 460.709472ms)","trace[457698128] 'applied index is now lower than readState.Index' (duration: 282.984µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:41.092502Z","caller":"traceutil/trace.go:171","msg":"trace[766652051] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"470.712477ms","start":"2026-09-19T06:07:40.621744Z","end":"2026-09-19T06:07:41.092456Z","steps":["trace[766652051] 'process raft request' (duration: 470.440883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:41.092838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.3785ms","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-09-19T06:07:41.092923Z","caller":"traceutil/trace.go:171","msg":"trace[427257695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"461.525811ms","start":"2026-09-19T06:07:40.631373Z","end":"2026-09-19T06:07:41.092899Z","steps":["trace[427257695] 'agreement among raft nodes before linearized reading' (duration: 461.181218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:41.092958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:40.631344Z","time spent":"461.606802ms","remote":"127.0.0.1:49028","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":"warn","ts":"2026-09-19T06:07:41.093199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:40.62171Z","time spent":"470.878839ms","remote":"127.0.0.1:48932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:42.289498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.618628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:42.289658Z","caller":"traceutil/trace.go:171","msg":"trace[2057358007] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"1.088608478s","start":"2026-09-19T06:07:41.201034Z","end":"2026-09-19T06:07:42.289642Z","steps":["trace[2057358007] 'read index received' (duration: 300.621756ms)","trace[2057358007] 'applied index is now lower than readState.Index' (duration: 787.985692ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:42.289914Z","caller":"traceutil/trace.go:171","msg":"trace[1803223380] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.618354718s","start":"2026-09-19T06:07:40.671315Z","end":"2026-09-19T06:07:42.289669Z","steps":["trace[1803223380] 'process raft request' (duration: 830.320066ms)","trace[1803223380] 'compare' (duration: 787.403116ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:42.289987Z","caller":"traceutil/trace.go:171","msg":"trace[415526255] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.190970552s","start":"2026-09-19T06:07:41.098974Z","end":"2026-09-19T06:07:42.289945Z","steps":["trace[415526255] 'process raft request' (duration: 1.190612468s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:42.290079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:40.671268Z","time spent":"1.618729182s","remote":"127.0.0.1:48996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T06:07:42.290152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089115903s","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-09-19T06:07:42.290187Z","caller":"traceutil/trace.go:171","msg":"trace[1395483580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"1.089216554s","start":"2026-09-19T06:07:41.20096Z","end":"2026-09-19T06:07:42.290176Z","steps":["trace[1395483580] 'agreement among raft nodes before linearized reading' (duration: 1.089052523s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:42.290217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:41.200929Z","time spent":"1.089279915s","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T06:07:42.290251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:41.098949Z","time spent":"1.191122853s","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:07:42.413316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.65822ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-19T06:07:42.413802Z","caller":"traceutil/trace.go:171","msg":"trace[1591270373] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"115.504991ms","start":"2026-09-19T06:07:42.29826Z","end":"2026-09-19T06:07:42.413765Z","steps":["trace[1591270373] 'process raft request' (duration: 115.225898ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:42.413784Z","caller":"traceutil/trace.go:171","msg":"trace[1545704977] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"124.06776ms","start":"2026-09-19T06:07:42.289682Z","end":"2026-09-19T06:07:42.41375Z","steps":["trace[1545704977] 'read index received' (duration: 14.998926ms)","trace[1545704977] 'applied index is now lower than readState.Index' (duration: 109.065454ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:42.414156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.240591ms","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-09-19T06:07:42.415127Z","caller":"traceutil/trace.go:171","msg":"trace[2063384902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"433.462085ms","start":"2026-09-19T06:07:41.981618Z","end":"2026-09-19T06:07:42.41508Z","steps":["trace[2063384902] 'agreement among raft nodes before linearized reading' (duration: 432.205491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:42.414156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.337131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-19T06:07:42.415385Z","caller":"traceutil/trace.go:171","msg":"trace[782562721] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:605; }","duration":"121.596315ms","start":"2026-09-19T06:07:42.293763Z","end":"2026-09-19T06:07:42.41536Z","steps":["trace[782562721] 'agreement among raft nodes before linearized reading' (duration: 120.292801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:42.41463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.664686ms","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-09-19T06:07:42.415762Z","caller":"traceutil/trace.go:171","msg":"trace[369793663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"782.889048ms","start":"2026-09-19T06:07:41.63285Z","end":"2026-09-19T06:07:42.415739Z","steps":["trace[369793663] 'agreement among raft nodes before linearized reading' (duration: 781.005979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:42.415827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:41.632813Z","time spent":"782.994399ms","remote":"127.0.0.1:49028","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-09-19T06:07:42.4153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:41.981583Z","time spent":"433.647846ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-19T06:07:43.788796Z","caller":"traceutil/trace.go:171","msg":"trace[139313924] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"138.63646ms","start":"2026-09-19T06:07:43.650055Z","end":"2026-09-19T06:07:43.788691Z","steps":["trace[139313924] 'process raft request' (duration: 138.387097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:58.845181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.622616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:07:58.845356Z","caller":"traceutil/trace.go:171","msg":"trace[1599164568] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"319.167782ms","start":"2026-09-19T06:07:58.526164Z","end":"2026-09-19T06:07:58.845332Z","steps":["trace[1599164568] 'read index received' (duration: 194.173212ms)","trace[1599164568] 'applied index is now lower than readState.Index' (duration: 124.9925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:07:58.845532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.344493ms","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-09-19T06:07:58.845509Z","caller":"traceutil/trace.go:171","msg":"trace[1225493369] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"321.535766ms","start":"2026-09-19T06:07:58.523893Z","end":"2026-09-19T06:07:58.845429Z","steps":["trace[1225493369] 'process raft request' (duration: 196.496056ms)","trace[1225493369] 'compare' (duration: 124.369283ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:07:58.845614Z","caller":"traceutil/trace.go:171","msg":"trace[139397527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"319.474424ms","start":"2026-09-19T06:07:58.526121Z","end":"2026-09-19T06:07:58.845595Z","steps":["trace[139397527] 'agreement among raft nodes before linearized reading' (duration: 319.269902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:07:58.845657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:58.52609Z","time spent":"319.559245ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T06:07:58.84555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.873412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T06:07:58.845731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.388468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-trsv5\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-09-19T06:07:58.84579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:07:58.523877Z","time spent":"321.774918ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:07:58.845883Z","caller":"traceutil/trace.go:171","msg":"trace[551397948] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-trsv5; range_end:; response_count:1; response_revision:769; }","duration":"238.578711ms","start":"2026-09-19T06:07:58.607234Z","end":"2026-09-19T06:07:58.845812Z","steps":["trace[551397948] 'agreement among raft nodes before linearized reading' (duration: 238.296728ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:07:58.846597Z","caller":"traceutil/trace.go:171","msg":"trace[1694177110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"298.172104ms","start":"2026-09-19T06:07:58.547622Z","end":"2026-09-19T06:07:58.845795Z","steps":["trace[1694177110] 'agreement among raft nodes before linearized reading' (duration: 297.868011ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:20.886301Z","caller":"traceutil/trace.go:171","msg":"trace[1859880001] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"366.505086ms","start":"2026-09-19T06:09:20.519772Z","end":"2026-09-19T06:09:20.886277Z","steps":["trace[1859880001] 'read index received' (duration: 366.261753ms)","trace[1859880001] 'applied index is now lower than readState.Index' (duration: 242.413µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:20.886531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.747298ms","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-09-19T06:09:20.886569Z","caller":"traceutil/trace.go:171","msg":"trace[1286370173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1844; }","duration":"366.854129ms","start":"2026-09-19T06:09:20.519705Z","end":"2026-09-19T06:09:20.886559Z","steps":["trace[1286370173] 'agreement among raft nodes before linearized reading' (duration: 366.696268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:20.886605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:20.519675Z","time spent":"366.92192ms","remote":"127.0.0.1:49028","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":"info","ts":"2026-09-19T06:09:20.886902Z","caller":"traceutil/trace.go:171","msg":"trace[888937319] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"371.449375ms","start":"2026-09-19T06:09:20.515338Z","end":"2026-09-19T06:09:20.886788Z","steps":["trace[888937319] 'process raft request' (duration: 370.769138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:20.887325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:09:20.515315Z","time spent":"371.772778ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:09:21.23053Z","caller":"traceutil/trace.go:171","msg":"trace[1960554063] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"190.076672ms","start":"2026-09-19T06:09:21.040421Z","end":"2026-09-19T06:09:21.230498Z","steps":["trace[1960554063] 'process raft request' (duration: 159.694964ms)","trace[1960554063] 'compare' (duration: 30.209946ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:21.23052Z","caller":"traceutil/trace.go:171","msg":"trace[1852714652] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"100.843699ms","start":"2026-09-19T06:09:21.129631Z","end":"2026-09-19T06:09:21.230474Z","steps":["trace[1852714652] 'read index received' (duration: 70.435522ms)","trace[1852714652] 'applied index is now lower than readState.Index' (duration: 30.406637ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:21.23137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.730138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-09-19T06:09:21.231513Z","caller":"traceutil/trace.go:171","msg":"trace[603484692] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1848; }","duration":"101.87325ms","start":"2026-09-19T06:09:21.129547Z","end":"2026-09-19T06:09:21.23142Z","steps":["trace[603484692] 'agreement among raft nodes before linearized reading' (duration: 101.034432ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:24.240877Z","caller":"traceutil/trace.go:171","msg":"trace[544764323] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1879; }","duration":"162.680744ms","start":"2026-09-19T06:09:24.078167Z","end":"2026-09-19T06:09:24.240847Z","steps":["trace[544764323] 'process raft request' (duration: 64.274929ms)","trace[544764323] 'compare' (duration: 98.323884ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:49.225227Z","caller":"traceutil/trace.go:171","msg":"trace[1070943277] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"108.753125ms","start":"2026-09-19T06:09:49.116435Z","end":"2026-09-19T06:09:49.225188Z","steps":["trace[1070943277] 'process raft request' (duration: 106.898497ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.094651Z","caller":"traceutil/trace.go:171","msg":"trace[1345891574] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"252.417205ms","start":"2026-09-19T06:09:54.842212Z","end":"2026-09-19T06:09:55.094629Z","steps":["trace[1345891574] 'process raft request' (duration: 252.276683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.252321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.920839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T06:09:55.252414Z","caller":"traceutil/trace.go:171","msg":"trace[753159241] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2196; }","duration":"183.57595ms","start":"2026-09-19T06:09:55.068825Z","end":"2026-09-19T06:09:55.252401Z","steps":["trace[753159241] 'read index received' (duration: 25.665059ms)","trace[753159241] 'applied index is now lower than readState.Index' (duration: 157.909421ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:55.252493Z","caller":"traceutil/trace.go:171","msg":"trace[1249381891] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"254.399914ms","start":"2026-09-19T06:09:54.998079Z","end":"2026-09-19T06:09:55.252479Z","steps":["trace[1249381891] 'process raft request' (duration: 138.248593ms)","trace[1249381891] 'compare' (duration: 115.785178ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:09:55.252839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.031695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-19T06:09:55.252881Z","caller":"traceutil/trace.go:171","msg":"trace[1039717265] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2128; }","duration":"184.108566ms","start":"2026-09-19T06:09:55.068761Z","end":"2026-09-19T06:09:55.25287Z","steps":["trace[1039717265] 'agreement among raft nodes before linearized reading' (duration: 183.905474ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.260143Z","caller":"traceutil/trace.go:171","msg":"trace[398443023] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2128; }","duration":"157.797061ms","start":"2026-09-19T06:09:55.102313Z","end":"2026-09-19T06:09:55.26011Z","steps":["trace[398443023] 'process raft request' (duration: 157.617039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.260215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.459808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118420"} {"level":"info","ts":"2026-09-19T06:09:55.260555Z","caller":"traceutil/trace.go:171","msg":"trace[34015098] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2128; }","duration":"175.738561ms","start":"2026-09-19T06:09:55.084689Z","end":"2026-09-19T06:09:55.260427Z","steps":["trace[34015098] 'agreement among raft nodes before linearized reading' (duration: 175.355718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.2602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.140536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-19T06:09:55.260551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.585265ms","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-09-19T06:09:55.260615Z","caller":"traceutil/trace.go:171","msg":"trace[680156307] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2128; }","duration":"177.58924ms","start":"2026-09-19T06:09:55.083007Z","end":"2026-09-19T06:09:55.260596Z","steps":["trace[680156307] 'agreement among raft nodes before linearized reading' (duration: 177.125775ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.26065Z","caller":"traceutil/trace.go:171","msg":"trace[646817704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2128; }","duration":"124.007829ms","start":"2026-09-19T06:09:55.136618Z","end":"2026-09-19T06:09:55.260625Z","steps":["trace[646817704] 'agreement among raft nodes before linearized reading' (duration: 123.431684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.261596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.209077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-09-19T06:09:55.261646Z","caller":"traceutil/trace.go:171","msg":"trace[936025044] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2128; }","duration":"179.289507ms","start":"2026-09-19T06:09:55.082342Z","end":"2026-09-19T06:09:55.261631Z","steps":["trace[936025044] 'agreement among raft nodes before linearized reading' (duration: 177.900374ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.431115Z","caller":"traceutil/trace.go:171","msg":"trace[768135138] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2200; }","duration":"164.043523ms","start":"2026-09-19T06:09:55.267052Z","end":"2026-09-19T06:09:55.431096Z","steps":["trace[768135138] 'read index received' (duration: 163.458018ms)","trace[768135138] 'applied index is now lower than readState.Index' (duration: 584.815µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:09:55.431165Z","caller":"traceutil/trace.go:171","msg":"trace[204606154] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"164.897592ms","start":"2026-09-19T06:09:55.266228Z","end":"2026-09-19T06:09:55.431125Z","steps":["trace[204606154] 'process raft request' (duration: 164.76711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.431276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.329347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13439"} {"level":"info","ts":"2026-09-19T06:09:55.431302Z","caller":"traceutil/trace.go:171","msg":"trace[180429695] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:2131; }","duration":"168.372977ms","start":"2026-09-19T06:09:55.262922Z","end":"2026-09-19T06:09:55.431295Z","steps":["trace[180429695] 'agreement among raft nodes before linearized reading' (duration: 168.241616ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.431365Z","caller":"traceutil/trace.go:171","msg":"trace[1341751497] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"165.624989ms","start":"2026-09-19T06:09:55.26573Z","end":"2026-09-19T06:09:55.431355Z","steps":["trace[1341751497] 'process raft request' (duration: 164.831511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.431793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.820032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:24309"} {"level":"info","ts":"2026-09-19T06:09:55.431818Z","caller":"traceutil/trace.go:171","msg":"trace[1724072873] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2131; }","duration":"166.848002ms","start":"2026-09-19T06:09:55.264964Z","end":"2026-09-19T06:09:55.431812Z","steps":["trace[1724072873] 'agreement among raft nodes before linearized reading' (duration: 166.299357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:09:55.431775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.817232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:24309"} {"level":"info","ts":"2026-09-19T06:09:55.431887Z","caller":"traceutil/trace.go:171","msg":"trace[559318519] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2131; }","duration":"167.000244ms","start":"2026-09-19T06:09:55.264857Z","end":"2026-09-19T06:09:55.431857Z","steps":["trace[559318519] 'agreement among raft nodes before linearized reading' (duration: 166.57227ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:09:55.433978Z","caller":"traceutil/trace.go:171","msg":"trace[1740801273] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"162.140794ms","start":"2026-09-19T06:09:55.271793Z","end":"2026-09-19T06:09:55.433933Z","steps":["trace[1740801273] 'process raft request' (duration: 161.941702ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:10:51.934366Z","caller":"traceutil/trace.go:171","msg":"trace[1458043942] linearizableReadLoop","detail":"{readStateIndex:2715; appliedIndex:2714; }","duration":"174.253051ms","start":"2026-09-19T06:10:51.760086Z","end":"2026-09-19T06:10:51.934339Z","steps":["trace[1458043942] 'read index received' (duration: 174.15374ms)","trace[1458043942] 'applied index is now lower than readState.Index' (duration: 97.701µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:10:51.934648Z","caller":"traceutil/trace.go:171","msg":"trace[820766183] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"219.613188ms","start":"2026-09-19T06:10:51.714971Z","end":"2026-09-19T06:10:51.934584Z","steps":["trace[820766183] 'process raft request' (duration: 219.186364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:10:51.934752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.605095ms","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-09-19T06:10:51.934843Z","caller":"traceutil/trace.go:171","msg":"trace[841348988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2618; }","duration":"174.740937ms","start":"2026-09-19T06:10:51.76008Z","end":"2026-09-19T06:10:51.934821Z","steps":["trace[841348988] 'agreement among raft nodes before linearized reading' (duration: 174.483594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:10:51.939933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.128731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T06:10:51.940075Z","caller":"traceutil/trace.go:171","msg":"trace[2100476021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2618; }","duration":"179.279092ms","start":"2026-09-19T06:10:51.760778Z","end":"2026-09-19T06:10:51.940057Z","steps":["trace[2100476021] 'agreement among raft nodes before linearized reading' (duration: 179.03056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:10:51.940351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.219627ms","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-09-19T06:10:51.940379Z","caller":"traceutil/trace.go:171","msg":"trace[1096094793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2618; }","duration":"110.283298ms","start":"2026-09-19T06:10:51.830087Z","end":"2026-09-19T06:10:51.94037Z","steps":["trace[1096094793] 'agreement among raft nodes before linearized reading' (duration: 110.213958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:10:51.940475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.439753ms","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-09-19T06:10:51.940543Z","caller":"traceutil/trace.go:171","msg":"trace[633810448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2618; }","duration":"165.520344ms","start":"2026-09-19T06:10:51.775008Z","end":"2026-09-19T06:10:51.940529Z","steps":["trace[633810448] 'agreement among raft nodes before linearized reading' (duration: 165.320732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.318871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11757667965495446873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T06:11:23.36713Z","caller":"traceutil/trace.go:171","msg":"trace[1823078063] linearizableReadLoop","detail":"{readStateIndex:2944; appliedIndex:2943; }","duration":"548.280796ms","start":"2026-09-19T06:11:22.818813Z","end":"2026-09-19T06:11:23.367094Z","steps":["trace[1823078063] 'read index received' (duration: 547.967633ms)","trace[1823078063] 'applied index is now lower than readState.Index' (duration: 311.903µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:11:23.367386Z","caller":"traceutil/trace.go:171","msg":"trace[1796538108] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"555.716121ms","start":"2026-09-19T06:11:22.811543Z","end":"2026-09-19T06:11:23.367259Z","steps":["trace[1796538108] 'process raft request' (duration: 555.339757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.367577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.733912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-09-19T06:11:23.36765Z","caller":"traceutil/trace.go:171","msg":"trace[1008014418] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2833; }","duration":"548.865404ms","start":"2026-09-19T06:11:22.818758Z","end":"2026-09-19T06:11:23.367623Z","steps":["trace[1008014418] 'agreement among raft nodes before linearized reading' (duration: 548.510979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.367708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:11:22.818742Z","time spent":"548.954244ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":591,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-19T06:11:23.367742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:11:22.811498Z","time spent":"556.049666ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T06:11:23.368103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.701368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-09-19T06:11:23.368141Z","caller":"traceutil/trace.go:171","msg":"trace[796980938] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2833; }","duration":"457.777558ms","start":"2026-09-19T06:11:22.910353Z","end":"2026-09-19T06:11:23.368131Z","steps":["trace[796980938] 'agreement among raft nodes before linearized reading' (duration: 457.629087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.368166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:11:22.91031Z","time spent":"457.84972ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-09-19T06:11:23.368305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.270094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-09-19T06:11:23.368355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.518528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T06:11:23.368462Z","caller":"traceutil/trace.go:171","msg":"trace[1135120673] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2833; }","duration":"267.703249ms","start":"2026-09-19T06:11:23.100731Z","end":"2026-09-19T06:11:23.368434Z","steps":["trace[1135120673] 'agreement among raft nodes before linearized reading' (duration: 267.509768ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:23.368395Z","caller":"traceutil/trace.go:171","msg":"trace[1703992950] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2833; }","duration":"240.414435ms","start":"2026-09-19T06:11:23.127963Z","end":"2026-09-19T06:11:23.368377Z","steps":["trace[1703992950] 'agreement among raft nodes before linearized reading' (duration: 240.237053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.368678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.901434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-09-19T06:11:23.369645Z","caller":"traceutil/trace.go:171","msg":"trace[629510460] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2833; }","duration":"124.958515ms","start":"2026-09-19T06:11:23.244659Z","end":"2026-09-19T06:11:23.369618Z","steps":["trace[629510460] 'agreement among raft nodes before linearized reading' (duration: 123.724883ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:23.665208Z","caller":"traceutil/trace.go:171","msg":"trace[2076320715] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"288.110244ms","start":"2026-09-19T06:11:23.377068Z","end":"2026-09-19T06:11:23.665178Z","steps":["trace[2076320715] 'process raft request' (duration: 287.74573ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:23.665512Z","caller":"traceutil/trace.go:171","msg":"trace[937964036] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2945; }","duration":"231.200833ms","start":"2026-09-19T06:11:23.434289Z","end":"2026-09-19T06:11:23.66549Z","steps":["trace[937964036] 'read index received' (duration: 230.667728ms)","trace[937964036] 'applied index is now lower than readState.Index' (duration: 531.225µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:11:23.665538Z","caller":"traceutil/trace.go:171","msg":"trace[1549309321] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2835; }","duration":"283.834571ms","start":"2026-09-19T06:11:23.381674Z","end":"2026-09-19T06:11:23.665509Z","steps":["trace[1549309321] 'process raft request' (duration: 283.415447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.665933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.057723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-09-19T06:11:23.665999Z","caller":"traceutil/trace.go:171","msg":"trace[1318194530] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2835; }","duration":"287.156294ms","start":"2026-09-19T06:11:23.378826Z","end":"2026-09-19T06:11:23.665982Z","steps":["trace[1318194530] 'agreement among raft nodes before linearized reading' (duration: 286.80702ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:23.740492Z","caller":"traceutil/trace.go:171","msg":"trace[1376719450] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"147.805375ms","start":"2026-09-19T06:11:23.592662Z","end":"2026-09-19T06:11:23.740468Z","steps":["trace[1376719450] 'process raft request' (duration: 147.544243ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:23.740576Z","caller":"traceutil/trace.go:171","msg":"trace[1603848923] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"133.160398ms","start":"2026-09-19T06:11:23.60736Z","end":"2026-09-19T06:11:23.740521Z","steps":["trace[1603848923] 'process raft request' (duration: 133.037087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.744615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.593969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1249"} {"level":"info","ts":"2026-09-19T06:11:23.744943Z","caller":"traceutil/trace.go:171","msg":"trace[23621879] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2837; }","duration":"306.949122ms","start":"2026-09-19T06:11:23.437838Z","end":"2026-09-19T06:11:23.744788Z","steps":["trace[23621879] 'agreement among raft nodes before linearized reading' (duration: 302.71313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.745199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:11:23.437814Z","time spent":"307.335917ms","remote":"127.0.0.1:48942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1273,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-09-19T06:11:23.776755Z","caller":"traceutil/trace.go:171","msg":"trace[758567984] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"104.986145ms","start":"2026-09-19T06:11:23.671681Z","end":"2026-09-19T06:11:23.776667Z","steps":["trace[758567984] 'process raft request' (duration: 104.086555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:11:23.776882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.856495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-09-19T06:11:23.776971Z","caller":"traceutil/trace.go:171","msg":"trace[1363114007] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:2838; }","duration":"109.984096ms","start":"2026-09-19T06:11:23.666963Z","end":"2026-09-19T06:11:23.776947Z","steps":["trace[1363114007] 'agreement among raft nodes before linearized reading' (duration: 109.684153ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:11:28.996311Z","caller":"traceutil/trace.go:171","msg":"trace[1877707731] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"102.344609ms","start":"2026-09-19T06:11:28.893897Z","end":"2026-09-19T06:11:28.996242Z","steps":["trace[1877707731] 'process raft request' (duration: 102.194297ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:13:56.503925Z","caller":"traceutil/trace.go:171","msg":"trace[463881845] linearizableReadLoop","detail":"{readStateIndex:4670; appliedIndex:4669; }","duration":"101.220956ms","start":"2026-09-19T06:13:56.402682Z","end":"2026-09-19T06:13:56.503903Z","steps":["trace[463881845] 'read index received' (duration: 101.065604ms)","trace[463881845] 'applied index is now lower than readState.Index' (duration: 154.502µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:13:56.50403Z","caller":"traceutil/trace.go:171","msg":"trace[2106764920] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"118.758572ms","start":"2026-09-19T06:13:56.38526Z","end":"2026-09-19T06:13:56.504019Z","steps":["trace[2106764920] 'process raft request' (duration: 118.53545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:13:56.504265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.576959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-09-19T06:13:56.504296Z","caller":"traceutil/trace.go:171","msg":"trace[57708312] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4472; }","duration":"101.64398ms","start":"2026-09-19T06:13:56.402643Z","end":"2026-09-19T06:13:56.504287Z","steps":["trace[57708312] 'agreement among raft nodes before linearized reading' (duration: 101.524848ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:06.51156Z","caller":"traceutil/trace.go:171","msg":"trace[1900885555] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"129.008094ms","start":"2026-09-19T06:14:06.382512Z","end":"2026-09-19T06:14:06.51152Z","steps":["trace[1900885555] 'process raft request' (duration: 128.786812ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:06.773843Z","caller":"traceutil/trace.go:171","msg":"trace[875106974] linearizableReadLoop","detail":"{readStateIndex:4886; appliedIndex:4885; }","duration":"234.727825ms","start":"2026-09-19T06:14:06.539053Z","end":"2026-09-19T06:14:06.773781Z","steps":["trace[875106974] 'read index received' (duration: 191.787264ms)","trace[875106974] 'applied index is now lower than readState.Index' (duration: 42.938901ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:06.773934Z","caller":"traceutil/trace.go:171","msg":"trace[1034757604] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"254.704916ms","start":"2026-09-19T06:14:06.519195Z","end":"2026-09-19T06:14:06.7739Z","steps":["trace[1034757604] 'process raft request' (duration: 211.641864ms)","trace[1034757604] 'compare' (duration: 42.784369ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:06.774069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.990818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78452fbb-ed77-4c26-b270-c5906c6a4441\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-09-19T06:14:06.774117Z","caller":"traceutil/trace.go:171","msg":"trace[1136482333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-78452fbb-ed77-4c26-b270-c5906c6a4441; range_end:; response_count:1; response_revision:4682; }","duration":"235.107749ms","start":"2026-09-19T06:14:06.538995Z","end":"2026-09-19T06:14:06.774102Z","steps":["trace[1136482333] 'agreement among raft nodes before linearized reading' (duration: 234.994478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:06.774201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.298271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:06.774304Z","caller":"traceutil/trace.go:171","msg":"trace[1322469660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4682; }","duration":"229.462813ms","start":"2026-09-19T06:14:06.544822Z","end":"2026-09-19T06:14:06.774285Z","steps":["trace[1322469660] 'agreement among raft nodes before linearized reading' (duration: 229.275221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:06.774395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.931106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-19T06:14:06.774435Z","caller":"traceutil/trace.go:171","msg":"trace[1022996108] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4682; }","duration":"201.020026ms","start":"2026-09-19T06:14:06.573406Z","end":"2026-09-19T06:14:06.774426Z","steps":["trace[1022996108] 'agreement among raft nodes before linearized reading' (duration: 200.852735ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:07.102825Z","caller":"traceutil/trace.go:171","msg":"trace[731416801] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"110.491859ms","start":"2026-09-19T06:14:06.992298Z","end":"2026-09-19T06:14:07.10279Z","steps":["trace[731416801] 'process raft request' (duration: 110.288186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.008344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.290327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-09-19T06:14:21.008446Z","caller":"traceutil/trace.go:171","msg":"trace[1261419125] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4888; }","duration":"443.433559ms","start":"2026-09-19T06:14:20.564991Z","end":"2026-09-19T06:14:21.008425Z","steps":["trace[1261419125] 'range keys from in-memory index tree' (duration: 443.127636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.008554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:20.564972Z","time spent":"443.5116ms","remote":"127.0.0.1:48932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10540,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-19T06:14:21.008344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.508199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-09-19T06:14:21.00856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.19121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-09-19T06:14:21.009352Z","caller":"traceutil/trace.go:171","msg":"trace[163637133] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4888; }","duration":"503.014008ms","start":"2026-09-19T06:14:20.506314Z","end":"2026-09-19T06:14:21.009328Z","steps":["trace[163637133] 'range keys from in-memory index tree' (duration: 502.051568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.009446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:20.506294Z","time spent":"503.132549ms","remote":"127.0.0.1:40980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3222,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-09-19T06:14:21.009264Z","caller":"traceutil/trace.go:171","msg":"trace[1057285263] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4888; }","duration":"430.479749ms","start":"2026-09-19T06:14:20.578763Z","end":"2026-09-19T06:14:21.009243Z","steps":["trace[1057285263] 'range keys from in-memory index tree' (duration: 429.341068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.009808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:20.578739Z","time spent":"431.049636ms","remote":"127.0.0.1:48902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-19T06:14:21.318968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11757667965495451982,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T06:14:21.329976Z","caller":"traceutil/trace.go:171","msg":"trace[211967508] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"717.959474ms","start":"2026-09-19T06:14:20.611983Z","end":"2026-09-19T06:14:21.329943Z","steps":["trace[211967508] 'process raft request' (duration: 717.760962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.330206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:20.611962Z","time spent":"718.076565ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:14:21.475044Z","caller":"traceutil/trace.go:171","msg":"trace[1236671069] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5102; }","duration":"656.787259ms","start":"2026-09-19T06:14:20.818221Z","end":"2026-09-19T06:14:21.475008Z","steps":["trace[1236671069] 'read index received' (duration: 511.600762ms)","trace[1236671069] 'applied index is now lower than readState.Index' (duration: 145.181657ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:21.475141Z","caller":"traceutil/trace.go:171","msg":"trace[306800075] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"450.595351ms","start":"2026-09-19T06:14:21.024506Z","end":"2026-09-19T06:14:21.475101Z","steps":["trace[306800075] 'process raft request' (duration: 439.605961ms)","trace[306800075] 'compare' (duration: 10.693347ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:14:21.47527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.035633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-19T06:14:21.475298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:21.024455Z","time spent":"450.768453ms","remote":"127.0.0.1:48932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10791,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:14:21.475319Z","caller":"traceutil/trace.go:171","msg":"trace[1442448088] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4890; }","duration":"657.096083ms","start":"2026-09-19T06:14:20.81821Z","end":"2026-09-19T06:14:21.475306Z","steps":["trace[1442448088] 'agreement among raft nodes before linearized reading' (duration: 656.961642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.475346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:20.818155Z","time spent":"657.183983ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":22,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-09-19T06:14:21.47548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.152718ms","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-09-19T06:14:21.475525Z","caller":"traceutil/trace.go:171","msg":"trace[898272646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4890; }","duration":"211.272099ms","start":"2026-09-19T06:14:21.264243Z","end":"2026-09-19T06:14:21.475516Z","steps":["trace[898272646] 'agreement among raft nodes before linearized reading' (duration: 211.162138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.475502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.322064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-19T06:14:21.475592Z","caller":"traceutil/trace.go:171","msg":"trace[1310324809] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4890; }","duration":"614.496526ms","start":"2026-09-19T06:14:20.861079Z","end":"2026-09-19T06:14:21.475575Z","steps":["trace[1310324809] 'agreement among raft nodes before linearized reading' (duration: 614.222953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.475669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:20.861065Z","time spent":"614.590336ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-09-19T06:14:21.475674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.648804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T06:14:21.475708Z","caller":"traceutil/trace.go:171","msg":"trace[1057259802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4890; }","duration":"218.708724ms","start":"2026-09-19T06:14:21.256991Z","end":"2026-09-19T06:14:21.475699Z","steps":["trace[1057259802] 'agreement among raft nodes before linearized reading' (duration: 218.615443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.475717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.792788ms","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-09-19T06:14:21.475755Z","caller":"traceutil/trace.go:171","msg":"trace[1810491932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4890; }","duration":"304.862379ms","start":"2026-09-19T06:14:21.170879Z","end":"2026-09-19T06:14:21.475741Z","steps":["trace[1810491932] 'agreement among raft nodes before linearized reading' (duration: 304.766578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.475755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.414061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-19T06:14:21.475782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:21.170859Z","time spent":"304.91514ms","remote":"127.0.0.1:49028","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-09-19T06:14:21.475783Z","caller":"traceutil/trace.go:171","msg":"trace[1172170786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4890; }","duration":"237.495242ms","start":"2026-09-19T06:14:21.23828Z","end":"2026-09-19T06:14:21.475775Z","steps":["trace[1172170786] 'agreement among raft nodes before linearized reading' (duration: 237.415151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.475826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.066215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-09-19T06:14:21.47585Z","caller":"traceutil/trace.go:171","msg":"trace[1099259321] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4890; }","duration":"453.143616ms","start":"2026-09-19T06:14:21.022699Z","end":"2026-09-19T06:14:21.475842Z","steps":["trace[1099259321] 'agreement among raft nodes before linearized reading' (duration: 453.069086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.475874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:21.022672Z","time spent":"453.195897ms","remote":"127.0.0.1:48942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1289,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"warn","ts":"2026-09-19T06:14:21.475892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.559761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-78452fbb-ed77-4c26-b270-c5906c6a4441\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-19T06:14:21.476226Z","caller":"traceutil/trace.go:171","msg":"trace[39165711] range","detail":"{range_begin:/registry/persistentvolumes/pvc-78452fbb-ed77-4c26-b270-c5906c6a4441; range_end:; response_count:1; response_revision:4890; }","duration":"461.939074ms","start":"2026-09-19T06:14:21.014263Z","end":"2026-09-19T06:14:21.476202Z","steps":["trace[39165711] 'agreement among raft nodes before linearized reading' (duration: 461.54674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.476272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:14:21.014236Z","time spent":"462.024804ms","remote":"127.0.0.1:48894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-78452fbb-ed77-4c26-b270-c5906c6a4441\" "} {"level":"warn","ts":"2026-09-19T06:14:21.47802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.947827ms","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-09-19T06:14:21.478067Z","caller":"traceutil/trace.go:171","msg":"trace[421517565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4890; }","duration":"235.997728ms","start":"2026-09-19T06:14:21.242058Z","end":"2026-09-19T06:14:21.478056Z","steps":["trace[421517565] 'agreement among raft nodes before linearized reading' (duration: 235.784546ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.697406Z","caller":"traceutil/trace.go:171","msg":"trace[151248221] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"216.765794ms","start":"2026-09-19T06:14:21.48059Z","end":"2026-09-19T06:14:21.697356Z","steps":["trace[151248221] 'process raft request' (duration: 202.41206ms)","trace[151248221] 'compare' (duration: 14.092471ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:21.771884Z","caller":"traceutil/trace.go:171","msg":"trace[183231493] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"288.05894ms","start":"2026-09-19T06:14:21.483799Z","end":"2026-09-19T06:14:21.771858Z","steps":["trace[183231493] 'process raft request' (duration: 287.832668ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.771993Z","caller":"traceutil/trace.go:171","msg":"trace[1126210191] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"288.158701ms","start":"2026-09-19T06:14:21.483812Z","end":"2026-09-19T06:14:21.77197Z","steps":["trace[1126210191] 'process raft request' (duration: 287.99434ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.772008Z","caller":"traceutil/trace.go:171","msg":"trace[591110373] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"288.092901ms","start":"2026-09-19T06:14:21.483879Z","end":"2026-09-19T06:14:21.771971Z","steps":["trace[591110373] 'process raft request' (duration: 287.99366ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.772507Z","caller":"traceutil/trace.go:171","msg":"trace[1349365195] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"288.326793ms","start":"2026-09-19T06:14:21.484163Z","end":"2026-09-19T06:14:21.77249Z","steps":["trace[1349365195] 'process raft request' (duration: 287.759998ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.772572Z","caller":"traceutil/trace.go:171","msg":"trace[1537342768] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"285.688127ms","start":"2026-09-19T06:14:21.486865Z","end":"2026-09-19T06:14:21.772554Z","steps":["trace[1537342768] 'process raft request' (duration: 285.490945ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.772654Z","caller":"traceutil/trace.go:171","msg":"trace[1325900176] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"285.663597ms","start":"2026-09-19T06:14:21.486962Z","end":"2026-09-19T06:14:21.772626Z","steps":["trace[1325900176] 'process raft request' (duration: 285.545165ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.772692Z","caller":"traceutil/trace.go:171","msg":"trace[1057457180] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5104; }","duration":"288.828548ms","start":"2026-09-19T06:14:21.483833Z","end":"2026-09-19T06:14:21.772662Z","steps":["trace[1057457180] 'read index received' (duration: 199.35481ms)","trace[1057457180] 'applied index is now lower than readState.Index' (duration: 89.472448ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:21.773Z","caller":"traceutil/trace.go:171","msg":"trace[1285431116] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"285.99614ms","start":"2026-09-19T06:14:21.486992Z","end":"2026-09-19T06:14:21.772988Z","steps":["trace[1285431116] 'process raft request' (duration: 285.554616ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.773101Z","caller":"traceutil/trace.go:171","msg":"trace[1374680660] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"286.136611ms","start":"2026-09-19T06:14:21.486887Z","end":"2026-09-19T06:14:21.773024Z","steps":["trace[1374680660] 'process raft request' (duration: 285.581506ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.773218Z","caller":"traceutil/trace.go:171","msg":"trace[501380441] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"285.854428ms","start":"2026-09-19T06:14:21.487354Z","end":"2026-09-19T06:14:21.773209Z","steps":["trace[501380441] 'process raft request' (duration: 285.233482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.773661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.220783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18d6a43414d4ccdf\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-09-19T06:14:21.773727Z","caller":"traceutil/trace.go:171","msg":"trace[1965466118] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18d6a43414d4ccdf; range_end:; response_count:1; response_revision:4901; }","duration":"289.353564ms","start":"2026-09-19T06:14:21.484354Z","end":"2026-09-19T06:14:21.773707Z","steps":["trace[1965466118] 'agreement among raft nodes before linearized reading' (duration: 289.106322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.774546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.002395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-09-19T06:14:21.774576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.25081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:14:21.774596Z","caller":"traceutil/trace.go:171","msg":"trace[1475697021] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4901; }","duration":"126.143656ms","start":"2026-09-19T06:14:21.648439Z","end":"2026-09-19T06:14:21.774583Z","steps":["trace[1475697021] 'agreement among raft nodes before linearized reading' (duration: 125.947384ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.774758Z","caller":"traceutil/trace.go:171","msg":"trace[528479075] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"287.220461ms","start":"2026-09-19T06:14:21.487524Z","end":"2026-09-19T06:14:21.774745Z","steps":["trace[528479075] 'process raft request' (duration: 285.09756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.774886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.05271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9761"} {"level":"info","ts":"2026-09-19T06:14:21.774911Z","caller":"traceutil/trace.go:171","msg":"trace[1069253131] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4901; }","duration":"291.152182ms","start":"2026-09-19T06:14:21.483753Z","end":"2026-09-19T06:14:21.774905Z","steps":["trace[1069253131] 'agreement among raft nodes before linearized reading' (duration: 291.069381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.775547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.790434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-19T06:14:21.775617Z","caller":"traceutil/trace.go:171","msg":"trace[1957494906] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4901; }","duration":"194.904395ms","start":"2026-09-19T06:14:21.580694Z","end":"2026-09-19T06:14:21.775598Z","steps":["trace[1957494906] 'agreement among raft nodes before linearized reading' (duration: 194.750264ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.77463Z","caller":"traceutil/trace.go:171","msg":"trace[672917863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4901; }","duration":"227.337591ms","start":"2026-09-19T06:14:21.547276Z","end":"2026-09-19T06:14:21.774614Z","steps":["trace[672917863] 'agreement among raft nodes before linearized reading' (duration: 227.24992ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:14:21.894748Z","caller":"traceutil/trace.go:171","msg":"trace[257833930] linearizableReadLoop","detail":"{readStateIndex:5117; appliedIndex:5115; }","duration":"113.59404ms","start":"2026-09-19T06:14:21.781131Z","end":"2026-09-19T06:14:21.894725Z","steps":["trace[257833930] 'read index received' (duration: 33.576397ms)","trace[257833930] 'applied index is now lower than readState.Index' (duration: 80.016073ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:21.894879Z","caller":"traceutil/trace.go:171","msg":"trace[346220742] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"115.561679ms","start":"2026-09-19T06:14:21.779306Z","end":"2026-09-19T06:14:21.894867Z","steps":["trace[346220742] 'process raft request' (duration: 35.359984ms)","trace[346220742] 'compare' (duration: 79.846632ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:14:21.895043Z","caller":"traceutil/trace.go:171","msg":"trace[1161584153] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"115.520769ms","start":"2026-09-19T06:14:21.779501Z","end":"2026-09-19T06:14:21.895022Z","steps":["trace[1161584153] 'process raft request' (duration: 115.165935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.895324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.017484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-09-19T06:14:21.895367Z","caller":"traceutil/trace.go:171","msg":"trace[54377817] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4903; }","duration":"113.140115ms","start":"2026-09-19T06:14:21.782216Z","end":"2026-09-19T06:14:21.895356Z","steps":["trace[54377817] 'agreement among raft nodes before linearized reading' (duration: 112.851132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:14:21.895345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.182706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-78452fbb-ed77-4c26-b270-c5906c6a4441\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-19T06:14:21.895496Z","caller":"traceutil/trace.go:171","msg":"trace[1382090846] range","detail":"{range_begin:/registry/persistentvolumes/pvc-78452fbb-ed77-4c26-b270-c5906c6a4441; range_end:; response_count:1; response_revision:4903; }","duration":"114.409078ms","start":"2026-09-19T06:14:21.78106Z","end":"2026-09-19T06:14:21.895469Z","steps":["trace[1382090846] 'agreement among raft nodes before linearized reading' (duration: 113.922133ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:15:06.465726Z","caller":"traceutil/trace.go:171","msg":"trace[477581223] linearizableReadLoop","detail":"{readStateIndex:5566; appliedIndex:5565; }","duration":"134.480849ms","start":"2026-09-19T06:15:06.331223Z","end":"2026-09-19T06:15:06.465704Z","steps":["trace[477581223] 'read index received' (duration: 134.372158ms)","trace[477581223] 'applied index is now lower than readState.Index' (duration: 107.491µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:15:06.465842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.596801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:15:06.465808Z","caller":"traceutil/trace.go:171","msg":"trace[143441369] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"207.923496ms","start":"2026-09-19T06:15:06.257859Z","end":"2026-09-19T06:15:06.465783Z","steps":["trace[143441369] 'process raft request' (duration: 207.711854ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:15:06.465874Z","caller":"traceutil/trace.go:171","msg":"trace[1741601740] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:5332; }","duration":"134.683763ms","start":"2026-09-19T06:15:06.331179Z","end":"2026-09-19T06:15:06.465863Z","steps":["trace[1741601740] 'agreement among raft nodes before linearized reading' (duration: 134.613731ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:15:06.879233Z","caller":"traceutil/trace.go:171","msg":"trace[800084037] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"107.372777ms","start":"2026-09-19T06:15:06.7718Z","end":"2026-09-19T06:15:06.879173Z","steps":["trace[800084037] 'process raft request' (duration: 22.568906ms)","trace[800084037] 'compare' (duration: 84.537059ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T06:16:53.792775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3032} {"level":"info","ts":"2026-09-19T06:16:53.931766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3032,"took":"137.704572ms","hash":326837848,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":30744576,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T06:16:53.931835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":326837848,"revision":3032,"compact-revision":-1} {"level":"warn","ts":"2026-09-19T06:18:09.66339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.177886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T06:18:09.663736Z","caller":"traceutil/trace.go:171","msg":"trace[1593543061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6850; }","duration":"116.59066ms","start":"2026-09-19T06:18:09.547118Z","end":"2026-09-19T06:18:09.663709Z","steps":["trace[1593543061] 'range keys from in-memory index tree' (duration: 116.068494ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:20.666997Z","caller":"traceutil/trace.go:171","msg":"trace[1103884312] transaction","detail":"{read_only:false; response_revision:6969; number_of_response:1; }","duration":"403.076124ms","start":"2026-09-19T06:18:20.263902Z","end":"2026-09-19T06:18:20.666978Z","steps":["trace[1103884312] 'process raft request' (duration: 402.875602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:20.667109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:20.263889Z","time spent":"403.151815ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T06:18:20.666932Z","caller":"traceutil/trace.go:171","msg":"trace[406343929] linearizableReadLoop","detail":"{readStateIndex:7290; appliedIndex:7289; }","duration":"362.794711ms","start":"2026-09-19T06:18:20.304114Z","end":"2026-09-19T06:18:20.666909Z","steps":["trace[406343929] 'read index received' (duration: 362.655069ms)","trace[406343929] 'applied index is now lower than readState.Index' (duration: 138.552µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T06:18:20.667735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.609508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:4 size:218502"} {"level":"info","ts":"2026-09-19T06:18:20.667785Z","caller":"traceutil/trace.go:171","msg":"trace[1379682325] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:4; response_revision:6969; }","duration":"363.683949ms","start":"2026-09-19T06:18:20.304086Z","end":"2026-09-19T06:18:20.66777Z","steps":["trace[1379682325] 'agreement among raft nodes before linearized reading' (duration: 362.942952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:20.66782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:20.304072Z","time spent":"363.740139ms","remote":"127.0.0.1:48840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":4,"response size":218526,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-09-19T06:18:20.738169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.212938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-09-19T06:18:20.738249Z","caller":"traceutil/trace.go:171","msg":"trace[322056831] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6970; }","duration":"187.346739ms","start":"2026-09-19T06:18:20.550885Z","end":"2026-09-19T06:18:20.738232Z","steps":["trace[322056831] 'agreement among raft nodes before linearized reading' (duration: 187.123737ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T06:18:20.738929Z","caller":"traceutil/trace.go:171","msg":"trace[879864763] transaction","detail":"{read_only:false; response_revision:6970; number_of_response:1; }","duration":"416.723711ms","start":"2026-09-19T06:18:20.322189Z","end":"2026-09-19T06:18:20.738913Z","steps":["trace[879864763] 'process raft request' (duration: 415.70315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T06:18:20.73904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T06:18:20.322172Z","time spent":"416.799142ms","remote":"127.0.0.1:49028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}