{"level":"warn","ts":"2026-08-10T22:25:28.742822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:25:28.742978Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-10T22:25:28.743103Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:25:28.743123Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-10T22:25:28.743154Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-10T22:25:28.744287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-08-10T22:25:28.744402Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-10T22:25:28.747162Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.43524ms"} {"level":"info","ts":"2026-08-10T22:25:28.75291Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-08-10T22:25:28.752981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:25:28.752999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-08-10T22:25:28.753009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:25:28.753023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-08-10T22:25:28.753057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-08-10T22:25:28.762392Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:25:28.763839Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:25:28.765037Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-10T22:25:28.768473Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:25:28.768622Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:25:28.769061Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-10T22:25:28.769183Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-10T22:25:28.769195Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-10T22:25:28.770679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-08-10T22:25:28.770882Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-10T22:25:28.774853Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-10T22:25:28.775067Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-10T22:25:28.775151Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-10T22:25:28.775264Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:25:28.775305Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:25:29.554173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:25:29.554258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:25:29.554286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-08-10T22:25:29.554307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:25:29.554319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-10T22:25:29.554337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-08-10T22:25:29.554357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-10T22:25:29.555654Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:25:29.555698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:25:29.555752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:25:29.55581Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:29.555993Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:25:29.556039Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:25:29.556927Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:29.557059Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:29.557095Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:29.55737Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-08-10T22:25:29.558905Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:25:49.147772Z","caller":"traceutil/trace.go:171","msg":"trace[495095075] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"101.01742ms","start":"2026-08-10T22:25:49.046727Z","end":"2026-08-10T22:25:49.147744Z","steps":["trace[495095075] 'process raft request' (duration: 100.860456ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:25:54.983229Z","caller":"traceutil/trace.go:171","msg":"trace[1854563325] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"283.710804ms","start":"2026-08-10T22:25:54.699491Z","end":"2026-08-10T22:25:54.983202Z","steps":["trace[1854563325] 'process raft request' (duration: 283.54697ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:05.496746Z","caller":"traceutil/trace.go:171","msg":"trace[2036307322] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"104.31906ms","start":"2026-08-10T22:26:05.39238Z","end":"2026-08-10T22:26:05.496699Z","steps":["trace[2036307322] 'process raft request' (duration: 103.987512ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:05.496871Z","caller":"traceutil/trace.go:171","msg":"trace[1782548944] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"101.673106ms","start":"2026-08-10T22:26:05.395168Z","end":"2026-08-10T22:26:05.496841Z","steps":["trace[1782548944] 'process raft request' (duration: 101.360168ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:11.523541Z","caller":"traceutil/trace.go:171","msg":"trace[1206465918] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"213.450231ms","start":"2026-08-10T22:26:11.310062Z","end":"2026-08-10T22:26:11.523512Z","steps":["trace[1206465918] 'process raft request' (duration: 213.244386ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:11.817187Z","caller":"traceutil/trace.go:171","msg":"trace[1473330053] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"280.204987ms","start":"2026-08-10T22:26:11.536895Z","end":"2026-08-10T22:26:11.8171Z","steps":["trace[1473330053] 'read index received' (duration: 257.974092ms)","trace[1473330053] 'applied index is now lower than readState.Index' (duration: 22.229625ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:11.817227Z","caller":"traceutil/trace.go:171","msg":"trace[1979013204] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"379.999318ms","start":"2026-08-10T22:26:11.437193Z","end":"2026-08-10T22:26:11.817192Z","steps":["trace[1979013204] 'process raft request' (duration: 358.221703ms)","trace[1979013204] 'compare' (duration: 21.126058ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:11.817492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.529697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-10T22:26:11.8176Z","caller":"traceutil/trace.go:171","msg":"trace[127160702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"280.707501ms","start":"2026-08-10T22:26:11.536863Z","end":"2026-08-10T22:26:11.817571Z","steps":["trace[127160702] 'agreement among raft nodes before linearized reading' (duration: 280.392023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:11.817967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:11.437163Z","time spent":"380.126931ms","remote":"127.0.0.1:46118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:26:12.305388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.31983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:26:12.305586Z","caller":"traceutil/trace.go:171","msg":"trace[441048936] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"435.695905ms","start":"2026-08-10T22:26:11.869858Z","end":"2026-08-10T22:26:12.305554Z","steps":["trace[441048936] 'process raft request' (duration: 264.603826ms)","trace[441048936] 'compare' (duration: 170.155977ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:12.305643Z","caller":"traceutil/trace.go:171","msg":"trace[1445213018] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"314.221753ms","start":"2026-08-10T22:26:11.991394Z","end":"2026-08-10T22:26:12.305616Z","steps":["trace[1445213018] 'read index received' (duration: 143.083622ms)","trace[1445213018] 'applied index is now lower than readState.Index' (duration: 171.134791ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:12.305674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:11.869843Z","time spent":"435.794577ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-10T22:26:12.306247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.845057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-10T22:26:12.306316Z","caller":"traceutil/trace.go:171","msg":"trace[712802146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"314.95141ms","start":"2026-08-10T22:26:11.991346Z","end":"2026-08-10T22:26:12.306297Z","steps":["trace[712802146] 'agreement among raft nodes before linearized reading' (duration: 314.392847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:12.30636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:11.991321Z","time spent":"315.028932ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-10T22:26:12.306916Z","caller":"traceutil/trace.go:171","msg":"trace[1718271630] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"314.311575ms","start":"2026-08-10T22:26:11.99259Z","end":"2026-08-10T22:26:12.306902Z","steps":["trace[1718271630] 'process raft request' (duration: 312.918781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:12.307003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:11.992566Z","time spent":"314.391906ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:26:30.587478Z","caller":"traceutil/trace.go:171","msg":"trace[1344672303] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"175.818806ms","start":"2026-08-10T22:26:30.411631Z","end":"2026-08-10T22:26:30.58745Z","steps":["trace[1344672303] 'process raft request' (duration: 175.604191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:49.640825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.660737ms","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-08-10T22:27:49.640924Z","caller":"traceutil/trace.go:171","msg":"trace[1289283045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1869; }","duration":"127.80598ms","start":"2026-08-10T22:27:49.513098Z","end":"2026-08-10T22:27:49.640904Z","steps":["trace[1289283045] 'range keys from in-memory index tree' (duration: 127.36115ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:27:49.751538Z","caller":"traceutil/trace.go:171","msg":"trace[1361288665] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"106.410006ms","start":"2026-08-10T22:27:49.645092Z","end":"2026-08-10T22:27:49.751502Z","steps":["trace[1361288665] 'process raft request' (duration: 106.213641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:50.141875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.068073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T22:27:50.142064Z","caller":"traceutil/trace.go:171","msg":"trace[2089342540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1873; }","duration":"138.311809ms","start":"2026-08-10T22:27:50.003722Z","end":"2026-08-10T22:27:50.142034Z","steps":["trace[2089342540] 'range keys from in-memory index tree' (duration: 137.830847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:35.235203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.080493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-10T22:28:35.235342Z","caller":"traceutil/trace.go:171","msg":"trace[748893473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2174; }","duration":"211.281528ms","start":"2026-08-10T22:28:35.024032Z","end":"2026-08-10T22:28:35.235314Z","steps":["trace[748893473] 'range keys from in-memory index tree' (duration: 210.56047ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:35.351117Z","caller":"traceutil/trace.go:171","msg":"trace[1503436688] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"111.79987ms","start":"2026-08-10T22:28:35.239297Z","end":"2026-08-10T22:28:35.351096Z","steps":["trace[1503436688] 'process raft request' (duration: 111.677557ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:29:57.929011Z","caller":"traceutil/trace.go:171","msg":"trace[1961409255] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"185.869764ms","start":"2026-08-10T22:29:57.743094Z","end":"2026-08-10T22:29:57.928964Z","steps":["trace[1961409255] 'process raft request' (duration: 140.298902ms)","trace[1961409255] 'compare' (duration: 45.410838ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:03.680002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.60346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:03.680165Z","caller":"traceutil/trace.go:171","msg":"trace[501725112] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"143.488145ms","start":"2026-08-10T22:34:03.53665Z","end":"2026-08-10T22:34:03.680138Z","steps":["trace[501725112] 'process raft request' (duration: 14.631469ms)","trace[501725112] 'compare' (duration: 128.508138ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:03.865147Z","caller":"traceutil/trace.go:171","msg":"trace[566023365] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5046; }","duration":"138.950593ms","start":"2026-08-10T22:34:03.72617Z","end":"2026-08-10T22:34:03.865121Z","steps":["trace[566023365] 'read index received' (duration: 94.716446ms)","trace[566023365] 'applied index is now lower than readState.Index' (duration: 44.233127ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:03.86517Z","caller":"traceutil/trace.go:171","msg":"trace[3498436] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"181.194172ms","start":"2026-08-10T22:34:03.683938Z","end":"2026-08-10T22:34:03.865132Z","steps":["trace[3498436] 'process raft request' (duration: 136.933725ms)","trace[3498436] 'compare' (duration: 44.058472ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:03.865287Z","caller":"traceutil/trace.go:171","msg":"trace[1703910906] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"180.73367ms","start":"2026-08-10T22:34:03.684535Z","end":"2026-08-10T22:34:03.865268Z","steps":["trace[1703910906] 'process raft request' (duration: 180.540055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:03.865551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.375734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:03.865598Z","caller":"traceutil/trace.go:171","msg":"trace[296967503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4866; }","duration":"139.496926ms","start":"2026-08-10T22:34:03.726089Z","end":"2026-08-10T22:34:03.865586Z","steps":["trace[296967503] 'agreement among raft nodes before linearized reading' (duration: 139.410944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:03.865847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.769035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:763739"} {"level":"info","ts":"2026-08-10T22:34:03.865961Z","caller":"traceutil/trace.go:171","msg":"trace[498249594] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4866; }","duration":"113.918709ms","start":"2026-08-10T22:34:03.752015Z","end":"2026-08-10T22:34:03.865933Z","steps":["trace[498249594] 'agreement among raft nodes before linearized reading' (duration: 113.330204ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:05.973624Z","caller":"traceutil/trace.go:171","msg":"trace[359652428] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"100.646743ms","start":"2026-08-10T22:34:05.872924Z","end":"2026-08-10T22:34:05.973571Z","steps":["trace[359652428] 'process raft request' (duration: 100.488369ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:05.974388Z","caller":"traceutil/trace.go:171","msg":"trace[2044602587] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"100.618962ms","start":"2026-08-10T22:34:05.873744Z","end":"2026-08-10T22:34:05.974363Z","steps":["trace[2044602587] 'process raft request' (duration: 100.226033ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:17.464997Z","caller":"traceutil/trace.go:171","msg":"trace[1183794012] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"103.543774ms","start":"2026-08-10T22:34:17.361422Z","end":"2026-08-10T22:34:17.464966Z","steps":["trace[1183794012] 'process raft request' (duration: 32.137719ms)","trace[1183794012] 'compare' (duration: 71.146369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:18.159015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.553577ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-10T22:34:18.159131Z","caller":"traceutil/trace.go:171","msg":"trace[1089217663] linearizableReadLoop","detail":"{readStateIndex:5327; appliedIndex:5326; }","duration":"129.763199ms","start":"2026-08-10T22:34:18.029349Z","end":"2026-08-10T22:34:18.159112Z","steps":["trace[1089217663] 'read index received' (duration: 58.062µs)","trace[1089217663] 'applied index is now lower than readState.Index' (duration: 129.703147ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:18.159589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.571175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-10T22:34:18.159581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.20719ms","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-08-10T22:34:18.159694Z","caller":"traceutil/trace.go:171","msg":"trace[1974194159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5136; }","duration":"130.367264ms","start":"2026-08-10T22:34:18.029304Z","end":"2026-08-10T22:34:18.159671Z","steps":["trace[1974194159] 'agreement among raft nodes before linearized reading' (duration: 130.050926ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:18.159624Z","caller":"traceutil/trace.go:171","msg":"trace[491505929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5136; }","duration":"127.634476ms","start":"2026-08-10T22:34:18.031979Z","end":"2026-08-10T22:34:18.159613Z","steps":["trace[491505929] 'agreement among raft nodes before linearized reading' (duration: 127.475723ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:24.902077Z","caller":"traceutil/trace.go:171","msg":"trace[1124644806] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"110.670367ms","start":"2026-08-10T22:34:24.791386Z","end":"2026-08-10T22:34:24.902057Z","steps":["trace[1124644806] 'process raft request' (duration: 110.514664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:25.26589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.207551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:25.266088Z","caller":"traceutil/trace.go:171","msg":"trace[1608260306] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"474.304274ms","start":"2026-08-10T22:34:24.791759Z","end":"2026-08-10T22:34:25.266063Z","steps":["trace[1608260306] 'process raft request' (duration: 366.815745ms)","trace[1608260306] 'compare' (duration: 107.013187ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:25.266176Z","caller":"traceutil/trace.go:171","msg":"trace[132860172] linearizableReadLoop","detail":"{readStateIndex:5482; appliedIndex:5479; }","duration":"394.555425ms","start":"2026-08-10T22:34:24.871598Z","end":"2026-08-10T22:34:25.266153Z","steps":["trace[132860172] 'read index received' (duration: 30.313534ms)","trace[132860172] 'applied index is now lower than readState.Index' (duration: 364.239741ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:25.266249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:24.791735Z","time spent":"474.438027ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:25.266256Z","caller":"traceutil/trace.go:171","msg":"trace[2038199734] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"471.995927ms","start":"2026-08-10T22:34:24.794246Z","end":"2026-08-10T22:34:25.266242Z","steps":["trace[2038199734] 'process raft request' (duration: 471.755742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:25.266363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:24.794224Z","time spent":"472.089099ms","remote":"127.0.0.1:46198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:25.26648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.855362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7e520bc-ab47-4568-8462-ff5571fbc04e\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-08-10T22:34:25.266544Z","caller":"traceutil/trace.go:171","msg":"trace[557524534] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7e520bc-ab47-4568-8462-ff5571fbc04e; range_end:; response_count:1; response_revision:5288; }","duration":"394.974975ms","start":"2026-08-10T22:34:24.871555Z","end":"2026-08-10T22:34:25.26653Z","steps":["trace[557524534] 'agreement among raft nodes before linearized reading' (duration: 394.788031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:25.266572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:24.871535Z","time spent":"395.031797ms","remote":"127.0.0.1:46198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7e520bc-ab47-4568-8462-ff5571fbc04e\" "} {"level":"warn","ts":"2026-08-10T22:34:25.267132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.390879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-x9v6s\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-10T22:34:25.267246Z","caller":"traceutil/trace.go:171","msg":"trace[553213282] range","detail":"{range_begin:/registry/pods/openstack/keepalived-x9v6s; range_end:; response_count:1; response_revision:5289; }","duration":"365.550284ms","start":"2026-08-10T22:34:24.901667Z","end":"2026-08-10T22:34:25.267217Z","steps":["trace[553213282] 'agreement among raft nodes before linearized reading' (duration: 365.288458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:25.267299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:24.901646Z","time spent":"365.639217ms","remote":"127.0.0.1:46198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-x9v6s\" "} {"level":"info","ts":"2026-08-10T22:35:29.584172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2841} {"level":"info","ts":"2026-08-10T22:35:29.653384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2841,"took":"67.572469ms","hash":3447316264,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-10T22:35:29.653447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3447316264,"revision":2841,"compact-revision":-1} {"level":"warn","ts":"2026-08-10T22:40:29.358626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.126346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-10T22:40:29.358723Z","caller":"traceutil/trace.go:171","msg":"trace[1910248726] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8107; }","duration":"287.2637ms","start":"2026-08-10T22:40:29.071439Z","end":"2026-08-10T22:40:29.358703Z","steps":["trace[1910248726] 'range keys from in-memory index tree' (duration: 286.912261ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:29.637716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5897} {"level":"warn","ts":"2026-08-10T22:40:29.832194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.90892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:29.832276Z","caller":"traceutil/trace.go:171","msg":"trace[801452843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8109; }","duration":"104.041103ms","start":"2026-08-10T22:40:29.728216Z","end":"2026-08-10T22:40:29.832258Z","steps":["trace[801452843] 'range keys from in-memory index tree' (duration: 103.802947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:30.119674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.275249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:40:30.119952Z","caller":"traceutil/trace.go:171","msg":"trace[154717089] transaction","detail":"{read_only:false; response_revision:8110; number_of_response:1; }","duration":"294.191579ms","start":"2026-08-10T22:40:29.825707Z","end":"2026-08-10T22:40:30.119899Z","steps":["trace[154717089] 'process raft request' (duration: 145.562822ms)","trace[154717089] 'compare' (duration: 148.097554ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:40:30.120063Z","caller":"traceutil/trace.go:171","msg":"trace[38339254] transaction","detail":"{read_only:false; response_revision:8111; number_of_response:1; }","duration":"265.458303ms","start":"2026-08-10T22:40:29.854576Z","end":"2026-08-10T22:40:30.120035Z","steps":["trace[38339254] 'process raft request' (duration: 265.248758ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:30.120238Z","caller":"traceutil/trace.go:171","msg":"trace[1289754264] linearizableReadLoop","detail":"{readStateIndex:8392; appliedIndex:8391; }","duration":"286.947852ms","start":"2026-08-10T22:40:29.833243Z","end":"2026-08-10T22:40:30.120191Z","steps":["trace[1289754264] 'read index received' (duration: 138.016767ms)","trace[1289754264] 'applied index is now lower than readState.Index' (duration: 148.924605ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:40:30.120392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.138267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:40:30.120457Z","caller":"traceutil/trace.go:171","msg":"trace[1077220036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8111; }","duration":"287.231749ms","start":"2026-08-10T22:40:29.833209Z","end":"2026-08-10T22:40:30.120441Z","steps":["trace[1077220036] 'agreement among raft nodes before linearized reading' (duration: 287.103456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:30.120509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.967895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"warn","ts":"2026-08-10T22:40:30.120579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.201395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-10T22:40:30.120603Z","caller":"traceutil/trace.go:171","msg":"trace[1516429080] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8111; }","duration":"171.065768ms","start":"2026-08-10T22:40:29.949492Z","end":"2026-08-10T22:40:30.120558Z","steps":["trace[1516429080] 'agreement among raft nodes before linearized reading' (duration: 170.900324ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:30.120638Z","caller":"traceutil/trace.go:171","msg":"trace[548423714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8111; }","duration":"132.328848ms","start":"2026-08-10T22:40:29.988296Z","end":"2026-08-10T22:40:30.120625Z","steps":["trace[548423714] 'agreement among raft nodes before linearized reading' (duration: 132.092262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:30.120796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.590844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:40:30.120889Z","caller":"traceutil/trace.go:171","msg":"trace[1317597442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8111; }","duration":"132.702827ms","start":"2026-08-10T22:40:29.988163Z","end":"2026-08-10T22:40:30.120866Z","steps":["trace[1317597442] 'agreement among raft nodes before linearized reading' (duration: 132.468872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:30.120909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.235697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-10T22:40:30.120961Z","caller":"traceutil/trace.go:171","msg":"trace[1461591462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8111; }","duration":"139.329349ms","start":"2026-08-10T22:40:29.981617Z","end":"2026-08-10T22:40:30.120946Z","steps":["trace[1461591462] 'agreement among raft nodes before linearized reading' (duration: 139.126815ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:30.229986Z","caller":"traceutil/trace.go:171","msg":"trace[1619923395] transaction","detail":"{read_only:false; response_revision:8113; number_of_response:1; }","duration":"104.471205ms","start":"2026-08-10T22:40:30.125481Z","end":"2026-08-10T22:40:30.229952Z","steps":["trace[1619923395] 'process raft request' (duration: 104.345622ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:30.230006Z","caller":"traceutil/trace.go:171","msg":"trace[675122512] transaction","detail":"{read_only:false; response_revision:8112; number_of_response:1; }","duration":"104.68577ms","start":"2026-08-10T22:40:30.125266Z","end":"2026-08-10T22:40:30.229951Z","steps":["trace[675122512] 'process raft request' (duration: 30.510969ms)","trace[675122512] 'compare' (duration: 41.535989ms)","trace[675122512] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 32.287783ms)"],"step_count":3} {"level":"info","ts":"2026-08-10T22:40:30.230145Z","caller":"traceutil/trace.go:171","msg":"trace[1798490549] transaction","detail":"{read_only:false; response_revision:8114; number_of_response:1; }","duration":"104.185507ms","start":"2026-08-10T22:40:30.125911Z","end":"2026-08-10T22:40:30.230097Z","steps":["trace[1798490549] 'process raft request' (duration: 103.972202ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:30.301861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5897,"took":"662.609379ms","hash":2964235866,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":26947584,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-10T22:40:30.301934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2964235866,"revision":5897,"compact-revision":2841} {"level":"info","ts":"2026-08-10T22:40:30.302318Z","caller":"traceutil/trace.go:171","msg":"trace[1839918564] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"174.36175ms","start":"2026-08-10T22:40:30.127896Z","end":"2026-08-10T22:40:30.302258Z","steps":["trace[1839918564] 'process raft request' (duration: 174.251166ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:30.302427Z","caller":"traceutil/trace.go:171","msg":"trace[1027064731] transaction","detail":"{read_only:false; response_revision:8115; number_of_response:1; }","duration":"176.397319ms","start":"2026-08-10T22:40:30.125928Z","end":"2026-08-10T22:40:30.302326Z","steps":["trace[1027064731] 'process raft request' (duration: 104.152296ms)","trace[1027064731] 'compare' (duration: 24.758007ms)","trace[1027064731] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 47.151236ms)"],"step_count":3} {"level":"info","ts":"2026-08-10T22:40:58.606505Z","caller":"traceutil/trace.go:171","msg":"trace[1844346148] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"237.163492ms","start":"2026-08-10T22:40:58.369316Z","end":"2026-08-10T22:40:58.606479Z","steps":["trace[1844346148] 'process raft request' (duration: 237.016908ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:40:58.67113Z","caller":"traceutil/trace.go:171","msg":"trace[1350986416] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"301.705407ms","start":"2026-08-10T22:40:58.369401Z","end":"2026-08-10T22:40:58.671107Z","steps":["trace[1350986416] 'process raft request' (duration: 301.535292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:58.671335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:58.36939Z","time spent":"301.867541ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:40:58.671176Z","caller":"traceutil/trace.go:171","msg":"trace[1100329104] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"301.514382ms","start":"2026-08-10T22:40:58.369629Z","end":"2026-08-10T22:40:58.671143Z","steps":["trace[1100329104] 'process raft request' (duration: 301.42259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:40:58.671521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:40:58.369616Z","time spent":"301.878021ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:40:58.671339Z","caller":"traceutil/trace.go:171","msg":"trace[541496354] linearizableReadLoop","detail":"{readStateIndex:8610; appliedIndex:8607; }","duration":"239.869849ms","start":"2026-08-10T22:40:58.431457Z","end":"2026-08-10T22:40:58.671327Z","steps":["trace[541496354] 'read index received' (duration: 174.871853ms)","trace[541496354] 'applied index is now lower than readState.Index' (duration: 64.995756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:40:58.671518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.037683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-10T22:40:58.672545Z","caller":"traceutil/trace.go:171","msg":"trace[335592981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8320; }","duration":"241.10857ms","start":"2026-08-10T22:40:58.431426Z","end":"2026-08-10T22:40:58.672535Z","steps":["trace[335592981] 'agreement among raft nodes before linearized reading' (duration: 239.934791ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:12.276659Z","caller":"traceutil/trace.go:171","msg":"trace[1411285509] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8727; }","duration":"182.829308ms","start":"2026-08-10T22:41:12.093809Z","end":"2026-08-10T22:41:12.276638Z","steps":["trace[1411285509] 'read index received' (duration: 182.771627ms)","trace[1411285509] 'applied index is now lower than readState.Index' (duration: 56.611µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:12.276773Z","caller":"traceutil/trace.go:171","msg":"trace[927492099] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"263.965021ms","start":"2026-08-10T22:41:12.012775Z","end":"2026-08-10T22:41:12.27674Z","steps":["trace[927492099] 'process raft request' (duration: 263.748335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:12.27715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.326611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19316"} {"level":"info","ts":"2026-08-10T22:41:12.277198Z","caller":"traceutil/trace.go:171","msg":"trace[1953790108] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8434; }","duration":"183.437404ms","start":"2026-08-10T22:41:12.093749Z","end":"2026-08-10T22:41:12.277187Z","steps":["trace[1953790108] 'agreement among raft nodes before linearized reading' (duration: 182.976052ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:12.424104Z","caller":"traceutil/trace.go:171","msg":"trace[196110647] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"164.440388ms","start":"2026-08-10T22:41:12.259637Z","end":"2026-08-10T22:41:12.424077Z","steps":["trace[196110647] 'process raft request' (duration: 153.108549ms)","trace[196110647] 'compare' (duration: 10.623881ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:12.424332Z","caller":"traceutil/trace.go:171","msg":"trace[923687769] linearizableReadLoop","detail":"{readStateIndex:8729; appliedIndex:8728; }","duration":"137.221089ms","start":"2026-08-10T22:41:12.287096Z","end":"2026-08-10T22:41:12.424317Z","steps":["trace[923687769] 'read index received' (duration: 125.643415ms)","trace[923687769] 'applied index is now lower than readState.Index' (duration: 11.575554ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:41:12.424496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.382113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19316"} {"level":"info","ts":"2026-08-10T22:41:12.424542Z","caller":"traceutil/trace.go:171","msg":"trace[1432693794] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8436; }","duration":"137.458525ms","start":"2026-08-10T22:41:12.287068Z","end":"2026-08-10T22:41:12.424527Z","steps":["trace[1432693794] 'agreement among raft nodes before linearized reading' (duration: 137.307251ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:13.238354Z","caller":"traceutil/trace.go:171","msg":"trace[6662792] linearizableReadLoop","detail":"{readStateIndex:8739; appliedIndex:8738; }","duration":"146.400515ms","start":"2026-08-10T22:41:13.091926Z","end":"2026-08-10T22:41:13.238327Z","steps":["trace[6662792] 'read index received' (duration: 146.094027ms)","trace[6662792] 'applied index is now lower than readState.Index' (duration: 305.298µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:41:13.23864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.695602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-10T22:41:13.238702Z","caller":"traceutil/trace.go:171","msg":"trace[990917424] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8444; }","duration":"146.801904ms","start":"2026-08-10T22:41:13.091881Z","end":"2026-08-10T22:41:13.238683Z","steps":["trace[990917424] 'agreement among raft nodes before linearized reading' (duration: 146.594299ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:13.238691Z","caller":"traceutil/trace.go:171","msg":"trace[1055018407] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"181.481585ms","start":"2026-08-10T22:41:13.057159Z","end":"2026-08-10T22:41:13.238641Z","steps":["trace[1055018407] 'process raft request' (duration: 180.977653ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:56.454952Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002407.wal"} {"level":"info","ts":"2026-08-10T22:43:32.593672Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T22:43:32.598635Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-10T22:43:32.598734Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-10T22:45:29.644085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8109} {"level":"info","ts":"2026-08-10T22:45:29.721791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8109,"took":"75.939273ms","hash":2572435039,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":25460736,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-10T22:45:29.721881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2572435039,"revision":8109,"compact-revision":5897} {"level":"info","ts":"2026-08-10T22:46:02.950853Z","caller":"traceutil/trace.go:171","msg":"trace[1743465029] transaction","detail":"{read_only:false; response_revision:11022; number_of_response:1; }","duration":"101.241385ms","start":"2026-08-10T22:46:02.849592Z","end":"2026-08-10T22:46:02.950833Z","steps":["trace[1743465029] 'process raft request' (duration: 88.137703ms)","trace[1743465029] 'compare' (duration: 12.974539ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:50:29.651314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10652} {"level":"info","ts":"2026-08-10T22:50:29.731419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10652,"took":"77.634777ms","hash":235623288,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":27545600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-10T22:50:29.731494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":235623288,"revision":10652,"compact-revision":8109} {"level":"info","ts":"2026-08-10T22:51:49.168338Z","caller":"traceutil/trace.go:171","msg":"trace[126560733] transaction","detail":"{read_only:false; response_revision:13399; number_of_response:1; }","duration":"126.518095ms","start":"2026-08-10T22:51:49.041783Z","end":"2026-08-10T22:51:49.168301Z","steps":["trace[126560733] 'process raft request' (duration: 126.404183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:52:22.444275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.532766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:52:22.44437Z","caller":"traceutil/trace.go:171","msg":"trace[1241272217] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13586; }","duration":"126.68106ms","start":"2026-08-10T22:52:22.31767Z","end":"2026-08-10T22:52:22.444351Z","steps":["trace[1241272217] 'range keys from in-memory index tree' (duration: 126.427294ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:52:23.440016Z","caller":"traceutil/trace.go:171","msg":"trace[1852238340] linearizableReadLoop","detail":"{readStateIndex:14052; appliedIndex:14051; }","duration":"121.117243ms","start":"2026-08-10T22:52:23.318861Z","end":"2026-08-10T22:52:23.439978Z","steps":["trace[1852238340] 'read index received' (duration: 120.053357ms)","trace[1852238340] 'applied index is now lower than readState.Index' (duration: 1.061536ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:52:23.440029Z","caller":"traceutil/trace.go:171","msg":"trace[1422501439] transaction","detail":"{read_only:false; response_revision:13591; number_of_response:1; }","duration":"139.33954ms","start":"2026-08-10T22:52:23.30064Z","end":"2026-08-10T22:52:23.43998Z","steps":["trace[1422501439] 'process raft request' (duration: 138.392007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:52:23.44069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.784169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-10T22:52:23.441219Z","caller":"traceutil/trace.go:171","msg":"trace[3648234] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13591; }","duration":"122.382645ms","start":"2026-08-10T22:52:23.318795Z","end":"2026-08-10T22:52:23.441177Z","steps":["trace[3648234] 'agreement among raft nodes before linearized reading' (duration: 121.294318ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:52:44.558452Z","caller":"traceutil/trace.go:171","msg":"trace[1624333551] transaction","detail":"{read_only:false; response_revision:13895; number_of_response:1; }","duration":"121.556754ms","start":"2026-08-10T22:52:44.436862Z","end":"2026-08-10T22:52:44.558419Z","steps":["trace[1624333551] 'process raft request' (duration: 121.226226ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:53:57.280231Z","caller":"traceutil/trace.go:171","msg":"trace[187947190] transaction","detail":"{read_only:false; response_revision:14634; number_of_response:1; }","duration":"210.728604ms","start":"2026-08-10T22:53:57.069485Z","end":"2026-08-10T22:53:57.280213Z","steps":["trace[187947190] 'process raft request' (duration: 210.614481ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:53:57.280347Z","caller":"traceutil/trace.go:171","msg":"trace[1749788119] linearizableReadLoop","detail":"{readStateIndex:15128; appliedIndex:15128; }","duration":"124.238699ms","start":"2026-08-10T22:53:57.156071Z","end":"2026-08-10T22:53:57.28031Z","steps":["trace[1749788119] 'read index received' (duration: 124.225229ms)","trace[1749788119] 'applied index is now lower than readState.Index' (duration: 11.02µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:53:57.280625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.529767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-10T22:53:57.280665Z","caller":"traceutil/trace.go:171","msg":"trace[907690258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14634; }","duration":"124.6329ms","start":"2026-08-10T22:53:57.15602Z","end":"2026-08-10T22:53:57.280653Z","steps":["trace[907690258] 'agreement among raft nodes before linearized reading' (duration: 124.402714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:57.629764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.714386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:53:57.629877Z","caller":"traceutil/trace.go:171","msg":"trace[1963649541] transaction","detail":"{read_only:false; response_revision:14635; number_of_response:1; }","duration":"425.982039ms","start":"2026-08-10T22:53:57.203873Z","end":"2026-08-10T22:53:57.629855Z","steps":["trace[1963649541] 'process raft request' (duration: 221.076318ms)","trace[1963649541] 'compare' (duration: 204.608434ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:53:57.629935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:57.203845Z","time spent":"426.063121ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:53:57.643224Z","caller":"traceutil/trace.go:171","msg":"trace[810135128] linearizableReadLoop","detail":"{readStateIndex:15130; appliedIndex:15128; }","duration":"309.936029ms","start":"2026-08-10T22:53:57.333259Z","end":"2026-08-10T22:53:57.643195Z","steps":["trace[810135128] 'read index received' (duration: 91.705221ms)","trace[810135128] 'applied index is now lower than readState.Index' (duration: 218.229598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:53:57.64349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.208076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cn25b\" ","response":"range_response_count:1 size:9798"} {"level":"warn","ts":"2026-08-10T22:53:57.643543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.546724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-10T22:53:57.643771Z","caller":"traceutil/trace.go:171","msg":"trace[1994898738] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14636; }","duration":"288.806391ms","start":"2026-08-10T22:53:57.354936Z","end":"2026-08-10T22:53:57.643743Z","steps":["trace[1994898738] 'agreement among raft nodes before linearized reading' (duration: 288.472633ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:53:57.643658Z","caller":"traceutil/trace.go:171","msg":"trace[1438463000] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cn25b; range_end:; response_count:1; response_revision:14636; }","duration":"310.426091ms","start":"2026-08-10T22:53:57.333214Z","end":"2026-08-10T22:53:57.64364Z","steps":["trace[1438463000] 'agreement among raft nodes before linearized reading' (duration: 310.116724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:57.643877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:57.333196Z","time spent":"310.664008ms","remote":"127.0.0.1:46198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9821,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-cn25b\" "} {"level":"info","ts":"2026-08-10T22:53:57.643898Z","caller":"traceutil/trace.go:171","msg":"trace[1902288611] transaction","detail":"{read_only:false; response_revision:14636; number_of_response:1; }","duration":"355.929829ms","start":"2026-08-10T22:53:57.2874Z","end":"2026-08-10T22:53:57.64333Z","steps":["trace[1902288611] 'process raft request' (duration: 355.679723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:53:57.64398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:53:57.28738Z","time spent":"356.548663ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:55:29.658094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12847} {"level":"info","ts":"2026-08-10T22:55:29.736296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12847,"took":"75.3564ms","hash":3972248136,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30212096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T22:55:29.736403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3972248136,"revision":12847,"compact-revision":10652} {"level":"info","ts":"2026-08-10T22:59:41.988836Z","caller":"traceutil/trace.go:171","msg":"trace[222858497] transaction","detail":"{read_only:false; response_revision:17200; number_of_response:1; }","duration":"543.194327ms","start":"2026-08-10T22:59:41.445611Z","end":"2026-08-10T22:59:41.988805Z","steps":["trace[222858497] 'process raft request' (duration: 514.605625ms)","trace[222858497] 'compare' (duration: 28.491759ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:59:41.988988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:41.445574Z","time spent":"543.33806ms","remote":"127.0.0.1:46198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20873,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:59:41.992679Z","caller":"traceutil/trace.go:171","msg":"trace[1242365718] linearizableReadLoop","detail":"{readStateIndex:17779; appliedIndex:17777; }","duration":"426.241245ms","start":"2026-08-10T22:59:41.566405Z","end":"2026-08-10T22:59:41.992646Z","steps":["trace[1242365718] 'read index received' (duration: 393.829589ms)","trace[1242365718] 'applied index is now lower than readState.Index' (duration: 32.410045ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:59:41.993305Z","caller":"traceutil/trace.go:171","msg":"trace[265661824] transaction","detail":"{read_only:false; response_revision:17201; number_of_response:1; }","duration":"544.1793ms","start":"2026-08-10T22:59:41.449114Z","end":"2026-08-10T22:59:41.993293Z","steps":["trace[265661824] 'process raft request' (duration: 543.365971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:41.993493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:41.449085Z","time spent":"544.256142ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:59:41.993816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.764264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-10T22:59:41.993891Z","caller":"traceutil/trace.go:171","msg":"trace[1550654342] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17201; }","duration":"223.875996ms","start":"2026-08-10T22:59:41.769994Z","end":"2026-08-10T22:59:41.99387Z","steps":["trace[1550654342] 'agreement among raft nodes before linearized reading' (duration: 223.656761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:41.993914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.507756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cn25b\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-08-10T22:59:41.993947Z","caller":"traceutil/trace.go:171","msg":"trace[1712034185] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cn25b; range_end:; response_count:1; response_revision:17201; }","duration":"427.571318ms","start":"2026-08-10T22:59:41.566368Z","end":"2026-08-10T22:59:41.993939Z","steps":["trace[1712034185] 'agreement among raft nodes before linearized reading' (duration: 427.418524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:59:41.993979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:59:41.566352Z","time spent":"427.622399ms","remote":"127.0.0.1:46198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9822,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-cn25b\" "} {"level":"info","ts":"2026-08-10T23:00:29.665399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15238} {"level":"info","ts":"2026-08-10T23:00:29.766421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15238,"took":"97.681029ms","hash":1336379617,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":33124352,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-10T23:00:29.766488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1336379617,"revision":15238,"compact-revision":12847} {"level":"info","ts":"2026-08-10T23:03:25.353911Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:03:25.358343Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-10T23:03:25.358444Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-10T23:05:29.673495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17843} {"level":"info","ts":"2026-08-10T23:05:29.783839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17843,"took":"106.611659ms","hash":2623213081,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31932416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T23:05:29.783916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2623213081,"revision":17843,"compact-revision":15238} {"level":"info","ts":"2026-08-10T23:07:26.814507Z","caller":"traceutil/trace.go:171","msg":"trace[2039087552] transaction","detail":"{read_only:false; response_revision:21103; number_of_response:1; }","duration":"103.08701ms","start":"2026-08-10T23:07:26.711389Z","end":"2026-08-10T23:07:26.814477Z","steps":["trace[2039087552] 'process raft request' (duration: 87.038477ms)","trace[2039087552] 'compare' (duration: 15.525121ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:07:28.174927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.215843ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-10T23:07:28.175052Z","caller":"traceutil/trace.go:171","msg":"trace[414381723] linearizableReadLoop","detail":"{readStateIndex:21804; appliedIndex:21803; }","duration":"128.812263ms","start":"2026-08-10T23:07:28.046218Z","end":"2026-08-10T23:07:28.17503Z","steps":["trace[414381723] 'read index received' (duration: 47.401µs)","trace[414381723] 'applied index is now lower than readState.Index' (duration: 128.762842ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:07:28.175142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.919637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:07:28.175197Z","caller":"traceutil/trace.go:171","msg":"trace[1874363899] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:21105; }","duration":"129.015618ms","start":"2026-08-10T23:07:28.046164Z","end":"2026-08-10T23:07:28.175179Z","steps":["trace[1874363899] 'agreement among raft nodes before linearized reading' (duration: 128.925346ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:07:28.674461Z","caller":"traceutil/trace.go:171","msg":"trace[1958218907] linearizableReadLoop","detail":"{readStateIndex:21805; appliedIndex:21804; }","duration":"137.912926ms","start":"2026-08-10T23:07:28.536522Z","end":"2026-08-10T23:07:28.674435Z","steps":["trace[1958218907] 'read index received' (duration: 137.750602ms)","trace[1958218907] 'applied index is now lower than readState.Index' (duration: 161.304µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:07:28.674517Z","caller":"traceutil/trace.go:171","msg":"trace[1371657803] transaction","detail":"{read_only:false; response_revision:21106; number_of_response:1; }","duration":"225.19374ms","start":"2026-08-10T23:07:28.449291Z","end":"2026-08-10T23:07:28.674484Z","steps":["trace[1371657803] 'process raft request' (duration: 224.939384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:07:28.674674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.143812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-10T23:07:28.674719Z","caller":"traceutil/trace.go:171","msg":"trace[516581405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21106; }","duration":"138.239965ms","start":"2026-08-10T23:07:28.536466Z","end":"2026-08-10T23:07:28.674706Z","steps":["trace[516581405] 'agreement among raft nodes before linearized reading' (duration: 138.095371ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:07:28.792351Z","caller":"traceutil/trace.go:171","msg":"trace[414834972] transaction","detail":"{read_only:false; response_revision:21107; number_of_response:1; }","duration":"249.290052ms","start":"2026-08-10T23:07:28.543033Z","end":"2026-08-10T23:07:28.792323Z","steps":["trace[414834972] 'process raft request' (duration: 246.862442ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:07:28.792382Z","caller":"traceutil/trace.go:171","msg":"trace[1517997752] linearizableReadLoop","detail":"{readStateIndex:21806; appliedIndex:21805; }","duration":"117.815673ms","start":"2026-08-10T23:07:28.674532Z","end":"2026-08-10T23:07:28.792348Z","steps":["trace[1517997752] 'read index received' (duration: 115.378923ms)","trace[1517997752] 'applied index is now lower than readState.Index' (duration: 2.43554ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:07:28.792643Z","caller":"traceutil/trace.go:171","msg":"trace[1879284231] transaction","detail":"{read_only:false; response_revision:21108; number_of_response:1; }","duration":"113.785884ms","start":"2026-08-10T23:07:28.678828Z","end":"2026-08-10T23:07:28.792614Z","steps":["trace[1879284231] 'process raft request' (duration: 113.463916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:07:28.792764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.990216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-10T23:07:28.792816Z","caller":"traceutil/trace.go:171","msg":"trace[466233363] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21108; }","duration":"155.101249ms","start":"2026-08-10T23:07:28.637701Z","end":"2026-08-10T23:07:28.792803Z","steps":["trace[466233363] 'agreement among raft nodes before linearized reading' (duration: 154.890243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:07:28.793014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.662437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-10T23:07:28.793051Z","caller":"traceutil/trace.go:171","msg":"trace[1692801263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21108; }","duration":"145.719208ms","start":"2026-08-10T23:07:28.647318Z","end":"2026-08-10T23:07:28.793037Z","steps":["trace[1692801263] 'agreement among raft nodes before linearized reading' (duration: 145.03297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:07:28.793049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.055132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T23:07:28.7931Z","caller":"traceutil/trace.go:171","msg":"trace[1950342660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21108; }","duration":"147.147814ms","start":"2026-08-10T23:07:28.645939Z","end":"2026-08-10T23:07:28.793087Z","steps":["trace[1950342660] 'agreement among raft nodes before linearized reading' (duration: 146.443586ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:10:29.680606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20298} {"level":"info","ts":"2026-08-10T23:10:29.79183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20298,"took":"108.304959ms","hash":1722952627,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":32096256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T23:10:29.791898Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1722952627,"revision":20298,"compact-revision":17843} {"level":"info","ts":"2026-08-10T23:11:15.591879Z","caller":"traceutil/trace.go:171","msg":"trace[26001957] transaction","detail":"{read_only:false; response_revision:23389; number_of_response:1; }","duration":"156.594804ms","start":"2026-08-10T23:11:15.435265Z","end":"2026-08-10T23:11:15.59186Z","steps":["trace[26001957] 'process raft request' (duration: 110.36321ms)","trace[26001957] 'compare' (duration: 46.072771ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:11:15.592081Z","caller":"traceutil/trace.go:171","msg":"trace[759721199] transaction","detail":"{read_only:false; number_of_response:1; response_revision:23390; }","duration":"154.3323ms","start":"2026-08-10T23:11:15.437742Z","end":"2026-08-10T23:11:15.592074Z","steps":["trace[759721199] 'process raft request' (duration: 154.066053ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:11:15.592117Z","caller":"traceutil/trace.go:171","msg":"trace[698592104] linearizableReadLoop","detail":"{readStateIndex:24163; appliedIndex:24161; }","duration":"146.030196ms","start":"2026-08-10T23:11:15.446043Z","end":"2026-08-10T23:11:15.592073Z","steps":["trace[698592104] 'read index received' (duration: 99.567945ms)","trace[698592104] 'applied index is now lower than readState.Index' (duration: 46.459691ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:11:15.592202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.141848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:11:15.592239Z","caller":"traceutil/trace.go:171","msg":"trace[1583867947] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:23390; }","duration":"146.221739ms","start":"2026-08-10T23:11:15.446008Z","end":"2026-08-10T23:11:15.59223Z","steps":["trace[1583867947] 'agreement among raft nodes before linearized reading' (duration: 146.140127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:11:15.592362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.763338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-08-10T23:11:15.592401Z","caller":"traceutil/trace.go:171","msg":"trace[1750063735] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:23390; }","duration":"125.830779ms","start":"2026-08-10T23:11:15.466558Z","end":"2026-08-10T23:11:15.592389Z","steps":["trace[1750063735] 'agreement among raft nodes before linearized reading' (duration: 125.683665ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:10.269747Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065c6.wal"} {"level":"info","ts":"2026-08-10T23:14:45.595484Z","caller":"traceutil/trace.go:171","msg":"trace[953161332] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"119.350971ms","start":"2026-08-10T23:14:45.476104Z","end":"2026-08-10T23:14:45.595455Z","steps":["trace[953161332] 'process raft request' (duration: 119.159306ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:46.5038Z","caller":"traceutil/trace.go:171","msg":"trace[1243489282] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"118.594571ms","start":"2026-08-10T23:14:46.385185Z","end":"2026-08-10T23:14:46.503779Z","steps":["trace[1243489282] 'process raft request' (duration: 118.469168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:46.779909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.488101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:14:46.780035Z","caller":"traceutil/trace.go:171","msg":"trace[1735885219] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"393.544233ms","start":"2026-08-10T23:14:46.386475Z","end":"2026-08-10T23:14:46.78002Z","steps":["trace[1735885219] 'process raft request' (duration: 256.890418ms)","trace[1735885219] 'compare' (duration: 136.321506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:46.780086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:14:46.386454Z","time spent":"393.605735ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:14:46.780226Z","caller":"traceutil/trace.go:171","msg":"trace[595540023] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"342.547702ms","start":"2026-08-10T23:14:46.437647Z","end":"2026-08-10T23:14:46.780195Z","steps":["trace[595540023] 'process raft request' (duration: 342.331606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:46.780377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:14:46.437623Z","time spent":"342.670095ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:14:46.987377Z","caller":"traceutil/trace.go:171","msg":"trace[1495310776] linearizableReadLoop","detail":"{readStateIndex:26461; appliedIndex:26460; }","duration":"258.258502ms","start":"2026-08-10T23:14:46.729075Z","end":"2026-08-10T23:14:46.987334Z","steps":["trace[1495310776] 'read index received' (duration: 209.193267ms)","trace[1495310776] 'applied index is now lower than readState.Index' (duration: 49.063445ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:46.987419Z","caller":"traceutil/trace.go:171","msg":"trace[121370293] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"258.446196ms","start":"2026-08-10T23:14:46.728937Z","end":"2026-08-10T23:14:46.987383Z","steps":["trace[121370293] 'process raft request' (duration: 209.421052ms)","trace[121370293] 'compare' (duration: 48.799779ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:46.987538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.452167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:14:46.987571Z","caller":"traceutil/trace.go:171","msg":"trace[1407898540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25633; }","duration":"258.539739ms","start":"2026-08-10T23:14:46.729024Z","end":"2026-08-10T23:14:46.987563Z","steps":["trace[1407898540] 'agreement among raft nodes before linearized reading' (duration: 258.457627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:51.321077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.604744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:14:51.32123Z","caller":"traceutil/trace.go:171","msg":"trace[872249012] linearizableReadLoop","detail":"{readStateIndex:26492; appliedIndex:26490; }","duration":"250.528802ms","start":"2026-08-10T23:14:51.070686Z","end":"2026-08-10T23:14:51.321215Z","steps":["trace[872249012] 'read index received' (duration: 111.736964ms)","trace[872249012] 'applied index is now lower than readState.Index' (duration: 138.790668ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:51.321322Z","caller":"traceutil/trace.go:171","msg":"trace[549760493] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"251.497465ms","start":"2026-08-10T23:14:51.069812Z","end":"2026-08-10T23:14:51.321309Z","steps":["trace[549760493] 'process raft request' (duration: 251.353382ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.321339Z","caller":"traceutil/trace.go:171","msg":"trace[1211083076] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"252.380626ms","start":"2026-08-10T23:14:51.068928Z","end":"2026-08-10T23:14:51.321309Z","steps":["trace[1211083076] 'process raft request' (duration: 113.454405ms)","trace[1211083076] 'compare' (duration: 138.497731ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:51.321625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.933382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-10T23:14:51.321661Z","caller":"traceutil/trace.go:171","msg":"trace[823848657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25663; }","duration":"250.984093ms","start":"2026-08-10T23:14:51.070667Z","end":"2026-08-10T23:14:51.321651Z","steps":["trace[823848657] 'agreement among raft nodes before linearized reading' (duration: 250.8697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:51.321846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.029311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-10T23:14:51.32186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.884664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-10T23:14:51.322059Z","caller":"traceutil/trace.go:171","msg":"trace[902339304] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25663; }","duration":"235.273847ms","start":"2026-08-10T23:14:51.08677Z","end":"2026-08-10T23:14:51.322044Z","steps":["trace[902339304] 'agreement among raft nodes before linearized reading' (duration: 234.97611ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.322095Z","caller":"traceutil/trace.go:171","msg":"trace[1440003691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25663; }","duration":"241.143781ms","start":"2026-08-10T23:14:51.080934Z","end":"2026-08-10T23:14:51.322077Z","steps":["trace[1440003691] 'agreement among raft nodes before linearized reading' (duration: 240.811273ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.447725Z","caller":"traceutil/trace.go:171","msg":"trace[775441876] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"118.534051ms","start":"2026-08-10T23:14:51.329152Z","end":"2026-08-10T23:14:51.447686Z","steps":["trace[775441876] 'process raft request' (duration: 118.431119ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.447759Z","caller":"traceutil/trace.go:171","msg":"trace[1120747721] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"118.674234ms","start":"2026-08-10T23:14:51.32905Z","end":"2026-08-10T23:14:51.447724Z","steps":["trace[1120747721] 'process raft request' (duration: 118.384657ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.448104Z","caller":"traceutil/trace.go:171","msg":"trace[1391297695] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"116.217214ms","start":"2026-08-10T23:14:51.331863Z","end":"2026-08-10T23:14:51.44808Z","steps":["trace[1391297695] 'process raft request' (duration: 116.123781ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.448159Z","caller":"traceutil/trace.go:171","msg":"trace[1313495524] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"117.642398ms","start":"2026-08-10T23:14:51.33049Z","end":"2026-08-10T23:14:51.448132Z","steps":["trace[1313495524] 'process raft request' (duration: 117.145406ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.73661Z","caller":"traceutil/trace.go:171","msg":"trace[149928952] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"119.087805ms","start":"2026-08-10T23:14:51.617493Z","end":"2026-08-10T23:14:51.73658Z","steps":["trace[149928952] 'process raft request' (duration: 114.440721ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:51.760756Z","caller":"traceutil/trace.go:171","msg":"trace[129908027] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"141.566647ms","start":"2026-08-10T23:14:51.619149Z","end":"2026-08-10T23:14:51.760715Z","steps":["trace[129908027] 'process raft request' (duration: 141.176767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:52.020726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.868005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-10T23:14:52.02079Z","caller":"traceutil/trace.go:171","msg":"trace[1290010166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25670; }","duration":"114.014409ms","start":"2026-08-10T23:14:51.906762Z","end":"2026-08-10T23:14:52.020777Z","steps":["trace[1290010166] 'range keys from in-memory index tree' (duration: 113.493326ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:52.409346Z","caller":"traceutil/trace.go:171","msg":"trace[162233120] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"385.421925ms","start":"2026-08-10T23:14:52.023897Z","end":"2026-08-10T23:14:52.409319Z","steps":["trace[162233120] 'process raft request' (duration: 385.257281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:52.40951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:14:52.023878Z","time spent":"385.550427ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:14:52.490145Z","caller":"traceutil/trace.go:171","msg":"trace[16451449] linearizableReadLoop","detail":"{readStateIndex:26501; appliedIndex:26500; }","duration":"193.705736ms","start":"2026-08-10T23:14:52.296381Z","end":"2026-08-10T23:14:52.490086Z","steps":["trace[16451449] 'read index received' (duration: 113.704601ms)","trace[16451449] 'applied index is now lower than readState.Index' (duration: 79.999975ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:52.490274Z","caller":"traceutil/trace.go:171","msg":"trace[1704233355] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"424.380511ms","start":"2026-08-10T23:14:52.065869Z","end":"2026-08-10T23:14:52.49025Z","steps":["trace[1704233355] 'process raft request' (duration: 366.325055ms)","trace[1704233355] 'compare' (duration: 57.628205ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:52.490433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:14:52.065852Z","time spent":"424.519504ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:14:52.490433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.034194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-10T23:14:52.490492Z","caller":"traceutil/trace.go:171","msg":"trace[473461972] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25672; }","duration":"194.138566ms","start":"2026-08-10T23:14:52.296342Z","end":"2026-08-10T23:14:52.490481Z","steps":["trace[473461972] 'agreement among raft nodes before linearized reading' (duration: 193.912041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:52.491215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.509846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-10T23:14:52.491257Z","caller":"traceutil/trace.go:171","msg":"trace[195558375] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25672; }","duration":"194.569217ms","start":"2026-08-10T23:14:52.296676Z","end":"2026-08-10T23:14:52.491246Z","steps":["trace[195558375] 'agreement among raft nodes before linearized reading' (duration: 194.443184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:52.491657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.311332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:14:52.491696Z","caller":"traceutil/trace.go:171","msg":"trace[2098757387] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25672; }","duration":"100.355343ms","start":"2026-08-10T23:14:52.391331Z","end":"2026-08-10T23:14:52.491686Z","steps":["trace[2098757387] 'agreement among raft nodes before linearized reading' (duration: 100.294222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:52.492003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.487626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-10T23:14:52.492031Z","caller":"traceutil/trace.go:171","msg":"trace[1829274545] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25672; }","duration":"194.528717ms","start":"2026-08-10T23:14:52.297496Z","end":"2026-08-10T23:14:52.492025Z","steps":["trace[1829274545] 'agreement among raft nodes before linearized reading' (duration: 194.457445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:52.80364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.213411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:14:52.803733Z","caller":"traceutil/trace.go:171","msg":"trace[153925398] linearizableReadLoop","detail":"{readStateIndex:26502; appliedIndex:26501; }","duration":"114.126963ms","start":"2026-08-10T23:14:52.689591Z","end":"2026-08-10T23:14:52.803718Z","steps":["trace[153925398] 'read index received' (duration: 46.742µs)","trace[153925398] 'applied index is now lower than readState.Index' (duration: 114.078751ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:52.803919Z","caller":"traceutil/trace.go:171","msg":"trace[843586851] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"283.442729ms","start":"2026-08-10T23:14:52.520468Z","end":"2026-08-10T23:14:52.80391Z","steps":["trace[843586851] 'process raft request' (duration: 105.88052ms)","trace[843586851] 'compare' (duration: 176.794151ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:52.8042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.612734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-10T23:14:52.80424Z","caller":"traceutil/trace.go:171","msg":"trace[475719840] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25673; }","duration":"114.685206ms","start":"2026-08-10T23:14:52.689542Z","end":"2026-08-10T23:14:52.804227Z","steps":["trace[475719840] 'agreement among raft nodes before linearized reading' (duration: 114.516992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:52.821316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.502602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-10T23:14:52.82142Z","caller":"traceutil/trace.go:171","msg":"trace[500569860] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:25675; }","duration":"125.629864ms","start":"2026-08-10T23:14:52.695763Z","end":"2026-08-10T23:14:52.821393Z","steps":["trace[500569860] 'agreement among raft nodes before linearized reading' (duration: 125.386179ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:53.065001Z","caller":"traceutil/trace.go:171","msg":"trace[426099461] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"255.663668ms","start":"2026-08-10T23:14:52.809303Z","end":"2026-08-10T23:14:53.064967Z","steps":["trace[426099461] 'process raft request' (duration: 255.502644ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:53.138265Z","caller":"traceutil/trace.go:171","msg":"trace[58274708] linearizableReadLoop","detail":"{readStateIndex:26506; appliedIndex:26504; }","duration":"223.483057ms","start":"2026-08-10T23:14:52.914755Z","end":"2026-08-10T23:14:53.138238Z","steps":["trace[58274708] 'read index received' (duration: 149.991103ms)","trace[58274708] 'applied index is now lower than readState.Index' (duration: 73.490104ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:53.138294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:14:52.822581Z","time spent":"315.702961ms","remote":"127.0.0.1:46038","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-10T23:14:53.138403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.629691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:14:53.138461Z","caller":"traceutil/trace.go:171","msg":"trace[530110640] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25676; }","duration":"223.745834ms","start":"2026-08-10T23:14:52.914699Z","end":"2026-08-10T23:14:53.138445Z","steps":["trace[530110640] 'agreement among raft nodes before linearized reading' (duration: 223.632492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:53.369297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.746404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:14:53.36949Z","caller":"traceutil/trace.go:171","msg":"trace[1542744960] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"244.756711ms","start":"2026-08-10T23:14:53.124713Z","end":"2026-08-10T23:14:53.36947Z","steps":["trace[1542744960] 'process raft request' (duration: 244.692729ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:53.369636Z","caller":"traceutil/trace.go:171","msg":"trace[871438765] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"299.147216ms","start":"2026-08-10T23:14:53.070478Z","end":"2026-08-10T23:14:53.369625Z","steps":["trace[871438765] 'process raft request' (duration: 197.982441ms)","trace[871438765] 'compare' (duration: 100.599091ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:53.371859Z","caller":"traceutil/trace.go:171","msg":"trace[1964210183] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"232.502889ms","start":"2026-08-10T23:14:53.139328Z","end":"2026-08-10T23:14:53.371831Z","steps":["trace[1964210183] 'process raft request' (duration: 232.334095ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:53.657944Z","caller":"traceutil/trace.go:171","msg":"trace[1455207131] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26511; }","duration":"204.400009ms","start":"2026-08-10T23:14:53.453521Z","end":"2026-08-10T23:14:53.657921Z","steps":["trace[1455207131] 'read index received' (duration: 204.070501ms)","trace[1455207131] 'applied index is now lower than readState.Index' (duration: 328.188µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:53.657993Z","caller":"traceutil/trace.go:171","msg":"trace[721634967] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"236.163819ms","start":"2026-08-10T23:14:53.421798Z","end":"2026-08-10T23:14:53.657961Z","steps":["trace[721634967] 'process raft request' (duration: 235.973025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:53.658213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.631655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-10T23:14:53.658256Z","caller":"traceutil/trace.go:171","msg":"trace[465599174] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25682; }","duration":"204.724227ms","start":"2026-08-10T23:14:53.453518Z","end":"2026-08-10T23:14:53.658242Z","steps":["trace[465599174] 'agreement among raft nodes before linearized reading' (duration: 204.556463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:53.658322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.750988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-10T23:14:53.658366Z","caller":"traceutil/trace.go:171","msg":"trace[1552324682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25682; }","duration":"204.818449ms","start":"2026-08-10T23:14:53.453538Z","end":"2026-08-10T23:14:53.658356Z","steps":["trace[1552324682] 'agreement among raft nodes before linearized reading' (duration: 204.697276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:53.658612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.087636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-10T23:14:53.658658Z","caller":"traceutil/trace.go:171","msg":"trace[1529613201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25682; }","duration":"205.175278ms","start":"2026-08-10T23:14:53.453471Z","end":"2026-08-10T23:14:53.658646Z","steps":["trace[1529613201] 'agreement among raft nodes before linearized reading' (duration: 204.539493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:14:53.938473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.211804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:14:53.938748Z","caller":"traceutil/trace.go:171","msg":"trace[153133499] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"275.219578ms","start":"2026-08-10T23:14:53.663496Z","end":"2026-08-10T23:14:53.938716Z","steps":["trace[153133499] 'process raft request' (duration: 59.660855ms)","trace[153133499] 'compare' (duration: 214.708672ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:53.938754Z","caller":"traceutil/trace.go:171","msg":"trace[374312063] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"274.748976ms","start":"2026-08-10T23:14:53.663975Z","end":"2026-08-10T23:14:53.938724Z","steps":["trace[374312063] 'process raft request' (duration: 274.619993ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:53.938817Z","caller":"traceutil/trace.go:171","msg":"trace[211725602] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"197.11926ms","start":"2026-08-10T23:14:53.741675Z","end":"2026-08-10T23:14:53.938794Z","steps":["trace[211725602] 'process raft request' (duration: 197.046148ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:53.938834Z","caller":"traceutil/trace.go:171","msg":"trace[391359327] linearizableReadLoop","detail":"{readStateIndex:26515; appliedIndex:26513; }","duration":"210.470968ms","start":"2026-08-10T23:14:53.728336Z","end":"2026-08-10T23:14:53.938807Z","steps":["trace[391359327] 'read index received' (duration: 33.791µs)","trace[391359327] 'applied index is now lower than readState.Index' (duration: 210.434637ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:53.938969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.615801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:14:53.939016Z","caller":"traceutil/trace.go:171","msg":"trace[989264373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25686; }","duration":"210.706374ms","start":"2026-08-10T23:14:53.728296Z","end":"2026-08-10T23:14:53.939002Z","steps":["trace[989264373] 'agreement among raft nodes before linearized reading' (duration: 210.599821ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:54.167131Z","caller":"traceutil/trace.go:171","msg":"trace[1569580038] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"194.082576ms","start":"2026-08-10T23:14:53.973017Z","end":"2026-08-10T23:14:54.1671Z","steps":["trace[1569580038] 'process raft request' (duration: 193.897461ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:14:54.16721Z","caller":"traceutil/trace.go:171","msg":"trace[1765823015] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"401.376465ms","start":"2026-08-10T23:14:53.76578Z","end":"2026-08-10T23:14:54.167156Z","steps":["trace[1765823015] 'process raft request' (duration: 346.557699ms)","trace[1765823015] 'compare' (duration: 54.422166ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:14:54.167267Z","caller":"traceutil/trace.go:171","msg":"trace[2013455756] linearizableReadLoop","detail":"{readStateIndex:26517; appliedIndex:26516; }","duration":"227.1914ms","start":"2026-08-10T23:14:53.940052Z","end":"2026-08-10T23:14:54.167244Z","steps":["trace[2013455756] 'read index received' (duration: 172.301121ms)","trace[2013455756] 'applied index is now lower than readState.Index' (duration: 54.887238ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:14:54.167396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:14:53.765757Z","time spent":"401.53666ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:14:54.167401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.324053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:14:54.167477Z","caller":"traceutil/trace.go:171","msg":"trace[1249202166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25688; }","duration":"227.443286ms","start":"2026-08-10T23:14:53.940015Z","end":"2026-08-10T23:14:54.167459Z","steps":["trace[1249202166] 'agreement among raft nodes before linearized reading' (duration: 227.297392ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:03.504991Z","caller":"traceutil/trace.go:171","msg":"trace[203840381] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"101.911973ms","start":"2026-08-10T23:15:03.403055Z","end":"2026-08-10T23:15:03.504967Z","steps":["trace[203840381] 'process raft request' (duration: 101.525194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:03.860345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.213352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:15:03.860643Z","caller":"traceutil/trace.go:171","msg":"trace[484884792] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"351.681616ms","start":"2026-08-10T23:15:03.50893Z","end":"2026-08-10T23:15:03.860611Z","steps":["trace[484884792] 'process raft request' (duration: 152.100426ms)","trace[484884792] 'compare' (duration: 199.005396ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:03.860787Z","caller":"traceutil/trace.go:171","msg":"trace[2056391760] linearizableReadLoop","detail":"{readStateIndex:26614; appliedIndex:26612; }","duration":"289.482197ms","start":"2026-08-10T23:15:03.571282Z","end":"2026-08-10T23:15:03.860764Z","steps":["trace[2056391760] 'read index received' (duration: 89.766494ms)","trace[2056391760] 'applied index is now lower than readState.Index' (duration: 199.714403ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:03.860806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:03.508903Z","time spent":"351.799609ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:15:03.860812Z","caller":"traceutil/trace.go:171","msg":"trace[1774300606] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"234.97987ms","start":"2026-08-10T23:15:03.625805Z","end":"2026-08-10T23:15:03.860785Z","steps":["trace[1774300606] 'process raft request' (duration: 234.751954ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:03.860823Z","caller":"traceutil/trace.go:171","msg":"trace[294035123] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"340.691946ms","start":"2026-08-10T23:15:03.520087Z","end":"2026-08-10T23:15:03.860779Z","steps":["trace[294035123] 'process raft request' (duration: 340.390409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:03.860962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:03.520056Z","time spent":"340.831869ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:15:03.860996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.725805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"warn","ts":"2026-08-10T23:15:03.860955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.115204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:03.861023Z","caller":"traceutil/trace.go:171","msg":"trace[1238758824] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25783; }","duration":"289.791267ms","start":"2026-08-10T23:15:03.571225Z","end":"2026-08-10T23:15:03.861016Z","steps":["trace[1238758824] 'agreement among raft nodes before linearized reading' (duration: 289.633013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:03.861018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.431174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-10T23:15:03.861041Z","caller":"traceutil/trace.go:171","msg":"trace[656298844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25783; }","duration":"132.244667ms","start":"2026-08-10T23:15:03.728781Z","end":"2026-08-10T23:15:03.861026Z","steps":["trace[656298844] 'agreement among raft nodes before linearized reading' (duration: 132.123824ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:03.861071Z","caller":"traceutil/trace.go:171","msg":"trace[2054785040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25783; }","duration":"148.523887ms","start":"2026-08-10T23:15:03.712531Z","end":"2026-08-10T23:15:03.861055Z","steps":["trace[2054785040] 'agreement among raft nodes before linearized reading' (duration: 148.358353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:03.861065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.094742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-10T23:15:03.861125Z","caller":"traceutil/trace.go:171","msg":"trace[709097062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25783; }","duration":"112.209995ms","start":"2026-08-10T23:15:03.748901Z","end":"2026-08-10T23:15:03.861111Z","steps":["trace[709097062] 'agreement among raft nodes before linearized reading' (duration: 111.99638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:04.143386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.453038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:15:04.143723Z","caller":"traceutil/trace.go:171","msg":"trace[1507121240] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"278.672363ms","start":"2026-08-10T23:15:03.865025Z","end":"2026-08-10T23:15:04.143698Z","steps":["trace[1507121240] 'process raft request' (duration: 122.803255ms)","trace[1507121240] 'compare' (duration: 155.182871ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:04.143906Z","caller":"traceutil/trace.go:171","msg":"trace[1635900311] linearizableReadLoop","detail":"{readStateIndex:26618; appliedIndex:26615; }","duration":"156.618295ms","start":"2026-08-10T23:15:03.987267Z","end":"2026-08-10T23:15:04.143885Z","steps":["trace[1635900311] 'read index received' (duration: 575.194µs)","trace[1635900311] 'applied index is now lower than readState.Index' (duration: 156.042011ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:04.144004Z","caller":"traceutil/trace.go:171","msg":"trace[1682542578] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"172.579418ms","start":"2026-08-10T23:15:03.971416Z","end":"2026-08-10T23:15:04.143996Z","steps":["trace[1682542578] 'process raft request' (duration: 172.23655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:04.144124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.125355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-10T23:15:04.14416Z","caller":"traceutil/trace.go:171","msg":"trace[791712731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25786; }","duration":"154.181916ms","start":"2026-08-10T23:15:03.989969Z","end":"2026-08-10T23:15:04.144151Z","steps":["trace[791712731] 'agreement among raft nodes before linearized reading' (duration: 154.052453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:04.144238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.971704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-10T23:15:04.14427Z","caller":"traceutil/trace.go:171","msg":"trace[1582839562] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25786; }","duration":"157.040666ms","start":"2026-08-10T23:15:03.987221Z","end":"2026-08-10T23:15:04.144262Z","steps":["trace[1582839562] 'agreement among raft nodes before linearized reading' (duration: 156.878902ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:04.143731Z","caller":"traceutil/trace.go:171","msg":"trace[928171250] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"278.262532ms","start":"2026-08-10T23:15:03.865445Z","end":"2026-08-10T23:15:04.143708Z","steps":["trace[928171250] 'process raft request' (duration: 278.111709ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:04.304828Z","caller":"traceutil/trace.go:171","msg":"trace[221848834] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"156.78348ms","start":"2026-08-10T23:15:04.14802Z","end":"2026-08-10T23:15:04.304804Z","steps":["trace[221848834] 'process raft request' (duration: 156.624306ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:04.316672Z","caller":"traceutil/trace.go:171","msg":"trace[975811501] transaction","detail":"{read_only:false; response_revision:25789; number_of_response:1; }","duration":"120.465909ms","start":"2026-08-10T23:15:04.19617Z","end":"2026-08-10T23:15:04.316636Z","steps":["trace[975811501] 'process raft request' (duration: 120.007116ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:04.316685Z","caller":"traceutil/trace.go:171","msg":"trace[1491101666] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"168.128048ms","start":"2026-08-10T23:15:04.148534Z","end":"2026-08-10T23:15:04.316662Z","steps":["trace[1491101666] 'process raft request' (duration: 167.442901ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:05.147194Z","caller":"traceutil/trace.go:171","msg":"trace[707882316] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"295.136887ms","start":"2026-08-10T23:15:04.852025Z","end":"2026-08-10T23:15:05.147162Z","steps":["trace[707882316] 'process raft request' (duration: 294.943292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:05.369416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.319499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:15:05.369734Z","caller":"traceutil/trace.go:171","msg":"trace[1944150212] linearizableReadLoop","detail":"{readStateIndex:26626; appliedIndex:26623; }","duration":"484.873486ms","start":"2026-08-10T23:15:04.884836Z","end":"2026-08-10T23:15:05.36971Z","steps":["trace[1944150212] 'read index received' (duration: 262.22778ms)","trace[1944150212] 'applied index is now lower than readState.Index' (duration: 222.644076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:05.369825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.98433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:05.369854Z","caller":"traceutil/trace.go:171","msg":"trace[1171992607] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25794; }","duration":"485.054002ms","start":"2026-08-10T23:15:04.88479Z","end":"2026-08-10T23:15:05.369844Z","steps":["trace[1171992607] 'agreement among raft nodes before linearized reading' (duration: 484.98842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:05.369893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:04.884773Z","time spent":"485.111383ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-10T23:15:05.370019Z","caller":"traceutil/trace.go:171","msg":"trace[263715732] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"517.370354ms","start":"2026-08-10T23:15:04.852566Z","end":"2026-08-10T23:15:05.369937Z","steps":["trace[263715732] 'process raft request' (duration: 375.420438ms)","trace[263715732] 'compare' (duration: 141.156876ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:05.370053Z","caller":"traceutil/trace.go:171","msg":"trace[598310219] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"516.37602ms","start":"2026-08-10T23:15:04.853644Z","end":"2026-08-10T23:15:05.37002Z","steps":["trace[598310219] 'process raft request' (duration: 515.986529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:05.370456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:04.852546Z","time spent":"517.780984ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:15:05.370571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:04.853636Z","time spent":"516.846012ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:15:05.601347Z","caller":"traceutil/trace.go:171","msg":"trace[1395748818] linearizableReadLoop","detail":"{readStateIndex:26627; appliedIndex:26626; }","duration":"227.2517ms","start":"2026-08-10T23:15:05.37407Z","end":"2026-08-10T23:15:05.601322Z","steps":["trace[1395748818] 'read index received' (duration: 227.057385ms)","trace[1395748818] 'applied index is now lower than readState.Index' (duration: 192.915µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:05.601722Z","caller":"traceutil/trace.go:171","msg":"trace[2074172399] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"273.937166ms","start":"2026-08-10T23:15:05.327734Z","end":"2026-08-10T23:15:05.601671Z","steps":["trace[2074172399] 'process raft request' (duration: 273.433654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:05.601821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.70473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:05.601917Z","caller":"traceutil/trace.go:171","msg":"trace[422825813] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25795; }","duration":"227.891175ms","start":"2026-08-10T23:15:05.374013Z","end":"2026-08-10T23:15:05.601904Z","steps":["trace[422825813] 'agreement among raft nodes before linearized reading' (duration: 227.650209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:05.61817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.781632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:05.618196Z","caller":"traceutil/trace.go:171","msg":"trace[581408320] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"107.95056ms","start":"2026-08-10T23:15:05.510208Z","end":"2026-08-10T23:15:05.618158Z","steps":["trace[581408320] 'process raft request' (duration: 107.663613ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:05.618232Z","caller":"traceutil/trace.go:171","msg":"trace[1477077074] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25796; }","duration":"207.878233ms","start":"2026-08-10T23:15:05.410336Z","end":"2026-08-10T23:15:05.618215Z","steps":["trace[1477077074] 'agreement among raft nodes before linearized reading' (duration: 207.762031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:05.919681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.463086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:05.919779Z","caller":"traceutil/trace.go:171","msg":"trace[2027122314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25796; }","duration":"190.6143ms","start":"2026-08-10T23:15:05.729141Z","end":"2026-08-10T23:15:05.919755Z","steps":["trace[2027122314] 'range keys from in-memory index tree' (duration: 190.365774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:05.920087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.562197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-10T23:15:05.920127Z","caller":"traceutil/trace.go:171","msg":"trace[1355526217] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25796; }","duration":"115.646039ms","start":"2026-08-10T23:15:05.804471Z","end":"2026-08-10T23:15:05.920117Z","steps":["trace[1355526217] 'count revisions from in-memory index tree' (duration: 115.465044ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:06.09286Z","caller":"traceutil/trace.go:171","msg":"trace[390800367] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"165.591296ms","start":"2026-08-10T23:15:05.926895Z","end":"2026-08-10T23:15:06.092487Z","steps":["trace[390800367] 'process raft request' (duration: 163.666489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:10.429303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.286982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:15:10.429498Z","caller":"traceutil/trace.go:171","msg":"trace[853020240] linearizableReadLoop","detail":"{readStateIndex:26673; appliedIndex:26671; }","duration":"264.027264ms","start":"2026-08-10T23:15:10.16546Z","end":"2026-08-10T23:15:10.429487Z","steps":["trace[853020240] 'read index received' (duration: 42.892304ms)","trace[853020240] 'applied index is now lower than readState.Index' (duration: 221.13423ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:10.429542Z","caller":"traceutil/trace.go:171","msg":"trace[41464750] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"264.870684ms","start":"2026-08-10T23:15:10.164656Z","end":"2026-08-10T23:15:10.429527Z","steps":["trace[41464750] 'process raft request' (duration: 61.304855ms)","trace[41464750] 'compare' (duration: 203.18172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:10.429651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.173767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-10T23:15:10.429708Z","caller":"traceutil/trace.go:171","msg":"trace[1418858663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25840; }","duration":"264.27161ms","start":"2026-08-10T23:15:10.165413Z","end":"2026-08-10T23:15:10.429685Z","steps":["trace[1418858663] 'agreement among raft nodes before linearized reading' (duration: 264.112306ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:10.583503Z","caller":"traceutil/trace.go:171","msg":"trace[1662532828] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"257.603215ms","start":"2026-08-10T23:15:10.325874Z","end":"2026-08-10T23:15:10.583477Z","steps":["trace[1662532828] 'process raft request' (duration: 257.417901ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:10.594884Z","caller":"traceutil/trace.go:171","msg":"trace[1873241263] linearizableReadLoop","detail":"{readStateIndex:26675; appliedIndex:26673; }","duration":"165.290209ms","start":"2026-08-10T23:15:10.429573Z","end":"2026-08-10T23:15:10.594863Z","steps":["trace[1873241263] 'read index received' (duration: 153.728625ms)","trace[1873241263] 'applied index is now lower than readState.Index' (duration: 11.560654ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:10.594983Z","caller":"traceutil/trace.go:171","msg":"trace[494282423] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"161.198358ms","start":"2026-08-10T23:15:10.433755Z","end":"2026-08-10T23:15:10.594954Z","steps":["trace[494282423] 'process raft request' (duration: 161.061265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:10.595076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.434847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-10T23:15:10.595114Z","caller":"traceutil/trace.go:171","msg":"trace[756287689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25843; }","duration":"267.500239ms","start":"2026-08-10T23:15:10.327602Z","end":"2026-08-10T23:15:10.595102Z","steps":["trace[756287689] 'agreement among raft nodes before linearized reading' (duration: 267.365875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:10.595113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.906111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-08-10T23:15:10.595154Z","caller":"traceutil/trace.go:171","msg":"trace[249587267] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25843; }","duration":"316.992273ms","start":"2026-08-10T23:15:10.278148Z","end":"2026-08-10T23:15:10.59514Z","steps":["trace[249587267] 'agreement among raft nodes before linearized reading' (duration: 316.829199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:10.595188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:10.278095Z","time spent":"317.083625ms","remote":"127.0.0.1:46184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-10T23:15:10.59529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.368913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-10T23:15:10.595313Z","caller":"traceutil/trace.go:171","msg":"trace[325254548] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25843; }","duration":"249.459725ms","start":"2026-08-10T23:15:10.345846Z","end":"2026-08-10T23:15:10.595306Z","steps":["trace[325254548] 'agreement among raft nodes before linearized reading' (duration: 249.399114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:10.595564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.059145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-10T23:15:10.595606Z","caller":"traceutil/trace.go:171","msg":"trace[1355304469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25843; }","duration":"429.119286ms","start":"2026-08-10T23:15:10.166476Z","end":"2026-08-10T23:15:10.595595Z","steps":["trace[1355304469] 'agreement among raft nodes before linearized reading' (duration: 429.013264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:10.595634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:10.166465Z","time spent":"429.162768ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-10T23:15:10.595244Z","caller":"traceutil/trace.go:171","msg":"trace[550444414] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"261.10437ms","start":"2026-08-10T23:15:10.334122Z","end":"2026-08-10T23:15:10.595226Z","steps":["trace[550444414] 'process raft request' (duration: 260.596628ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:10.737856Z","caller":"traceutil/trace.go:171","msg":"trace[500031292] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"137.26583ms","start":"2026-08-10T23:15:10.600565Z","end":"2026-08-10T23:15:10.737831Z","steps":["trace[500031292] 'process raft request' (duration: 133.675261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:11.09303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.462891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:15:11.093261Z","caller":"traceutil/trace.go:171","msg":"trace[1200462548] linearizableReadLoop","detail":"{readStateIndex:26679; appliedIndex:26676; }","duration":"490.099425ms","start":"2026-08-10T23:15:10.603145Z","end":"2026-08-10T23:15:11.093244Z","steps":["trace[1200462548] 'read index received' (duration: 131.027207ms)","trace[1200462548] 'applied index is now lower than readState.Index' (duration: 359.071207ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:11.093391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.211859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-10T23:15:11.093497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.631088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-10T23:15:11.093536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.365921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-08-10T23:15:11.093575Z","caller":"traceutil/trace.go:171","msg":"trace[1348913412] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25846; }","duration":"455.743761ms","start":"2026-08-10T23:15:10.637815Z","end":"2026-08-10T23:15:11.093558Z","steps":["trace[1348913412] 'agreement among raft nodes before linearized reading' (duration: 455.531636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:11.093712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:10.637794Z","time spent":"455.906445ms","remote":"127.0.0.1:46198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-10T23:15:11.093529Z","caller":"traceutil/trace.go:171","msg":"trace[1890016626] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"492.665128ms","start":"2026-08-10T23:15:10.600622Z","end":"2026-08-10T23:15:11.093287Z","steps":["trace[1890016626] 'process raft request' (duration: 324.857817ms)","trace[1890016626] 'compare' (duration: 167.114143ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:11.093771Z","caller":"traceutil/trace.go:171","msg":"trace[1295150145] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25846; }","duration":"128.283711ms","start":"2026-08-10T23:15:10.965132Z","end":"2026-08-10T23:15:11.093416Z","steps":["trace[1295150145] 'agreement among raft nodes before linearized reading' (duration: 128.218499ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:11.093727Z","caller":"traceutil/trace.go:171","msg":"trace[1673290790] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"492.680368ms","start":"2026-08-10T23:15:10.600632Z","end":"2026-08-10T23:15:11.093313Z","steps":["trace[1673290790] 'process raft request' (duration: 492.513024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:11.093956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:10.600602Z","time spent":"493.308933ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:15:11.093632Z","caller":"traceutil/trace.go:171","msg":"trace[668294373] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25846; }","duration":"490.517324ms","start":"2026-08-10T23:15:10.603094Z","end":"2026-08-10T23:15:11.093611Z","steps":["trace[668294373] 'agreement among raft nodes before linearized reading' (duration: 490.242768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:11.094087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:10.600597Z","time spent":"493.218691ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:15:11.094176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:10.603073Z","time spent":"491.080589ms","remote":"127.0.0.1:46184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-08-10T23:15:11.093541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.934772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-10T23:15:11.094285Z","caller":"traceutil/trace.go:171","msg":"trace[1121758367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25846; }","duration":"440.725211ms","start":"2026-08-10T23:15:10.653546Z","end":"2026-08-10T23:15:11.094271Z","steps":["trace[1121758367] 'agreement among raft nodes before linearized reading' (duration: 439.84239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:11.094332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:10.653527Z","time spent":"440.792594ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-10T23:15:11.290908Z","caller":"traceutil/trace.go:171","msg":"trace[440158741] linearizableReadLoop","detail":"{readStateIndex:26681; appliedIndex:26680; }","duration":"108.352731ms","start":"2026-08-10T23:15:11.182524Z","end":"2026-08-10T23:15:11.290877Z","steps":["trace[440158741] 'read index received' (duration: 105.918141ms)","trace[440158741] 'applied index is now lower than readState.Index' (duration: 2.43328ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:11.29093Z","caller":"traceutil/trace.go:171","msg":"trace[1472398475] transaction","detail":"{read_only:false; response_revision:25848; number_of_response:1; }","duration":"125.864611ms","start":"2026-08-10T23:15:11.16503Z","end":"2026-08-10T23:15:11.290895Z","steps":["trace[1472398475] 'process raft request' (duration: 123.458702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:11.291053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.512025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:11.291088Z","caller":"traceutil/trace.go:171","msg":"trace[764525437] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25848; }","duration":"108.606077ms","start":"2026-08-10T23:15:11.182472Z","end":"2026-08-10T23:15:11.291078Z","steps":["trace[764525437] 'agreement among raft nodes before linearized reading' (duration: 108.510355ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:13.203422Z","caller":"traceutil/trace.go:171","msg":"trace[859019625] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"101.01422ms","start":"2026-08-10T23:15:13.102382Z","end":"2026-08-10T23:15:13.203397Z","steps":["trace[859019625] 'process raft request' (duration: 100.292982ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:13.353684Z","caller":"traceutil/trace.go:171","msg":"trace[1001934251] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"146.19172ms","start":"2026-08-10T23:15:13.207462Z","end":"2026-08-10T23:15:13.353654Z","steps":["trace[1001934251] 'process raft request' (duration: 120.049388ms)","trace[1001934251] 'compare' (duration: 25.939087ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:13.597966Z","caller":"traceutil/trace.go:171","msg":"trace[1205858163] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"152.807342ms","start":"2026-08-10T23:15:13.445125Z","end":"2026-08-10T23:15:13.597932Z","steps":["trace[1205858163] 'process raft request' (duration: 152.575917ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:13.598474Z","caller":"traceutil/trace.go:171","msg":"trace[1137662933] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"153.229273ms","start":"2026-08-10T23:15:13.445212Z","end":"2026-08-10T23:15:13.598441Z","steps":["trace[1137662933] 'process raft request' (duration: 153.10804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:14.518869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.722644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:14.518996Z","caller":"traceutil/trace.go:171","msg":"trace[37696849] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25878; }","duration":"175.898968ms","start":"2026-08-10T23:15:14.34308Z","end":"2026-08-10T23:15:14.518979Z","steps":["trace[37696849] 'count revisions from in-memory index tree' (duration: 175.646332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:14.518917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.893875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:14.519193Z","caller":"traceutil/trace.go:171","msg":"trace[1581108195] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25878; }","duration":"118.221073ms","start":"2026-08-10T23:15:14.400947Z","end":"2026-08-10T23:15:14.519168Z","steps":["trace[1581108195] 'count revisions from in-memory index tree' (duration: 117.806912ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:18.192165Z","caller":"traceutil/trace.go:171","msg":"trace[1656835959] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"108.651548ms","start":"2026-08-10T23:15:18.083492Z","end":"2026-08-10T23:15:18.192144Z","steps":["trace[1656835959] 'process raft request' (duration: 108.510154ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:18.376902Z","caller":"traceutil/trace.go:171","msg":"trace[754228936] linearizableReadLoop","detail":"{readStateIndex:26815; appliedIndex:26814; }","duration":"203.095607ms","start":"2026-08-10T23:15:18.173784Z","end":"2026-08-10T23:15:18.37688Z","steps":["trace[754228936] 'read index received' (duration: 18.76534ms)","trace[754228936] 'applied index is now lower than readState.Index' (duration: 184.329337ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:18.376957Z","caller":"traceutil/trace.go:171","msg":"trace[358017121] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"288.74385ms","start":"2026-08-10T23:15:18.08818Z","end":"2026-08-10T23:15:18.376924Z","steps":["trace[358017121] 'process raft request' (duration: 288.544825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:18.377087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.288912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:15:18.377125Z","caller":"traceutil/trace.go:171","msg":"trace[1610263249] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25980; }","duration":"203.379845ms","start":"2026-08-10T23:15:18.173732Z","end":"2026-08-10T23:15:18.377112Z","steps":["trace[1610263249] 'agreement among raft nodes before linearized reading' (duration: 203.293702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:18.377225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.193144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:18.377316Z","caller":"traceutil/trace.go:171","msg":"trace[1737580545] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25980; }","duration":"127.335347ms","start":"2026-08-10T23:15:18.249962Z","end":"2026-08-10T23:15:18.377297Z","steps":["trace[1737580545] 'agreement among raft nodes before linearized reading' (duration: 127.150733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:18.37737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.765912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-10T23:15:18.377483Z","caller":"traceutil/trace.go:171","msg":"trace[1704069485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25980; }","duration":"141.901775ms","start":"2026-08-10T23:15:18.235541Z","end":"2026-08-10T23:15:18.377443Z","steps":["trace[1704069485] 'agreement among raft nodes before linearized reading' (duration: 141.557646ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:18.526898Z","caller":"traceutil/trace.go:171","msg":"trace[1595893163] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"146.504487ms","start":"2026-08-10T23:15:18.380359Z","end":"2026-08-10T23:15:18.526864Z","steps":["trace[1595893163] 'process raft request' (duration: 110.934184ms)","trace[1595893163] 'compare' (duration: 35.290836ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:22.64925Z","caller":"traceutil/trace.go:171","msg":"trace[238814350] linearizableReadLoop","detail":"{readStateIndex:26861; appliedIndex:26860; }","duration":"106.527656ms","start":"2026-08-10T23:15:22.542702Z","end":"2026-08-10T23:15:22.64923Z","steps":["trace[238814350] 'read index received' (duration: 106.394313ms)","trace[238814350] 'applied index is now lower than readState.Index' (duration: 132.313µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:22.649295Z","caller":"traceutil/trace.go:171","msg":"trace[1672693549] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"113.036496ms","start":"2026-08-10T23:15:22.536236Z","end":"2026-08-10T23:15:22.649273Z","steps":["trace[1672693549] 'process raft request' (duration: 112.843121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:22.64945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.734741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-10T23:15:22.649493Z","caller":"traceutil/trace.go:171","msg":"trace[512666418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26025; }","duration":"106.816833ms","start":"2026-08-10T23:15:22.542666Z","end":"2026-08-10T23:15:22.649482Z","steps":["trace[512666418] 'agreement among raft nodes before linearized reading' (duration: 106.67557ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:22.910495Z","caller":"traceutil/trace.go:171","msg":"trace[1113269676] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"358.994025ms","start":"2026-08-10T23:15:22.551478Z","end":"2026-08-10T23:15:22.910472Z","steps":["trace[1113269676] 'process raft request' (duration: 358.805761ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:22.910612Z","caller":"traceutil/trace.go:171","msg":"trace[1098421892] linearizableReadLoop","detail":"{readStateIndex:26863; appliedIndex:26861; }","duration":"261.281235ms","start":"2026-08-10T23:15:22.649317Z","end":"2026-08-10T23:15:22.910599Z","steps":["trace[1098421892] 'read index received' (duration: 260.998488ms)","trace[1098421892] 'applied index is now lower than readState.Index' (duration: 281.437µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:22.910636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:22.551459Z","time spent":"359.103777ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:15:22.910705Z","caller":"traceutil/trace.go:171","msg":"trace[1310831202] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"274.991832ms","start":"2026-08-10T23:15:22.635708Z","end":"2026-08-10T23:15:22.9107Z","steps":["trace[1310831202] 'process raft request' (duration: 274.724945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:22.910895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.552465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-10T23:15:22.910936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.026356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-10T23:15:22.910936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.369663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:22.910947Z","caller":"traceutil/trace.go:171","msg":"trace[345752073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26027; }","duration":"266.626156ms","start":"2026-08-10T23:15:22.644306Z","end":"2026-08-10T23:15:22.910932Z","steps":["trace[345752073] 'agreement among raft nodes before linearized reading' (duration: 266.466442ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:22.911009Z","caller":"traceutil/trace.go:171","msg":"trace[500716209] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:26027; }","duration":"282.114248ms","start":"2026-08-10T23:15:22.628877Z","end":"2026-08-10T23:15:22.910991Z","steps":["trace[500716209] 'agreement among raft nodes before linearized reading' (duration: 281.877492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:22.911008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.63977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-10T23:15:22.911026Z","caller":"traceutil/trace.go:171","msg":"trace[979697982] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26027; }","duration":"341.482325ms","start":"2026-08-10T23:15:22.56952Z","end":"2026-08-10T23:15:22.911003Z","steps":["trace[979697982] 'agreement among raft nodes before linearized reading' (duration: 341.321481ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:22.911074Z","caller":"traceutil/trace.go:171","msg":"trace[274523639] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:26027; }","duration":"280.754483ms","start":"2026-08-10T23:15:22.6303Z","end":"2026-08-10T23:15:22.911054Z","steps":["trace[274523639] 'agreement among raft nodes before linearized reading' (duration: 280.495987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:22.911106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:22.569515Z","time spent":"341.579807ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-08-10T23:15:22.911703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.229868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-10T23:15:22.911788Z","caller":"traceutil/trace.go:171","msg":"trace[639775876] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:26027; }","duration":"284.358942ms","start":"2026-08-10T23:15:22.627408Z","end":"2026-08-10T23:15:22.911767Z","steps":["trace[639775876] 'agreement among raft nodes before linearized reading' (duration: 283.362237ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:22.93428Z","caller":"traceutil/trace.go:171","msg":"trace[1531346938] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"279.865221ms","start":"2026-08-10T23:15:22.654389Z","end":"2026-08-10T23:15:22.934255Z","steps":["trace[1531346938] 'process raft request' (duration: 278.455277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:22.934507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.891527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-10T23:15:22.934557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.541523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-10T23:15:22.934569Z","caller":"traceutil/trace.go:171","msg":"trace[648649069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26029; }","duration":"134.00352ms","start":"2026-08-10T23:15:22.80055Z","end":"2026-08-10T23:15:22.934554Z","steps":["trace[648649069] 'agreement among raft nodes before linearized reading' (duration: 133.740713ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:22.934595Z","caller":"traceutil/trace.go:171","msg":"trace[1284869195] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:26029; }","duration":"237.630015ms","start":"2026-08-10T23:15:22.696955Z","end":"2026-08-10T23:15:22.934585Z","steps":["trace[1284869195] 'agreement among raft nodes before linearized reading' (duration: 237.338407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:22.934681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.602339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:22.934705Z","caller":"traceutil/trace.go:171","msg":"trace[2005163936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26029; }","duration":"205.65854ms","start":"2026-08-10T23:15:22.729039Z","end":"2026-08-10T23:15:22.934698Z","steps":["trace[2005163936] 'agreement among raft nodes before linearized reading' (duration: 205.225619ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:29.686763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22615} {"level":"info","ts":"2026-08-10T23:15:29.787572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22615,"took":"96.787477ms","hash":1101980957,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":46940160,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-08-10T23:15:29.787625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101980957,"revision":22615,"compact-revision":20298} {"level":"info","ts":"2026-08-10T23:15:53.935086Z","caller":"traceutil/trace.go:171","msg":"trace[1601864134] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"132.816062ms","start":"2026-08-10T23:15:53.802244Z","end":"2026-08-10T23:15:53.93506Z","steps":["trace[1601864134] 'process raft request' (duration: 131.53588ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:54.498731Z","caller":"traceutil/trace.go:171","msg":"trace[1401018175] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"116.44774ms","start":"2026-08-10T23:15:54.382262Z","end":"2026-08-10T23:15:54.49871Z","steps":["trace[1401018175] 'process raft request' (duration: 116.328517ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:54.981567Z","caller":"traceutil/trace.go:171","msg":"trace[392894687] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"211.619475ms","start":"2026-08-10T23:15:54.769923Z","end":"2026-08-10T23:15:54.981543Z","steps":["trace[392894687] 'process raft request' (duration: 211.453371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:55.359435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.046642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:15:55.35961Z","caller":"traceutil/trace.go:171","msg":"trace[185164516] linearizableReadLoop","detail":"{readStateIndex:27142; appliedIndex:27140; }","duration":"307.146531ms","start":"2026-08-10T23:15:55.052447Z","end":"2026-08-10T23:15:55.359593Z","steps":["trace[185164516] 'read index received' (duration: 161.779542ms)","trace[185164516] 'applied index is now lower than readState.Index' (duration: 145.365779ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:55.359707Z","caller":"traceutil/trace.go:171","msg":"trace[1863527982] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"330.797303ms","start":"2026-08-10T23:15:55.028899Z","end":"2026-08-10T23:15:55.359697Z","steps":["trace[1863527982] 'process raft request' (duration: 330.641949ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:55.359702Z","caller":"traceutil/trace.go:171","msg":"trace[2071601447] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"332.938755ms","start":"2026-08-10T23:15:55.026741Z","end":"2026-08-10T23:15:55.35968Z","steps":["trace[2071601447] 'process raft request' (duration: 187.549495ms)","trace[2071601447] 'compare' (duration: 144.935149ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:55.359767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:55.028869Z","time spent":"330.863184ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:15:55.359817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:55.026715Z","time spent":"333.035028ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:15:55.359931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.474959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:15:55.359974Z","caller":"traceutil/trace.go:171","msg":"trace[1542257855] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:26297; }","duration":"307.557671ms","start":"2026-08-10T23:15:55.052406Z","end":"2026-08-10T23:15:55.359963Z","steps":["trace[1542257855] 'agreement among raft nodes before linearized reading' (duration: 307.461969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:55.359976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.868146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-10T23:15:55.359997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:55.052386Z","time spent":"307.604013ms","remote":"127.0.0.1:46332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":19,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-08-10T23:15:55.359998Z","caller":"traceutil/trace.go:171","msg":"trace[1471077655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26297; }","duration":"256.912708ms","start":"2026-08-10T23:15:55.103079Z","end":"2026-08-10T23:15:55.359991Z","steps":["trace[1471077655] 'agreement among raft nodes before linearized reading' (duration: 256.823976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:55.360013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.287302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-10T23:15:55.360016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.745473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-10T23:15:55.360064Z","caller":"traceutil/trace.go:171","msg":"trace[2089814131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26297; }","duration":"256.371934ms","start":"2026-08-10T23:15:55.103679Z","end":"2026-08-10T23:15:55.360051Z","steps":["trace[2089814131] 'agreement among raft nodes before linearized reading' (duration: 256.176219ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:55.360086Z","caller":"traceutil/trace.go:171","msg":"trace[1110513512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26297; }","duration":"258.824085ms","start":"2026-08-10T23:15:55.101244Z","end":"2026-08-10T23:15:55.360068Z","steps":["trace[1110513512] 'agreement among raft nodes before linearized reading' (duration: 258.604619ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:55.580828Z","caller":"traceutil/trace.go:171","msg":"trace[1027951677] linearizableReadLoop","detail":"{readStateIndex:27145; appliedIndex:27143; }","duration":"194.25435ms","start":"2026-08-10T23:15:55.386547Z","end":"2026-08-10T23:15:55.580802Z","steps":["trace[1027951677] 'read index received' (duration: 170.548738ms)","trace[1027951677] 'applied index is now lower than readState.Index' (duration: 23.704392ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:55.58089Z","caller":"traceutil/trace.go:171","msg":"trace[1755662701] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"215.366869ms","start":"2026-08-10T23:15:55.365476Z","end":"2026-08-10T23:15:55.580843Z","steps":["trace[1755662701] 'process raft request' (duration: 215.270957ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:55.580832Z","caller":"traceutil/trace.go:171","msg":"trace[2053944291] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"216.047855ms","start":"2026-08-10T23:15:55.36476Z","end":"2026-08-10T23:15:55.580808Z","steps":["trace[2053944291] 'process raft request' (duration: 192.321973ms)","trace[2053944291] 'compare' (duration: 23.501587ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:55.581168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.424074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:55.581253Z","caller":"traceutil/trace.go:171","msg":"trace[1095140205] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:26300; }","duration":"194.715131ms","start":"2026-08-10T23:15:55.38649Z","end":"2026-08-10T23:15:55.581206Z","steps":["trace[1095140205] 'agreement among raft nodes before linearized reading' (duration: 194.444624ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:55.673594Z","caller":"traceutil/trace.go:171","msg":"trace[1285658809] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"173.51429ms","start":"2026-08-10T23:15:55.50005Z","end":"2026-08-10T23:15:55.673564Z","steps":["trace[1285658809] 'process raft request' (duration: 173.338966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:55.673715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.602511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-10T23:15:55.673799Z","caller":"traceutil/trace.go:171","msg":"trace[1682673688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26301; }","duration":"118.734805ms","start":"2026-08-10T23:15:55.555047Z","end":"2026-08-10T23:15:55.673782Z","steps":["trace[1682673688] 'agreement among raft nodes before linearized reading' (duration: 118.481358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:55.673861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.494722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-10T23:15:55.673911Z","caller":"traceutil/trace.go:171","msg":"trace[108721165] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26301; }","duration":"268.577883ms","start":"2026-08-10T23:15:55.405319Z","end":"2026-08-10T23:15:55.673897Z","steps":["trace[108721165] 'agreement among raft nodes before linearized reading' (duration: 268.235155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:55.674058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.772821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-10T23:15:55.674169Z","caller":"traceutil/trace.go:171","msg":"trace[1088885570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26301; }","duration":"286.932586ms","start":"2026-08-10T23:15:55.387216Z","end":"2026-08-10T23:15:55.674149Z","steps":["trace[1088885570] 'agreement among raft nodes before linearized reading' (duration: 286.384112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:55.970678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.147139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:15:55.970803Z","caller":"traceutil/trace.go:171","msg":"trace[2103117523] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"292.484901ms","start":"2026-08-10T23:15:55.678295Z","end":"2026-08-10T23:15:55.97078Z","steps":["trace[2103117523] 'process raft request' (duration: 148.087886ms)","trace[2103117523] 'compare' (duration: 143.794291ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:56.000149Z","caller":"traceutil/trace.go:171","msg":"trace[1029759355] linearizableReadLoop","detail":"{readStateIndex:27149; appliedIndex:27146; }","duration":"271.123867ms","start":"2026-08-10T23:15:55.729005Z","end":"2026-08-10T23:15:56.000129Z","steps":["trace[1029759355] 'read index received' (duration: 97.354101ms)","trace[1029759355] 'applied index is now lower than readState.Index' (duration: 173.768476ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:56.000197Z","caller":"traceutil/trace.go:171","msg":"trace[999511432] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"321.152286ms","start":"2026-08-10T23:15:55.679009Z","end":"2026-08-10T23:15:56.000161Z","steps":["trace[999511432] 'process raft request' (duration: 320.861379ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:56.000239Z","caller":"traceutil/trace.go:171","msg":"trace[1645626284] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"245.634522ms","start":"2026-08-10T23:15:55.754597Z","end":"2026-08-10T23:15:56.000231Z","steps":["trace[1645626284] 'process raft request' (duration: 245.505489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:56.000362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:55.678985Z","time spent":"321.27867ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:15:56.000372Z","caller":"traceutil/trace.go:171","msg":"trace[318038589] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"252.454488ms","start":"2026-08-10T23:15:55.74788Z","end":"2026-08-10T23:15:56.000335Z","steps":["trace[318038589] 'process raft request' (duration: 252.198702ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:56.000453Z","caller":"traceutil/trace.go:171","msg":"trace[1154432560] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"320.171312ms","start":"2026-08-10T23:15:55.680257Z","end":"2026-08-10T23:15:56.000429Z","steps":["trace[1154432560] 'process raft request' (duration: 319.792022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:56.000479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.458905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:15:56.000547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:15:55.680206Z","time spent":"320.293454ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:15:56.000556Z","caller":"traceutil/trace.go:171","msg":"trace[708716306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26306; }","duration":"271.586108ms","start":"2026-08-10T23:15:55.72895Z","end":"2026-08-10T23:15:56.000537Z","steps":["trace[708716306] 'agreement among raft nodes before linearized reading' (duration: 271.439275ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:56.022225Z","caller":"traceutil/trace.go:171","msg":"trace[1936597575] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"109.109689ms","start":"2026-08-10T23:15:55.913099Z","end":"2026-08-10T23:15:56.022209Z","steps":["trace[1936597575] 'process raft request' (duration: 108.964255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:56.022593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.550511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:17939"} {"level":"info","ts":"2026-08-10T23:15:56.022663Z","caller":"traceutil/trace.go:171","msg":"trace[706865640] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26308; }","duration":"197.682654ms","start":"2026-08-10T23:15:55.824965Z","end":"2026-08-10T23:15:56.022647Z","steps":["trace[706865640] 'agreement among raft nodes before linearized reading' (duration: 197.453449ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:56.674917Z","caller":"traceutil/trace.go:171","msg":"trace[871305430] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"166.927439ms","start":"2026-08-10T23:15:56.507941Z","end":"2026-08-10T23:15:56.674868Z","steps":["trace[871305430] 'process raft request' (duration: 166.608322ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:57.511847Z","caller":"traceutil/trace.go:171","msg":"trace[1146277484] linearizableReadLoop","detail":"{readStateIndex:27156; appliedIndex:27154; }","duration":"141.585817ms","start":"2026-08-10T23:15:57.370238Z","end":"2026-08-10T23:15:57.511823Z","steps":["trace[1146277484] 'read index received' (duration: 22.562154ms)","trace[1146277484] 'applied index is now lower than readState.Index' (duration: 119.022633ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:57.512083Z","caller":"traceutil/trace.go:171","msg":"trace[547102304] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"146.474188ms","start":"2026-08-10T23:15:57.365562Z","end":"2026-08-10T23:15:57.512036Z","steps":["trace[547102304] 'process raft request' (duration: 146.101298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:57.512168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.461212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-10T23:15:57.512205Z","caller":"traceutil/trace.go:171","msg":"trace[599660241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26311; }","duration":"123.526033ms","start":"2026-08-10T23:15:57.38867Z","end":"2026-08-10T23:15:57.512196Z","steps":["trace[599660241] 'agreement among raft nodes before linearized reading' (duration: 123.40408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:57.512299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.880845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:57.512423Z","caller":"traceutil/trace.go:171","msg":"trace[1525995693] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26311; }","duration":"142.308645ms","start":"2026-08-10T23:15:57.370095Z","end":"2026-08-10T23:15:57.512404Z","steps":["trace[1525995693] 'agreement among raft nodes before linearized reading' (duration: 141.929866ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:57.771136Z","caller":"traceutil/trace.go:171","msg":"trace[704900897] linearizableReadLoop","detail":"{readStateIndex:27157; appliedIndex:27156; }","duration":"195.591953ms","start":"2026-08-10T23:15:57.575529Z","end":"2026-08-10T23:15:57.771121Z","steps":["trace[704900897] 'read index received' (duration: 101.996225ms)","trace[704900897] 'applied index is now lower than readState.Index' (duration: 93.595168ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:57.7712Z","caller":"traceutil/trace.go:171","msg":"trace[1320486035] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"251.761032ms","start":"2026-08-10T23:15:57.519433Z","end":"2026-08-10T23:15:57.771194Z","steps":["trace[1320486035] 'process raft request' (duration: 158.098152ms)","trace[1320486035] 'compare' (duration: 93.509156ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:57.771299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.760827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:57.77132Z","caller":"traceutil/trace.go:171","msg":"trace[1101007946] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26312; }","duration":"195.793408ms","start":"2026-08-10T23:15:57.57552Z","end":"2026-08-10T23:15:57.771314Z","steps":["trace[1101007946] 'agreement among raft nodes before linearized reading' (duration: 195.745947ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:57.784457Z","caller":"traceutil/trace.go:171","msg":"trace[597052320] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"103.817739ms","start":"2026-08-10T23:15:57.680608Z","end":"2026-08-10T23:15:57.784425Z","steps":["trace[597052320] 'process raft request' (duration: 103.637225ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:57.842579Z","caller":"traceutil/trace.go:171","msg":"trace[2000154742] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"135.751344ms","start":"2026-08-10T23:15:57.706787Z","end":"2026-08-10T23:15:57.842538Z","steps":["trace[2000154742] 'process raft request' (duration: 135.21592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:57.84289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.84551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:57.842932Z","caller":"traceutil/trace.go:171","msg":"trace[715901230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26314; }","duration":"114.916572ms","start":"2026-08-10T23:15:57.728006Z","end":"2026-08-10T23:15:57.842922Z","steps":["trace[715901230] 'agreement among raft nodes before linearized reading' (duration: 114.831759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:57.843243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.371504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:57.843354Z","caller":"traceutil/trace.go:171","msg":"trace[219460923] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26314; }","duration":"236.521078ms","start":"2026-08-10T23:15:57.606804Z","end":"2026-08-10T23:15:57.843325Z","steps":["trace[219460923] 'agreement among raft nodes before linearized reading' (duration: 236.364084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:57.843515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.767419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-10T23:15:57.843556Z","caller":"traceutil/trace.go:171","msg":"trace[889754201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26314; }","duration":"259.853331ms","start":"2026-08-10T23:15:57.583693Z","end":"2026-08-10T23:15:57.843546Z","steps":["trace[889754201] 'agreement among raft nodes before linearized reading' (duration: 259.710097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:57.843701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.794213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-10T23:15:57.843796Z","caller":"traceutil/trace.go:171","msg":"trace[1774764646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26314; }","duration":"247.927578ms","start":"2026-08-10T23:15:57.595844Z","end":"2026-08-10T23:15:57.843772Z","steps":["trace[1774764646] 'agreement among raft nodes before linearized reading' (duration: 247.684442ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:57.989354Z","caller":"traceutil/trace.go:171","msg":"trace[382997805] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"142.667653ms","start":"2026-08-10T23:15:57.846655Z","end":"2026-08-10T23:15:57.989323Z","steps":["trace[382997805] 'process raft request' (duration: 77.445912ms)","trace[382997805] 'compare' (duration: 64.899933ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:57.990219Z","caller":"traceutil/trace.go:171","msg":"trace[2136979670] linearizableReadLoop","detail":"{readStateIndex:27161; appliedIndex:27159; }","duration":"100.577199ms","start":"2026-08-10T23:15:57.889622Z","end":"2026-08-10T23:15:57.990199Z","steps":["trace[2136979670] 'read index received' (duration: 34.494267ms)","trace[2136979670] 'applied index is now lower than readState.Index' (duration: 66.081982ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:57.990255Z","caller":"traceutil/trace.go:171","msg":"trace[1761225787] transaction","detail":"{read_only:false; response_revision:26316; number_of_response:1; }","duration":"142.879118ms","start":"2026-08-10T23:15:57.84736Z","end":"2026-08-10T23:15:57.99024Z","steps":["trace[1761225787] 'process raft request' (duration: 142.696984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:57.990347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.704003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:57.990376Z","caller":"traceutil/trace.go:171","msg":"trace[487739355] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:26316; }","duration":"100.790104ms","start":"2026-08-10T23:15:57.889578Z","end":"2026-08-10T23:15:57.990368Z","steps":["trace[487739355] 'agreement among raft nodes before linearized reading' (duration: 100.717742ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:58.237898Z","caller":"traceutil/trace.go:171","msg":"trace[439035231] linearizableReadLoop","detail":"{readStateIndex:27167; appliedIndex:27166; }","duration":"196.120195ms","start":"2026-08-10T23:15:58.041754Z","end":"2026-08-10T23:15:58.237874Z","steps":["trace[439035231] 'read index received' (duration: 190.852746ms)","trace[439035231] 'applied index is now lower than readState.Index' (duration: 5.266439ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:15:58.238067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.333191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:15:58.238128Z","caller":"traceutil/trace.go:171","msg":"trace[389862421] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26322; }","duration":"196.392113ms","start":"2026-08-10T23:15:58.041706Z","end":"2026-08-10T23:15:58.238098Z","steps":["trace[389862421] 'agreement among raft nodes before linearized reading' (duration: 196.25781ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:58.23863Z","caller":"traceutil/trace.go:171","msg":"trace[1662142826] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"210.406267ms","start":"2026-08-10T23:15:58.028192Z","end":"2026-08-10T23:15:58.238598Z","steps":["trace[1662142826] 'process raft request' (duration: 204.320418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:58.373751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.327218ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-08-10T23:15:59.033622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.015775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:15:59.033684Z","caller":"traceutil/trace.go:171","msg":"trace[51830190] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26323; }","duration":"194.126217ms","start":"2026-08-10T23:15:58.839544Z","end":"2026-08-10T23:15:59.03367Z","steps":["trace[51830190] 'count revisions from in-memory index tree' (duration: 193.8663ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:59.511597Z","caller":"traceutil/trace.go:171","msg":"trace[208634627] linearizableReadLoop","detail":"{readStateIndex:27170; appliedIndex:27169; }","duration":"108.636837ms","start":"2026-08-10T23:15:59.402933Z","end":"2026-08-10T23:15:59.51157Z","steps":["trace[208634627] 'read index received' (duration: 108.419071ms)","trace[208634627] 'applied index is now lower than readState.Index' (duration: 216.636µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:15:59.511685Z","caller":"traceutil/trace.go:171","msg":"trace[1522064801] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"112.82287ms","start":"2026-08-10T23:15:59.398835Z","end":"2026-08-10T23:15:59.511658Z","steps":["trace[1522064801] 'process raft request' (duration: 112.529173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:15:59.511757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.804801ms","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:8"} {"level":"info","ts":"2026-08-10T23:15:59.511801Z","caller":"traceutil/trace.go:171","msg":"trace[1070754044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26324; }","duration":"108.867773ms","start":"2026-08-10T23:15:59.402919Z","end":"2026-08-10T23:15:59.511787Z","steps":["trace[1070754044] 'agreement among raft nodes before linearized reading' (duration: 108.776041ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:59.962353Z","caller":"traceutil/trace.go:171","msg":"trace[1093498261] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"172.930505ms","start":"2026-08-10T23:15:59.789397Z","end":"2026-08-10T23:15:59.962328Z","steps":["trace[1093498261] 'process raft request' (duration: 172.777112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.166957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.831578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:16:00.1672Z","caller":"traceutil/trace.go:171","msg":"trace[1204465154] linearizableReadLoop","detail":"{readStateIndex:27174; appliedIndex:27173; }","duration":"168.463067ms","start":"2026-08-10T23:15:59.998724Z","end":"2026-08-10T23:16:00.167187Z","steps":["trace[1204465154] 'read index received' (duration: 49.361803ms)","trace[1204465154] 'applied index is now lower than readState.Index' (duration: 119.100504ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:16:00.167233Z","caller":"traceutil/trace.go:171","msg":"trace[1617760317] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"168.842326ms","start":"2026-08-10T23:15:59.998354Z","end":"2026-08-10T23:16:00.167196Z","steps":["trace[1617760317] 'process raft request' (duration: 49.685241ms)","trace[1617760317] 'compare' (duration: 118.274374ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:16:00.167348Z","caller":"traceutil/trace.go:171","msg":"trace[1710599246] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"164.805966ms","start":"2026-08-10T23:16:00.002529Z","end":"2026-08-10T23:16:00.167335Z","steps":["trace[1710599246] 'process raft request' (duration: 164.591071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.167412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.683132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-10T23:16:00.167451Z","caller":"traceutil/trace.go:171","msg":"trace[1425677713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26329; }","duration":"168.750134ms","start":"2026-08-10T23:15:59.998692Z","end":"2026-08-10T23:16:00.167442Z","steps":["trace[1425677713] 'agreement among raft nodes before linearized reading' (duration: 168.59099ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:00.1676Z","caller":"traceutil/trace.go:171","msg":"trace[703484559] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"152.747532ms","start":"2026-08-10T23:16:00.014844Z","end":"2026-08-10T23:16:00.167592Z","steps":["trace[703484559] 'process raft request' (duration: 152.461634ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:00.167769Z","caller":"traceutil/trace.go:171","msg":"trace[538426773] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"146.307074ms","start":"2026-08-10T23:16:00.021448Z","end":"2026-08-10T23:16:00.167756Z","steps":["trace[538426773] 'process raft request' (duration: 146.114689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.167783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.178742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-10T23:16:00.167873Z","caller":"traceutil/trace.go:171","msg":"trace[1511421049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26331; }","duration":"151.291635ms","start":"2026-08-10T23:16:00.016573Z","end":"2026-08-10T23:16:00.167865Z","steps":["trace[1511421049] 'agreement among raft nodes before linearized reading' (duration: 151.153412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.168147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.638932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-10T23:16:00.168175Z","caller":"traceutil/trace.go:171","msg":"trace[2104636972] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26331; }","duration":"146.675143ms","start":"2026-08-10T23:16:00.02149Z","end":"2026-08-10T23:16:00.168165Z","steps":["trace[2104636972] 'agreement among raft nodes before linearized reading' (duration: 146.612091ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:00.516575Z","caller":"traceutil/trace.go:171","msg":"trace[1613918351] linearizableReadLoop","detail":"{readStateIndex:27180; appliedIndex:27179; }","duration":"284.414633ms","start":"2026-08-10T23:16:00.232142Z","end":"2026-08-10T23:16:00.516557Z","steps":["trace[1613918351] 'read index received' (duration: 284.382782ms)","trace[1613918351] 'applied index is now lower than readState.Index' (duration: 30.871µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:16:00.516639Z","caller":"traceutil/trace.go:171","msg":"trace[1277772418] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"344.790696ms","start":"2026-08-10T23:16:00.171811Z","end":"2026-08-10T23:16:00.516602Z","steps":["trace[1277772418] 'process raft request' (duration: 344.585371ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:00.516664Z","caller":"traceutil/trace.go:171","msg":"trace[1637010427] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"344.52299ms","start":"2026-08-10T23:16:00.172113Z","end":"2026-08-10T23:16:00.516636Z","steps":["trace[1637010427] 'process raft request' (duration: 344.409817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.516762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.603507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-08-10T23:16:00.516796Z","caller":"traceutil/trace.go:171","msg":"trace[1432048153] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26334; }","duration":"284.683789ms","start":"2026-08-10T23:16:00.232101Z","end":"2026-08-10T23:16:00.516785Z","steps":["trace[1432048153] 'agreement among raft nodes before linearized reading' (duration: 284.545506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.5168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:16:00.172105Z","time spent":"344.624692ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:16:00.516816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:16:00.171794Z","time spent":"344.93284ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:16:00.527128Z","caller":"traceutil/trace.go:171","msg":"trace[1407489943] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"281.601125ms","start":"2026-08-10T23:16:00.245507Z","end":"2026-08-10T23:16:00.527108Z","steps":["trace[1407489943] 'process raft request' (duration: 281.322428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.527365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.994244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:16:00.527423Z","caller":"traceutil/trace.go:171","msg":"trace[968573278] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26335; }","duration":"183.101926ms","start":"2026-08-10T23:16:00.344305Z","end":"2026-08-10T23:16:00.527407Z","steps":["trace[968573278] 'agreement among raft nodes before linearized reading' (duration: 182.986654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.527473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.068268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:16:00.527546Z","caller":"traceutil/trace.go:171","msg":"trace[913713826] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:26335; }","duration":"212.177651ms","start":"2026-08-10T23:16:00.315336Z","end":"2026-08-10T23:16:00.527514Z","steps":["trace[913713826] 'agreement among raft nodes before linearized reading' (duration: 212.075318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.52759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.582327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:16:00.52761Z","caller":"traceutil/trace.go:171","msg":"trace[596422399] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26335; }","duration":"119.624768ms","start":"2026-08-10T23:16:00.407978Z","end":"2026-08-10T23:16:00.527603Z","steps":["trace[596422399] 'agreement among raft nodes before linearized reading' (duration: 119.587837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.527705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.993455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:16:00.527724Z","caller":"traceutil/trace.go:171","msg":"trace[768138850] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:26335; }","duration":"155.013426ms","start":"2026-08-10T23:16:00.372706Z","end":"2026-08-10T23:16:00.52772Z","steps":["trace[768138850] 'agreement among raft nodes before linearized reading' (duration: 154.981835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:00.527826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.040605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:16:00.527857Z","caller":"traceutil/trace.go:171","msg":"trace[85270566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26335; }","duration":"159.110607ms","start":"2026-08-10T23:16:00.368739Z","end":"2026-08-10T23:16:00.52785Z","steps":["trace[85270566] 'agreement among raft nodes before linearized reading' (duration: 159.060566ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:01.697486Z","caller":"traceutil/trace.go:171","msg":"trace[291500060] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"151.724936ms","start":"2026-08-10T23:16:01.54572Z","end":"2026-08-10T23:16:01.697445Z","steps":["trace[291500060] 'process raft request' (duration: 151.435409ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:19.848169Z","caller":"traceutil/trace.go:171","msg":"trace[457728179] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"128.467945ms","start":"2026-08-10T23:16:19.719677Z","end":"2026-08-10T23:16:19.848145Z","steps":["trace[457728179] 'process raft request' (duration: 128.334502ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:25.559335Z","caller":"traceutil/trace.go:171","msg":"trace[219923444] linearizableReadLoop","detail":"{readStateIndex:27376; appliedIndex:27375; }","duration":"257.37673ms","start":"2026-08-10T23:16:25.301922Z","end":"2026-08-10T23:16:25.559299Z","steps":["trace[219923444] 'read index received' (duration: 257.140073ms)","trace[219923444] 'applied index is now lower than readState.Index' (duration: 235.557µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:16:25.559366Z","caller":"traceutil/trace.go:171","msg":"trace[444677983] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"294.049181ms","start":"2026-08-10T23:16:25.265284Z","end":"2026-08-10T23:16:25.559334Z","steps":["trace[444677983] 'process raft request' (duration: 293.821794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:25.559704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.752529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:16:25.560163Z","caller":"traceutil/trace.go:171","msg":"trace[1221173541] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:26525; }","duration":"258.191559ms","start":"2026-08-10T23:16:25.301873Z","end":"2026-08-10T23:16:25.560065Z","steps":["trace[1221173541] 'agreement among raft nodes before linearized reading' (duration: 257.699977ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:25.732626Z","caller":"traceutil/trace.go:171","msg":"trace[239116366] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"159.801334ms","start":"2026-08-10T23:16:25.572796Z","end":"2026-08-10T23:16:25.732597Z","steps":["trace[239116366] 'process raft request' (duration: 159.65431ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:25.998109Z","caller":"traceutil/trace.go:171","msg":"trace[35990929] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"128.719621ms","start":"2026-08-10T23:16:25.86936Z","end":"2026-08-10T23:16:25.998079Z","steps":["trace[35990929] 'process raft request' (duration: 128.588398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:26.250498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.437488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:16:26.250631Z","caller":"traceutil/trace.go:171","msg":"trace[1593078432] linearizableReadLoop","detail":"{readStateIndex:27380; appliedIndex:27378; }","duration":"249.713461ms","start":"2026-08-10T23:16:26.000904Z","end":"2026-08-10T23:16:26.250617Z","steps":["trace[1593078432] 'read index received' (duration: 120.096528ms)","trace[1593078432] 'applied index is now lower than readState.Index' (duration: 129.615833ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:16:26.250691Z","caller":"traceutil/trace.go:171","msg":"trace[427486098] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"321.948096ms","start":"2026-08-10T23:16:25.928735Z","end":"2026-08-10T23:16:26.250683Z","steps":["trace[427486098] 'process raft request' (duration: 192.250611ms)","trace[427486098] 'compare' (duration: 129.345916ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:16:26.250729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:16:25.928703Z","time spent":"322.003807ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:16:26.251197Z","caller":"traceutil/trace.go:171","msg":"trace[1378717513] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"274.242634ms","start":"2026-08-10T23:16:25.976945Z","end":"2026-08-10T23:16:26.251188Z","steps":["trace[1378717513] 'process raft request' (duration: 273.628968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:26.253666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.732466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2380604"} {"level":"info","ts":"2026-08-10T23:16:26.253801Z","caller":"traceutil/trace.go:171","msg":"trace[191685387] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26529; }","duration":"252.907469ms","start":"2026-08-10T23:16:26.000861Z","end":"2026-08-10T23:16:26.253768Z","steps":["trace[191685387] 'agreement among raft nodes before linearized reading' (duration: 250.024058ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:26.258569Z","caller":"traceutil/trace.go:171","msg":"trace[1747291695] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"191.173364ms","start":"2026-08-10T23:16:26.067373Z","end":"2026-08-10T23:16:26.258546Z","steps":["trace[1747291695] 'process raft request' (duration: 191.00477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:26.258894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.033621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-10T23:16:26.258936Z","caller":"traceutil/trace.go:171","msg":"trace[940141228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26531; }","duration":"191.090942ms","start":"2026-08-10T23:16:26.067834Z","end":"2026-08-10T23:16:26.258925Z","steps":["trace[940141228] 'agreement among raft nodes before linearized reading' (duration: 190.920558ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:26.258859Z","caller":"traceutil/trace.go:171","msg":"trace[1133975711] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"142.707564ms","start":"2026-08-10T23:16:26.116109Z","end":"2026-08-10T23:16:26.258817Z","steps":["trace[1133975711] 'process raft request' (duration: 142.390227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:16:26.259023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.615168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T23:16:26.259043Z","caller":"traceutil/trace.go:171","msg":"trace[1341748942] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26531; }","duration":"106.69043ms","start":"2026-08-10T23:16:26.152346Z","end":"2026-08-10T23:16:26.259036Z","steps":["trace[1341748942] 'agreement among raft nodes before linearized reading' (duration: 106.646729ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:26.408755Z","caller":"traceutil/trace.go:171","msg":"trace[1190034238] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"107.91857ms","start":"2026-08-10T23:16:26.30078Z","end":"2026-08-10T23:16:26.408698Z","steps":["trace[1190034238] 'process raft request' (duration: 107.799797ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:16:26.409183Z","caller":"traceutil/trace.go:171","msg":"trace[1925353278] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"115.742971ms","start":"2026-08-10T23:16:26.293426Z","end":"2026-08-10T23:16:26.409169Z","steps":["trace[1925353278] 'process raft request' (duration: 79.697176ms)","trace[1925353278] 'compare' (duration: 35.259566ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:20:29.692105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26093} {"level":"info","ts":"2026-08-10T23:20:29.841082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26093,"took":"146.068717ms","hash":3864346221,"current-db-size-bytes":59432960,"current-db-size":"59 MB","current-db-size-in-use-bytes":37765120,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-10T23:20:29.841126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3864346221,"revision":26093,"compact-revision":22615} {"level":"info","ts":"2026-08-10T23:20:37.84699Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:20:37.851624Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-10T23:20:37.851818Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-10T23:25:29.705578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29027} {"level":"info","ts":"2026-08-10T23:25:29.846654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29027,"took":"136.402888ms","hash":886017691,"current-db-size-bytes":59432960,"current-db-size":"59 MB","current-db-size-in-use-bytes":32858112,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-10T23:25:29.846809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":886017691,"revision":29027,"compact-revision":26093} {"level":"info","ts":"2026-08-10T23:30:29.711328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31299} {"level":"info","ts":"2026-08-10T23:30:29.842378Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31299,"took":"124.441877ms","hash":3151613136,"current-db-size-bytes":59432960,"current-db-size":"59 MB","current-db-size-in-use-bytes":33026048,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-10T23:30:29.842449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3151613136,"revision":31299,"compact-revision":29027} {"level":"info","ts":"2026-08-10T23:35:29.718417Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33613} {"level":"info","ts":"2026-08-10T23:35:29.872742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33613,"took":"150.667541ms","hash":3997436977,"current-db-size-bytes":60477440,"current-db-size":"60 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-10T23:35:29.872813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3997436977,"revision":33613,"compact-revision":31299} {"level":"info","ts":"2026-08-10T23:40:29.724345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35883} {"level":"info","ts":"2026-08-10T23:40:29.871608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35883,"took":"142.367747ms","hash":2999681093,"current-db-size-bytes":60477440,"current-db-size":"60 MB","current-db-size-in-use-bytes":31965184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-10T23:40:29.871673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2999681093,"revision":35883,"compact-revision":33613} {"level":"info","ts":"2026-08-10T23:42:01.861024Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9133559b3198d4ec","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:42:01.865216Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-10T23:42:01.865339Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-10T23:45:29.731412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38086} {"level":"info","ts":"2026-08-10T23:45:29.858345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38086,"took":"121.778941ms","hash":779812167,"current-db-size-bytes":60477440,"current-db-size":"60 MB","current-db-size-in-use-bytes":30982144,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:45:29.858423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779812167,"revision":38086,"compact-revision":35883} {"level":"info","ts":"2026-08-10T23:50:29.737421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40339} {"level":"info","ts":"2026-08-10T23:50:29.866267Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40339,"took":"125.516853ms","hash":4109059835,"current-db-size-bytes":60477440,"current-db-size":"60 MB","current-db-size-in-use-bytes":30019584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T23:50:29.866323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4109059835,"revision":40339,"compact-revision":38086}