{"level":"warn","ts":"2026-08-21T02:29:14.207152Z","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-21T02:29:14.207246Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90: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-21T02:29:14.207333Z","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-21T02:29:14.207348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-08-21T02:29:14.207365Z","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-21T02:29:14.20807Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-08-21T02:29:14.208169Z","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.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90: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-21T02:29:14.213935Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.459359ms"} {"level":"info","ts":"2026-08-21T02:29:14.218813Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-08-21T02:29:14.218902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-08-21T02:29:14.218924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-08-21T02:29:14.218953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-21T02:29:14.218966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-08-21T02:29:14.219005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-08-21T02:29:14.229658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-21T02:29:14.231757Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-21T02:29:14.232833Z","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-21T02:29:14.236972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-21T02:29:14.237208Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-21T02:29:14.237583Z","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-21T02:29:14.23769Z","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-21T02:29:14.237716Z","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-21T02:29:14.238472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-08-21T02:29:14.238582Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-08-21T02:29:14.244596Z","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-21T02:29:14.244774Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-08-21T02:29:14.244834Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-08-21T02:29:14.245011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-21T02:29:14.245091Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-21T02:29:15.219369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-08-21T02:29:15.21943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-21T02:29:15.219474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-08-21T02:29:15.219494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-08-21T02:29:15.219505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-08-21T02:29:15.219522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-08-21T02:29:15.219544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-08-21T02:29:15.220695Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-08-21T02:29:15.220769Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:29:15.220752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:29:15.220967Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:15.221175Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-21T02:29:15.22128Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-21T02:29:15.221948Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:15.222052Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:15.22208Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:29:15.224072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-08-21T02:29:15.224078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-21T02:29:32.060738Z","caller":"traceutil/trace.go:171","msg":"trace[1126567031] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"129.50782ms","start":"2026-08-21T02:29:31.930984Z","end":"2026-08-21T02:29:32.060492Z","steps":["trace[1126567031] 'process raft request' (duration: 129.347507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:29:32.360921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.981801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-21T02:29:32.361075Z","caller":"traceutil/trace.go:171","msg":"trace[1277650473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"180.183865ms","start":"2026-08-21T02:29:32.180871Z","end":"2026-08-21T02:29:32.361055Z","steps":["trace[1277650473] 'range keys from in-memory index tree' (duration: 179.831027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:29:32.361072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.982142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:29:32.361187Z","caller":"traceutil/trace.go:171","msg":"trace[2093846954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"214.223267ms","start":"2026-08-21T02:29:32.146939Z","end":"2026-08-21T02:29:32.361163Z","steps":["trace[2093846954] 'range keys from in-memory index tree' (duration: 213.859399ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:32.509389Z","caller":"traceutil/trace.go:171","msg":"trace[1936286225] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"144.095388ms","start":"2026-08-21T02:29:32.365271Z","end":"2026-08-21T02:29:32.509366Z","steps":["trace[1936286225] 'process raft request' (duration: 143.903864ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.024179Z","caller":"traceutil/trace.go:171","msg":"trace[557204886] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"161.530169ms","start":"2026-08-21T02:29:55.862627Z","end":"2026-08-21T02:29:56.024157Z","steps":["trace[557204886] 'process raft request' (duration: 161.401315ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029376Z","caller":"traceutil/trace.go:171","msg":"trace[1609798461] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"154.680439ms","start":"2026-08-21T02:29:55.874676Z","end":"2026-08-21T02:29:56.029356Z","steps":["trace[1609798461] 'process raft request' (duration: 154.275311ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029518Z","caller":"traceutil/trace.go:171","msg":"trace[917221690] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"166.779973ms","start":"2026-08-21T02:29:55.862726Z","end":"2026-08-21T02:29:56.029506Z","steps":["trace[917221690] 'process raft request' (duration: 166.125559ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029591Z","caller":"traceutil/trace.go:171","msg":"trace[1281303102] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"140.817297ms","start":"2026-08-21T02:29:55.888766Z","end":"2026-08-21T02:29:56.029584Z","steps":["trace[1281303102] 'process raft request' (duration: 140.281476ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029617Z","caller":"traceutil/trace.go:171","msg":"trace[1708222300] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"143.278671ms","start":"2026-08-21T02:29:55.886301Z","end":"2026-08-21T02:29:56.02958Z","steps":["trace[1708222300] 'process raft request' (duration: 142.692949ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029662Z","caller":"traceutil/trace.go:171","msg":"trace[1361533674] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"137.292761ms","start":"2026-08-21T02:29:55.89236Z","end":"2026-08-21T02:29:56.029653Z","steps":["trace[1361533674] 'process raft request' (duration: 137.145768ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029678Z","caller":"traceutil/trace.go:171","msg":"trace[2055612309] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"134.326475ms","start":"2026-08-21T02:29:55.895347Z","end":"2026-08-21T02:29:56.029674Z","steps":["trace[2055612309] 'process raft request' (duration: 134.232423ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029653Z","caller":"traceutil/trace.go:171","msg":"trace[1664640604] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"138.570628ms","start":"2026-08-21T02:29:55.891073Z","end":"2026-08-21T02:29:56.029644Z","steps":["trace[1664640604] 'process raft request' (duration: 138.020376ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029789Z","caller":"traceutil/trace.go:171","msg":"trace[1394833707] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"132.60749ms","start":"2026-08-21T02:29:55.89717Z","end":"2026-08-21T02:29:56.029778Z","steps":["trace[1394833707] 'process raft request' (duration: 132.451845ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029857Z","caller":"traceutil/trace.go:171","msg":"trace[1220583703] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"105.57369ms","start":"2026-08-21T02:29:55.924256Z","end":"2026-08-21T02:29:56.02983Z","steps":["trace[1220583703] 'process raft request' (duration: 105.493938ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:29:56.029891Z","caller":"traceutil/trace.go:171","msg":"trace[1646194487] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"119.36222ms","start":"2026-08-21T02:29:55.910478Z","end":"2026-08-21T02:29:56.02984Z","steps":["trace[1646194487] 'process raft request' (duration: 119.208066ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:30:02.08995Z","caller":"traceutil/trace.go:171","msg":"trace[1474627735] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"159.393212ms","start":"2026-08-21T02:30:01.930527Z","end":"2026-08-21T02:30:02.08992Z","steps":["trace[1474627735] 'process raft request' (duration: 157.644123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:02.911418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.527794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:30:02.911623Z","caller":"traceutil/trace.go:171","msg":"trace[1290320975] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"675.847181ms","start":"2026-08-21T02:30:02.235719Z","end":"2026-08-21T02:30:02.911566Z","steps":["trace[1290320975] 'read index received' (duration: 106.365357ms)","trace[1290320975] 'applied index is now lower than readState.Index' (duration: 569.479844ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:30:02.911634Z","caller":"traceutil/trace.go:171","msg":"trace[932503639] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"678.130102ms","start":"2026-08-21T02:30:02.233462Z","end":"2026-08-21T02:30:02.911592Z","steps":["trace[932503639] 'process raft request' (duration: 108.577886ms)","trace[932503639] 'compare' (duration: 568.284538ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:30:02.911787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:02.23344Z","time spent":"678.264734ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:30:02.91185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.118768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6t9st\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-21T02:30:02.911899Z","caller":"traceutil/trace.go:171","msg":"trace[1902021240] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6t9st; range_end:; response_count:1; response_revision:643; }","duration":"676.183799ms","start":"2026-08-21T02:30:02.2357Z","end":"2026-08-21T02:30:02.911884Z","steps":["trace[1902021240] 'agreement among raft nodes before linearized reading' (duration: 676.001795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:02.911958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:02.235692Z","time spent":"676.25285ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-6t9st\" "} {"level":"info","ts":"2026-08-21T02:30:03.091448Z","caller":"traceutil/trace.go:171","msg":"trace[1311919642] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"179.755616ms","start":"2026-08-21T02:30:02.911666Z","end":"2026-08-21T02:30:03.091422Z","steps":["trace[1311919642] 'read index received' (duration: 178.954799ms)","trace[1311919642] 'applied index is now lower than readState.Index' (duration: 799.487µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:30:03.091501Z","caller":"traceutil/trace.go:171","msg":"trace[1493251063] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"459.855827ms","start":"2026-08-21T02:30:02.631609Z","end":"2026-08-21T02:30:03.091465Z","steps":["trace[1493251063] 'process raft request' (duration: 459.248034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:03.091713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:02.631554Z","time spent":"460.01072ms","remote":"127.0.0.1:54678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:30:03.091725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.66401ms","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-21T02:30:03.091869Z","caller":"traceutil/trace.go:171","msg":"trace[618809447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"435.866824ms","start":"2026-08-21T02:30:02.655969Z","end":"2026-08-21T02:30:03.091835Z","steps":["trace[618809447] 'agreement among raft nodes before linearized reading' (duration: 435.572967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:30:03.091925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:30:02.655947Z","time spent":"435.966326ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-21T02:30:03.10284Z","caller":"traceutil/trace.go:171","msg":"trace[1008070301] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"176.473514ms","start":"2026-08-21T02:30:02.926346Z","end":"2026-08-21T02:30:03.10282Z","steps":["trace[1008070301] 'process raft request' (duration: 175.943883ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:29.652425Z","caller":"traceutil/trace.go:171","msg":"trace[1585105618] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"178.58643ms","start":"2026-08-21T02:31:29.473793Z","end":"2026-08-21T02:31:29.652379Z","steps":["trace[1585105618] 'process raft request' (duration: 178.179641ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:19.79077Z","caller":"traceutil/trace.go:171","msg":"trace[1764865747] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"182.406685ms","start":"2026-08-21T02:32:19.608329Z","end":"2026-08-21T02:32:19.790736Z","steps":["trace[1764865747] 'read index received' (duration: 181.382931ms)","trace[1764865747] 'applied index is now lower than readState.Index' (duration: 1.018933ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:19.790868Z","caller":"traceutil/trace.go:171","msg":"trace[1872411396] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"313.68458ms","start":"2026-08-21T02:32:19.477146Z","end":"2026-08-21T02:32:19.79083Z","steps":["trace[1872411396] 'process raft request' (duration: 312.677337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:19.790977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.614889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:32:19.791025Z","caller":"traceutil/trace.go:171","msg":"trace[77004640] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2171; }","duration":"182.740473ms","start":"2026-08-21T02:32:19.608271Z","end":"2026-08-21T02:32:19.791011Z","steps":["trace[77004640] 'agreement among raft nodes before linearized reading' (duration: 182.61816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:19.791078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:32:19.477112Z","time spent":"313.858534ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:33:00.332046Z","caller":"traceutil/trace.go:171","msg":"trace[555964547] linearizableReadLoop","detail":"{readStateIndex:2670; appliedIndex:2669; }","duration":"182.087363ms","start":"2026-08-21T02:33:00.149927Z","end":"2026-08-21T02:33:00.332014Z","steps":["trace[555964547] 'read index received' (duration: 157.789441ms)","trace[555964547] 'applied index is now lower than readState.Index' (duration: 24.296762ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:00.332133Z","caller":"traceutil/trace.go:171","msg":"trace[1509127726] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"182.191626ms","start":"2026-08-21T02:33:00.149893Z","end":"2026-08-21T02:33:00.332085Z","steps":["trace[1509127726] 'process raft request' (duration: 157.909434ms)","trace[1509127726] 'compare' (duration: 24.089756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:00.332273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.327838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:33:00.332346Z","caller":"traceutil/trace.go:171","msg":"trace[1587315719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2568; }","duration":"182.454202ms","start":"2026-08-21T02:33:00.149874Z","end":"2026-08-21T02:33:00.332329Z","steps":["trace[1587315719] 'agreement among raft nodes before linearized reading' (duration: 182.328279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:00.332411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.448341ms","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-21T02:33:00.33255Z","caller":"traceutil/trace.go:171","msg":"trace[94946812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2568; }","duration":"182.636946ms","start":"2026-08-21T02:33:00.149895Z","end":"2026-08-21T02:33:00.332532Z","steps":["trace[94946812] 'agreement among raft nodes before linearized reading' (duration: 182.37397ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:34.317397Z","caller":"traceutil/trace.go:171","msg":"trace[2012985923] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"100.51562ms","start":"2026-08-21T02:33:34.216847Z","end":"2026-08-21T02:33:34.317362Z","steps":["trace[2012985923] 'process raft request' (duration: 100.278435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:34.473369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.882814ms","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-21T02:33:34.473476Z","caller":"traceutil/trace.go:171","msg":"trace[473204595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2772; }","duration":"120.064398ms","start":"2026-08-21T02:33:34.35339Z","end":"2026-08-21T02:33:34.473455Z","steps":["trace[473204595] 'range keys from in-memory index tree' (duration: 119.635109ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:44.084456Z","caller":"traceutil/trace.go:171","msg":"trace[1763605381] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5109; }","duration":"157.637567ms","start":"2026-08-21T02:37:43.926795Z","end":"2026-08-21T02:37:44.084433Z","steps":["trace[1763605381] 'read index received' (duration: 54.745339ms)","trace[1763605381] 'applied index is now lower than readState.Index' (duration: 102.891288ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:37:44.084591Z","caller":"traceutil/trace.go:171","msg":"trace[274836891] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4918; }","duration":"159.796518ms","start":"2026-08-21T02:37:43.924786Z","end":"2026-08-21T02:37:44.084582Z","steps":["trace[274836891] 'process raft request' (duration: 159.502491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:37:44.085169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.317353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-21T02:37:44.085293Z","caller":"traceutil/trace.go:171","msg":"trace[1495190503] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4918; }","duration":"158.487347ms","start":"2026-08-21T02:37:43.926779Z","end":"2026-08-21T02:37:44.085267Z","steps":["trace[1495190503] 'agreement among raft nodes before linearized reading' (duration: 158.125898ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:50.131732Z","caller":"traceutil/trace.go:171","msg":"trace[1282537916] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"207.810038ms","start":"2026-08-21T02:37:49.923892Z","end":"2026-08-21T02:37:50.131702Z","steps":["trace[1282537916] 'process raft request' (duration: 207.695565ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:37:50.132079Z","caller":"traceutil/trace.go:171","msg":"trace[1558971223] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"208.245629ms","start":"2026-08-21T02:37:49.923814Z","end":"2026-08-21T02:37:50.132059Z","steps":["trace[1558971223] 'process raft request' (duration: 115.843323ms)","trace[1558971223] 'compare' (duration: 91.703688ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:37:50.132177Z","caller":"traceutil/trace.go:171","msg":"trace[1041767473] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"208.116926ms","start":"2026-08-21T02:37:49.924032Z","end":"2026-08-21T02:37:50.132148Z","steps":["trace[1041767473] 'process raft request' (duration: 207.635704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:38:03.320107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.836415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:38:03.320234Z","caller":"traceutil/trace.go:171","msg":"trace[1360373191] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"332.452423ms","start":"2026-08-21T02:38:02.987762Z","end":"2026-08-21T02:38:03.320214Z","steps":["trace[1360373191] 'process raft request' (duration: 34.418043ms)","trace[1360373191] 'compare' (duration: 297.682061ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:38:03.320287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:38:02.98774Z","time spent":"332.520584ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:38:03.419466Z","caller":"traceutil/trace.go:171","msg":"trace[1001386992] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"431.205602ms","start":"2026-08-21T02:38:02.988223Z","end":"2026-08-21T02:38:03.419428Z","steps":["trace[1001386992] 'process raft request' (duration: 430.928445ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:38:03.419674Z","caller":"traceutil/trace.go:171","msg":"trace[1760684134] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"430.946995ms","start":"2026-08-21T02:38:02.988691Z","end":"2026-08-21T02:38:03.419638Z","steps":["trace[1760684134] 'process raft request' (duration: 430.653768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:38:03.4197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:38:02.988201Z","time spent":"431.371085ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:38:03.419866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:38:02.988669Z","time spent":"431.090929ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:38:03.452175Z","caller":"traceutil/trace.go:171","msg":"trace[299420405] linearizableReadLoop","detail":"{readStateIndex:5531; appliedIndex:5527; }","duration":"289.218687ms","start":"2026-08-21T02:38:03.162934Z","end":"2026-08-21T02:38:03.452153Z","steps":["trace[299420405] 'read index received' (duration: 256.341051ms)","trace[299420405] 'applied index is now lower than readState.Index' (duration: 32.876806ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:38:03.452188Z","caller":"traceutil/trace.go:171","msg":"trace[373232039] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"462.565292ms","start":"2026-08-21T02:38:02.989557Z","end":"2026-08-21T02:38:03.452123Z","steps":["trace[373232039] 'process raft request' (duration: 430.090545ms)","trace[373232039] 'compare' (duration: 32.281711ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:38:03.452397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:38:02.989531Z","time spent":"462.758405ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:38:03.452406Z","caller":"traceutil/trace.go:171","msg":"trace[1838132188] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"458.308848ms","start":"2026-08-21T02:38:02.994072Z","end":"2026-08-21T02:38:03.452381Z","steps":["trace[1838132188] 'process raft request' (duration: 457.992799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:38:03.452654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:38:02.994063Z","time spent":"458.513722ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:38:03.452453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.530525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-21T02:38:03.452855Z","caller":"traceutil/trace.go:171","msg":"trace[1252159852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5327; }","duration":"289.994326ms","start":"2026-08-21T02:38:03.162837Z","end":"2026-08-21T02:38:03.452832Z","steps":["trace[1252159852] 'agreement among raft nodes before linearized reading' (duration: 289.433123ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:38:12.195039Z","caller":"traceutil/trace.go:171","msg":"trace[997771458] linearizableReadLoop","detail":"{readStateIndex:5755; appliedIndex:5754; }","duration":"135.686382ms","start":"2026-08-21T02:38:12.059316Z","end":"2026-08-21T02:38:12.195002Z","steps":["trace[997771458] 'read index received' (duration: 135.419476ms)","trace[997771458] 'applied index is now lower than readState.Index' (duration: 265.846µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:38:12.19517Z","caller":"traceutil/trace.go:171","msg":"trace[734284048] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"214.16059ms","start":"2026-08-21T02:38:11.980964Z","end":"2026-08-21T02:38:12.195125Z","steps":["trace[734284048] 'process raft request' (duration: 213.845283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:38:12.195333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.99425ms","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-21T02:38:12.195366Z","caller":"traceutil/trace.go:171","msg":"trace[421489627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5546; }","duration":"136.120544ms","start":"2026-08-21T02:38:12.059235Z","end":"2026-08-21T02:38:12.195356Z","steps":["trace[421489627] 'agreement among raft nodes before linearized reading' (duration: 135.924128ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:38:12.305657Z","caller":"traceutil/trace.go:171","msg":"trace[1055346188] transaction","detail":"{read_only:false; response_revision:5547; number_of_response:1; }","duration":"105.108893ms","start":"2026-08-21T02:38:12.200506Z","end":"2026-08-21T02:38:12.305615Z","steps":["trace[1055346188] 'process raft request' (duration: 60.160285ms)","trace[1055346188] 'compare' (duration: 44.697212ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:38:12.4828Z","caller":"traceutil/trace.go:171","msg":"trace[84696543] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"171.649502ms","start":"2026-08-21T02:38:12.3111Z","end":"2026-08-21T02:38:12.48275Z","steps":["trace[84696543] 'process raft request' (duration: 149.610799ms)","trace[84696543] 'compare' (duration: 21.779877ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:38:12.482822Z","caller":"traceutil/trace.go:171","msg":"trace[765235094] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"171.091729ms","start":"2026-08-21T02:38:12.311689Z","end":"2026-08-21T02:38:12.482781Z","steps":["trace[765235094] 'process raft request' (duration: 170.967796ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:38:12.482837Z","caller":"traceutil/trace.go:171","msg":"trace[447734945] linearizableReadLoop","detail":"{readStateIndex:5763; appliedIndex:5758; }","duration":"168.448326ms","start":"2026-08-21T02:38:12.314361Z","end":"2026-08-21T02:38:12.482809Z","steps":["trace[447734945] 'read index received' (duration: 56.302802ms)","trace[447734945] 'applied index is now lower than readState.Index' (duration: 112.144684ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:38:12.483049Z","caller":"traceutil/trace.go:171","msg":"trace[611512108] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"169.05481ms","start":"2026-08-21T02:38:12.313974Z","end":"2026-08-21T02:38:12.483029Z","steps":["trace[611512108] 'process raft request' (duration: 168.780423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:38:12.483092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.715842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lvxrt\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-21T02:38:12.483134Z","caller":"traceutil/trace.go:171","msg":"trace[926537017] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lvxrt; range_end:; response_count:1; response_revision:5554; }","duration":"168.803453ms","start":"2026-08-21T02:38:12.31432Z","end":"2026-08-21T02:38:12.483124Z","steps":["trace[926537017] 'agreement among raft nodes before linearized reading' (duration: 168.592848ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:38:12.483427Z","caller":"traceutil/trace.go:171","msg":"trace[527977719] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"170.250048ms","start":"2026-08-21T02:38:12.313129Z","end":"2026-08-21T02:38:12.483379Z","steps":["trace[527977719] 'process raft request' (duration: 169.582582ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:38:12.499916Z","caller":"traceutil/trace.go:171","msg":"trace[601005223] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"115.406182ms","start":"2026-08-21T02:38:12.384463Z","end":"2026-08-21T02:38:12.499869Z","steps":["trace[601005223] 'process raft request' (duration: 115.197487ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:38:23.517418Z","caller":"traceutil/trace.go:171","msg":"trace[1448117046] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"100.859801ms","start":"2026-08-21T02:38:23.416521Z","end":"2026-08-21T02:38:23.517381Z","steps":["trace[1448117046] 'process raft request' (duration: 100.508372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:39:03.572597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.407639ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-21T02:39:15.249555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2997} {"level":"info","ts":"2026-08-21T02:39:15.339331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2997,"took":"87.50498ms","hash":1618584003,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":33918976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-21T02:39:15.339412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1618584003,"revision":2997,"compact-revision":-1} {"level":"info","ts":"2026-08-21T02:44:15.26111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6090} {"level":"info","ts":"2026-08-21T02:44:15.358529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6090,"took":"94.450577ms","hash":3656537538,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":23789568,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-21T02:44:15.358641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3656537538,"revision":6090,"compact-revision":2997} {"level":"warn","ts":"2026-08-21T02:44:18.037823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.513023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:44:18.037911Z","caller":"traceutil/trace.go:171","msg":"trace[691483967] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8325; }","duration":"239.680407ms","start":"2026-08-21T02:44:17.798213Z","end":"2026-08-21T02:44:18.037893Z","steps":["trace[691483967] 'count revisions from in-memory index tree' (duration: 239.3919ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:44:18.103569Z","caller":"traceutil/trace.go:171","msg":"trace[660064234] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"140.22324ms","start":"2026-08-21T02:44:17.963307Z","end":"2026-08-21T02:44:18.10353Z","steps":["trace[660064234] 'process raft request' (duration: 139.982914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:45:00.194625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.815906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:45:00.19489Z","caller":"traceutil/trace.go:171","msg":"trace[2016618914] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"281.004273ms","start":"2026-08-21T02:44:59.913847Z","end":"2026-08-21T02:45:00.194851Z","steps":["trace[2016618914] 'compare' (duration: 272.285203ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:00.422081Z","caller":"traceutil/trace.go:171","msg":"trace[2136672321] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"113.540006ms","start":"2026-08-21T02:45:00.308511Z","end":"2026-08-21T02:45:00.422051Z","steps":["trace[2136672321] 'process raft request' (duration: 113.377063ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:00.425119Z","caller":"traceutil/trace.go:171","msg":"trace[1845105832] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"116.441196ms","start":"2026-08-21T02:45:00.308651Z","end":"2026-08-21T02:45:00.425093Z","steps":["trace[1845105832] 'process raft request' (duration: 116.290802ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:06.55638Z","caller":"traceutil/trace.go:171","msg":"trace[1966076657] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"110.476474ms","start":"2026-08-21T02:45:06.445871Z","end":"2026-08-21T02:45:06.556348Z","steps":["trace[1966076657] 'process raft request' (duration: 110.33084ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:06.706595Z","caller":"traceutil/trace.go:171","msg":"trace[1357116343] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"145.051717ms","start":"2026-08-21T02:45:06.5615Z","end":"2026-08-21T02:45:06.706552Z","steps":["trace[1357116343] 'process raft request' (duration: 67.079827ms)","trace[1357116343] 'compare' (duration: 77.491317ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:45:06.706676Z","caller":"traceutil/trace.go:171","msg":"trace[1137255387] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"144.303209ms","start":"2026-08-21T02:45:06.562339Z","end":"2026-08-21T02:45:06.706642Z","steps":["trace[1137255387] 'process raft request' (duration: 144.158095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:45:07.26706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.705117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:45:07.267193Z","caller":"traceutil/trace.go:171","msg":"trace[1532831522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8643; }","duration":"117.922843ms","start":"2026-08-21T02:45:07.149247Z","end":"2026-08-21T02:45:07.26717Z","steps":["trace[1532831522] 'range keys from in-memory index tree' (duration: 117.598415ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:14.261492Z","caller":"traceutil/trace.go:171","msg":"trace[437881380] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"151.758368ms","start":"2026-08-21T02:45:14.109676Z","end":"2026-08-21T02:45:14.261434Z","steps":["trace[437881380] 'process raft request' (duration: 145.013985ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:14.273705Z","caller":"traceutil/trace.go:171","msg":"trace[1329229786] linearizableReadLoop","detail":"{readStateIndex:9034; appliedIndex:9032; }","duration":"126.863389ms","start":"2026-08-21T02:45:14.146822Z","end":"2026-08-21T02:45:14.273685Z","steps":["trace[1329229786] 'read index received' (duration: 107.888331ms)","trace[1329229786] 'applied index is now lower than readState.Index' (duration: 18.973928ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:45:14.273848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.002112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:45:14.273881Z","caller":"traceutil/trace.go:171","msg":"trace[1074210141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8717; }","duration":"127.100704ms","start":"2026-08-21T02:45:14.146772Z","end":"2026-08-21T02:45:14.273873Z","steps":["trace[1074210141] 'agreement among raft nodes before linearized reading' (duration: 126.984481ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:14.274117Z","caller":"traceutil/trace.go:171","msg":"trace[1659844226] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8717; }","duration":"163.124272ms","start":"2026-08-21T02:45:14.110905Z","end":"2026-08-21T02:45:14.27403Z","steps":["trace[1659844226] 'process raft request' (duration: 162.63982ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:14.700344Z","caller":"traceutil/trace.go:171","msg":"trace[2001725202] transaction","detail":"{read_only:false; response_revision:8720; number_of_response:1; }","duration":"119.034268ms","start":"2026-08-21T02:45:14.58126Z","end":"2026-08-21T02:45:14.700294Z","steps":["trace[2001725202] 'process raft request' (duration: 94.248671ms)","trace[2001725202] 'compare' (duration: 24.353237ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:45:14.945176Z","caller":"traceutil/trace.go:171","msg":"trace[48926478] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"196.827754ms","start":"2026-08-21T02:45:14.748319Z","end":"2026-08-21T02:45:14.945147Z","steps":["trace[48926478] 'process raft request' (duration: 196.66095ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:15.044534Z","caller":"traceutil/trace.go:171","msg":"trace[401778946] linearizableReadLoop","detail":"{readStateIndex:9039; appliedIndex:9037; }","duration":"262.099169ms","start":"2026-08-21T02:45:14.782395Z","end":"2026-08-21T02:45:15.044494Z","steps":["trace[401778946] 'read index received' (duration: 162.59253ms)","trace[401778946] 'applied index is now lower than readState.Index' (duration: 99.504849ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:45:15.044661Z","caller":"traceutil/trace.go:171","msg":"trace[891610968] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"293.958586ms","start":"2026-08-21T02:45:14.750655Z","end":"2026-08-21T02:45:15.044614Z","steps":["trace[891610968] 'process raft request' (duration: 293.25762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:45:15.100458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.037157ms","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-21T02:45:15.100562Z","caller":"traceutil/trace.go:171","msg":"trace[1959216596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8722; }","duration":"318.16632ms","start":"2026-08-21T02:45:14.782357Z","end":"2026-08-21T02:45:15.100523Z","steps":["trace[1959216596] 'agreement among raft nodes before linearized reading' (duration: 262.288573ms)","trace[1959216596] 'range keys from in-memory index tree' (duration: 55.670402ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:45:15.100606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:45:14.78234Z","time spent":"318.257402ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-21T02:45:15.118498Z","caller":"traceutil/trace.go:171","msg":"trace[245832784] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"124.47424ms","start":"2026-08-21T02:45:14.993979Z","end":"2026-08-21T02:45:15.118453Z","steps":["trace[245832784] 'process raft request' (duration: 124.363857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:45:15.118586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.450295ms","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-21T02:45:15.118658Z","caller":"traceutil/trace.go:171","msg":"trace[2116260320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8724; }","duration":"313.546908ms","start":"2026-08-21T02:45:14.805094Z","end":"2026-08-21T02:45:15.118641Z","steps":["trace[2116260320] 'agreement among raft nodes before linearized reading' (duration: 313.357864ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:15.118624Z","caller":"traceutil/trace.go:171","msg":"trace[1931779240] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"132.010492ms","start":"2026-08-21T02:45:14.986565Z","end":"2026-08-21T02:45:15.118576Z","steps":["trace[1931779240] 'process raft request' (duration: 131.547301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:45:15.118699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:45:14.805086Z","time spent":"313.60691ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-21T02:45:15.118858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.539544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-21T02:45:15.118906Z","caller":"traceutil/trace.go:171","msg":"trace[1823685731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8724; }","duration":"322.625836ms","start":"2026-08-21T02:45:14.796268Z","end":"2026-08-21T02:45:15.118894Z","steps":["trace[1823685731] 'agreement among raft nodes before linearized reading' (duration: 322.382731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:45:15.118971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:45:14.796245Z","time spent":"322.714339ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-21T02:45:15.119225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.552529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rbfvg\" ","response":"range_response_count:1 size:4652"} {"level":"warn","ts":"2026-08-21T02:45:15.118586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.104568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-08-21T02:45:15.119377Z","caller":"traceutil/trace.go:171","msg":"trace[719346158] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8724; }","duration":"116.928049ms","start":"2026-08-21T02:45:15.002436Z","end":"2026-08-21T02:45:15.119364Z","steps":["trace[719346158] 'agreement among raft nodes before linearized reading' (duration: 115.993945ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:15.119555Z","caller":"traceutil/trace.go:171","msg":"trace[275347062] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rbfvg; range_end:; response_count:1; response_revision:8724; }","duration":"133.946659ms","start":"2026-08-21T02:45:14.985389Z","end":"2026-08-21T02:45:15.119336Z","steps":["trace[275347062] 'agreement among raft nodes before linearized reading' (duration: 133.116218ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:38.373475Z","caller":"traceutil/trace.go:171","msg":"trace[1092670467] transaction","detail":"{read_only:false; response_revision:8969; number_of_response:1; }","duration":"159.554997ms","start":"2026-08-21T02:45:38.213888Z","end":"2026-08-21T02:45:38.373443Z","steps":["trace[1092670467] 'process raft request' (duration: 159.364921ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:45:42.701503Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002477.wal"} {"level":"info","ts":"2026-08-21T02:46:57.041294Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-21T02:46:57.046865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-21T02:46:57.046945Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-21T02:48:21.3355Z","caller":"traceutil/trace.go:171","msg":"trace[661639270] transaction","detail":"{read_only:false; response_revision:10266; number_of_response:1; }","duration":"100.405561ms","start":"2026-08-21T02:48:21.23506Z","end":"2026-08-21T02:48:21.335466Z","steps":["trace[661639270] 'process raft request' (duration: 76.725027ms)","trace[661639270] 'compare' (duration: 23.490429ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:48:21.528859Z","caller":"traceutil/trace.go:171","msg":"trace[649325358] transaction","detail":"{read_only:false; response_revision:10268; number_of_response:1; }","duration":"112.291559ms","start":"2026-08-21T02:48:21.416531Z","end":"2026-08-21T02:48:21.528823Z","steps":["trace[649325358] 'process raft request' (duration: 54.334226ms)","trace[649325358] 'compare' (duration: 57.578544ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:48:22.525122Z","caller":"traceutil/trace.go:171","msg":"trace[933565801] transaction","detail":"{read_only:false; response_revision:10273; number_of_response:1; }","duration":"160.179928ms","start":"2026-08-21T02:48:22.364895Z","end":"2026-08-21T02:48:22.525075Z","steps":["trace[933565801] 'process raft request' (duration: 116.058891ms)","trace[933565801] 'compare' (duration: 43.957673ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:48:23.280883Z","caller":"traceutil/trace.go:171","msg":"trace[1948493110] transaction","detail":"{read_only:false; response_revision:10279; number_of_response:1; }","duration":"109.604793ms","start":"2026-08-21T02:48:23.171243Z","end":"2026-08-21T02:48:23.280848Z","steps":["trace[1948493110] 'process raft request' (duration: 109.46442ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:48:23.281083Z","caller":"traceutil/trace.go:171","msg":"trace[906769087] transaction","detail":"{read_only:false; response_revision:10278; number_of_response:1; }","duration":"125.157241ms","start":"2026-08-21T02:48:23.15588Z","end":"2026-08-21T02:48:23.281037Z","steps":["trace[906769087] 'process raft request' (duration: 60.971856ms)","trace[906769087] 'compare' (duration: 63.532529ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:48:24.711521Z","caller":"traceutil/trace.go:171","msg":"trace[2118639075] linearizableReadLoop","detail":"{readStateIndex:10679; appliedIndex:10678; }","duration":"116.185873ms","start":"2026-08-21T02:48:24.595309Z","end":"2026-08-21T02:48:24.711495Z","steps":["trace[2118639075] 'read index received' (duration: 75.099328ms)","trace[2118639075] 'applied index is now lower than readState.Index' (duration: 41.085215ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:48:24.711533Z","caller":"traceutil/trace.go:171","msg":"trace[999102055] transaction","detail":"{read_only:false; response_revision:10313; number_of_response:1; }","duration":"116.285955ms","start":"2026-08-21T02:48:24.595206Z","end":"2026-08-21T02:48:24.711492Z","steps":["trace[999102055] 'process raft request' (duration: 75.17761ms)","trace[999102055] 'compare' (duration: 40.927121ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:48:24.711786Z","caller":"traceutil/trace.go:171","msg":"trace[2020383464] transaction","detail":"{read_only:false; response_revision:10314; number_of_response:1; }","duration":"114.83399ms","start":"2026-08-21T02:48:24.596942Z","end":"2026-08-21T02:48:24.711776Z","steps":["trace[2020383464] 'process raft request' (duration: 114.521133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:48:24.711965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.626814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-21T02:48:24.712076Z","caller":"traceutil/trace.go:171","msg":"trace[54700868] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:10314; }","duration":"116.749087ms","start":"2026-08-21T02:48:24.595299Z","end":"2026-08-21T02:48:24.712049Z","steps":["trace[54700868] 'agreement among raft nodes before linearized reading' (duration: 116.282695ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:48:30.756595Z","caller":"traceutil/trace.go:171","msg":"trace[2052974522] transaction","detail":"{read_only:false; response_revision:10342; number_of_response:1; }","duration":"126.111113ms","start":"2026-08-21T02:48:30.630453Z","end":"2026-08-21T02:48:30.756564Z","steps":["trace[2052974522] 'process raft request' (duration: 125.913268ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:48:30.976986Z","caller":"traceutil/trace.go:171","msg":"trace[534624930] transaction","detail":"{read_only:false; response_revision:10343; number_of_response:1; }","duration":"179.839684ms","start":"2026-08-21T02:48:30.797112Z","end":"2026-08-21T02:48:30.976952Z","steps":["trace[534624930] 'process raft request' (duration: 171.605774ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:48:30.977737Z","caller":"traceutil/trace.go:171","msg":"trace[1323776123] linearizableReadLoop","detail":"{readStateIndex:10711; appliedIndex:10709; }","duration":"154.701356ms","start":"2026-08-21T02:48:30.823015Z","end":"2026-08-21T02:48:30.977716Z","steps":["trace[1323776123] 'read index received' (duration: 145.672866ms)","trace[1323776123] 'applied index is now lower than readState.Index' (duration: 9.02732ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:48:30.977815Z","caller":"traceutil/trace.go:171","msg":"trace[1528421771] transaction","detail":"{read_only:false; response_revision:10344; number_of_response:1; }","duration":"166.814989ms","start":"2026-08-21T02:48:30.810938Z","end":"2026-08-21T02:48:30.977753Z","steps":["trace[1528421771] 'process raft request' (duration: 166.634415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:48:30.978135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.101356ms","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-21T02:48:30.97825Z","caller":"traceutil/trace.go:171","msg":"trace[1091952566] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10345; }","duration":"155.27918ms","start":"2026-08-21T02:48:30.822946Z","end":"2026-08-21T02:48:30.978225Z","steps":["trace[1091952566] 'agreement among raft nodes before linearized reading' (duration: 154.84769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:48:33.827884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.792708ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-21T02:48:36.048077Z","caller":"traceutil/trace.go:171","msg":"trace[1750840877] transaction","detail":"{read_only:false; response_revision:10370; number_of_response:1; }","duration":"133.00511ms","start":"2026-08-21T02:48:35.915029Z","end":"2026-08-21T02:48:36.048034Z","steps":["trace[1750840877] 'process raft request' (duration: 114.611295ms)","trace[1750840877] 'compare' (duration: 18.210301ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:49:15.268155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8311} {"level":"info","ts":"2026-08-21T02:49:15.355667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8311,"took":"84.843334ms","hash":1715559012,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":25440256,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-21T02:49:15.355768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1715559012,"revision":8311,"compact-revision":6090} {"level":"info","ts":"2026-08-21T02:52:11.810582Z","caller":"traceutil/trace.go:171","msg":"trace[1381897710] transaction","detail":"{read_only:false; response_revision:11943; number_of_response:1; }","duration":"166.257961ms","start":"2026-08-21T02:52:11.644231Z","end":"2026-08-21T02:52:11.810489Z","steps":["trace[1381897710] 'process raft request' (duration: 166.012285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:52:11.993422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.749649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:52:11.993442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.129986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:52:11.993541Z","caller":"traceutil/trace.go:171","msg":"trace[1520142305] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:11943; }","duration":"118.980014ms","start":"2026-08-21T02:52:11.874527Z","end":"2026-08-21T02:52:11.993507Z","steps":["trace[1520142305] 'count revisions from in-memory index tree' (duration: 118.619805ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:52:11.993545Z","caller":"traceutil/trace.go:171","msg":"trace[2104507691] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:11943; }","duration":"121.321331ms","start":"2026-08-21T02:52:11.872202Z","end":"2026-08-21T02:52:11.993523Z","steps":["trace[2104507691] 'count revisions from in-memory index tree' (duration: 121.024373ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:52:12.643677Z","caller":"traceutil/trace.go:171","msg":"trace[1438951861] transaction","detail":"{read_only:false; response_revision:11948; number_of_response:1; }","duration":"134.129612ms","start":"2026-08-21T02:52:12.50949Z","end":"2026-08-21T02:52:12.64362Z","steps":["trace[1438951861] 'process raft request' (duration: 112.99786ms)","trace[1438951861] 'compare' (duration: 20.881386ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:52:12.820601Z","caller":"traceutil/trace.go:171","msg":"trace[468126288] transaction","detail":"{read_only:false; response_revision:11950; number_of_response:1; }","duration":"100.355554ms","start":"2026-08-21T02:52:12.720208Z","end":"2026-08-21T02:52:12.820564Z","steps":["trace[468126288] 'process raft request' (duration: 79.816576ms)","trace[468126288] 'compare' (duration: 20.392224ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:54:15.276533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10571} {"level":"info","ts":"2026-08-21T02:54:15.356605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10571,"took":"77.698589ms","hash":351746405,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":26185728,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-21T02:54:15.356681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":351746405,"revision":10571,"compact-revision":8311} {"level":"warn","ts":"2026-08-21T02:59:04.730849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.506474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-21T02:59:04.730915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.820706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lvxrt\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-21T02:59:04.731001Z","caller":"traceutil/trace.go:171","msg":"trace[1505045202] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lvxrt; range_end:; response_count:1; response_revision:14658; }","duration":"162.963889ms","start":"2026-08-21T02:59:04.568015Z","end":"2026-08-21T02:59:04.730979Z","steps":["trace[1505045202] 'range keys from in-memory index tree' (duration: 162.643242ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:59:04.731004Z","caller":"traceutil/trace.go:171","msg":"trace[421601105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14658; }","duration":"125.694939ms","start":"2026-08-21T02:59:04.605268Z","end":"2026-08-21T02:59:04.730963Z","steps":["trace[421601105] 'range keys from in-memory index tree' (duration: 125.235618ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:59:15.282376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12788} {"level":"info","ts":"2026-08-21T02:59:15.367378Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12788,"took":"81.797539ms","hash":4092065365,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":27078656,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-21T02:59:15.36746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4092065365,"revision":12788,"compact-revision":10571} {"level":"info","ts":"2026-08-21T02:59:20.651916Z","caller":"traceutil/trace.go:171","msg":"trace[341313767] transaction","detail":"{read_only:false; response_revision:14755; number_of_response:1; }","duration":"136.688535ms","start":"2026-08-21T02:59:20.515198Z","end":"2026-08-21T02:59:20.651887Z","steps":["trace[341313767] 'process raft request' (duration: 136.48428ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:59:45.928039Z","caller":"traceutil/trace.go:171","msg":"trace[1930792096] transaction","detail":"{read_only:false; response_revision:15082; number_of_response:1; }","duration":"179.434188ms","start":"2026-08-21T02:59:45.748553Z","end":"2026-08-21T02:59:45.927987Z","steps":["trace[1930792096] 'process raft request' (duration: 179.028398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:38.652589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.109718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2fpr9\" ","response":"range_response_count:1 size:20655"} {"level":"info","ts":"2026-08-21T03:00:38.652757Z","caller":"traceutil/trace.go:171","msg":"trace[704206288] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2fpr9; range_end:; response_count:1; response_revision:15715; }","duration":"109.337053ms","start":"2026-08-21T03:00:38.543395Z","end":"2026-08-21T03:00:38.652732Z","steps":["trace[704206288] 'range keys from in-memory index tree' (duration: 108.894312ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:03.596974Z","caller":"traceutil/trace.go:171","msg":"trace[1327947284] transaction","detail":"{read_only:false; response_revision:15850; number_of_response:1; }","duration":"214.399742ms","start":"2026-08-21T03:01:03.382535Z","end":"2026-08-21T03:01:03.596935Z","steps":["trace[1327947284] 'process raft request' (duration: 199.150674ms)","trace[1327947284] 'compare' (duration: 15.046523ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:01:03.745296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.292235ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-21T03:04:15.292847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14713} {"level":"info","ts":"2026-08-21T03:04:15.36991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14713,"took":"74.311715ms","hash":3680171051,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":31244288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-21T03:04:15.369978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3680171051,"revision":14713,"compact-revision":12788} {"level":"info","ts":"2026-08-21T03:07:22.568367Z","caller":"traceutil/trace.go:171","msg":"trace[681944702] transaction","detail":"{read_only:false; response_revision:18621; number_of_response:1; }","duration":"142.929757ms","start":"2026-08-21T03:07:22.425409Z","end":"2026-08-21T03:07:22.568339Z","steps":["trace[681944702] 'process raft request' (duration: 142.756052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:23.14089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.690652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-21T03:07:23.141084Z","caller":"traceutil/trace.go:171","msg":"trace[339219945] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:18623; }","duration":"159.953568ms","start":"2026-08-21T03:07:22.981102Z","end":"2026-08-21T03:07:23.141055Z","steps":["trace[339219945] 'count revisions from in-memory index tree' (duration: 159.481317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:23.14107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.9961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fqqrb\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-08-21T03:07:23.141804Z","caller":"traceutil/trace.go:171","msg":"trace[1628762091] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fqqrb; range_end:; response_count:1; response_revision:18623; }","duration":"228.197918ms","start":"2026-08-21T03:07:22.913587Z","end":"2026-08-21T03:07:23.141784Z","steps":["trace[1628762091] 'range keys from in-memory index tree' (duration: 226.751994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:23.140958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.178259ms","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-21T03:07:23.14194Z","caller":"traceutil/trace.go:171","msg":"trace[1325142468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18623; }","duration":"198.189473ms","start":"2026-08-21T03:07:22.943709Z","end":"2026-08-21T03:07:23.141898Z","steps":["trace[1325142468] 'range keys from in-memory index tree' (duration: 196.865041ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:23.458754Z","caller":"traceutil/trace.go:171","msg":"trace[1629888644] linearizableReadLoop","detail":"{readStateIndex:19273; appliedIndex:19272; }","duration":"309.838222ms","start":"2026-08-21T03:07:23.148889Z","end":"2026-08-21T03:07:23.458728Z","steps":["trace[1629888644] 'read index received' (duration: 309.636457ms)","trace[1629888644] 'applied index is now lower than readState.Index' (duration: 201.095µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:07:23.458922Z","caller":"traceutil/trace.go:171","msg":"trace[642939804] transaction","detail":"{read_only:false; response_revision:18624; number_of_response:1; }","duration":"311.553784ms","start":"2026-08-21T03:07:23.147321Z","end":"2026-08-21T03:07:23.458874Z","steps":["trace[642939804] 'process raft request' (duration: 311.268358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:23.459096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.104218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:07:23.459163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:23.147294Z","time spent":"311.747228ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:07:23.459194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.01467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:07:23.459229Z","caller":"traceutil/trace.go:171","msg":"trace[1641519734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18624; }","duration":"310.268472ms","start":"2026-08-21T03:07:23.148928Z","end":"2026-08-21T03:07:23.459197Z","steps":["trace[1641519734] 'agreement among raft nodes before linearized reading' (duration: 310.016686ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:23.459274Z","caller":"traceutil/trace.go:171","msg":"trace[378820914] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:18624; }","duration":"153.156644ms","start":"2026-08-21T03:07:23.306096Z","end":"2026-08-21T03:07:23.459253Z","steps":["trace[378820914] 'agreement among raft nodes before linearized reading' (duration: 153.01668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:23.459284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:23.148924Z","time spent":"310.350186ms","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T03:07:23.459294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.161368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-21T03:07:23.459463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.221195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-08-21T03:07:23.459599Z","caller":"traceutil/trace.go:171","msg":"trace[1837750873] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18624; }","duration":"289.603274ms","start":"2026-08-21T03:07:23.169972Z","end":"2026-08-21T03:07:23.459575Z","steps":["trace[1837750873] 'agreement among raft nodes before linearized reading' (duration: 289.125712ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:07:23.459594Z","caller":"traceutil/trace.go:171","msg":"trace[582262965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18624; }","duration":"240.441254ms","start":"2026-08-21T03:07:23.219017Z","end":"2026-08-21T03:07:23.459458Z","steps":["trace[582262965] 'agreement among raft nodes before linearized reading' (duration: 240.007163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:23.459923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.016152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:952714"} {"level":"info","ts":"2026-08-21T03:07:23.45997Z","caller":"traceutil/trace.go:171","msg":"trace[914454467] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18624; }","duration":"311.110153ms","start":"2026-08-21T03:07:23.148848Z","end":"2026-08-21T03:07:23.459958Z","steps":["trace[914454467] 'agreement among raft nodes before linearized reading' (duration: 310.005206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:07:23.460001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:07:23.148835Z","time spent":"311.158025ms","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":952736,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-21T03:08:28.634725Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-21T03:08:28.640657Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-21T03:08:28.640866Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-21T03:09:15.300788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17179} {"level":"info","ts":"2026-08-21T03:09:15.522637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17179,"took":"218.542795ms","hash":2732562614,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":33251328,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-21T03:09:15.522708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2732562614,"revision":17179,"compact-revision":14713} {"level":"info","ts":"2026-08-21T03:13:04.148001Z","caller":"traceutil/trace.go:171","msg":"trace[1915679276] transaction","detail":"{read_only:false; response_revision:21274; number_of_response:1; }","duration":"159.869086ms","start":"2026-08-21T03:13:03.988098Z","end":"2026-08-21T03:13:04.147967Z","steps":["trace[1915679276] 'process raft request' (duration: 159.548548ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:13:04.147964Z","caller":"traceutil/trace.go:171","msg":"trace[838048822] linearizableReadLoop","detail":"{readStateIndex:22010; appliedIndex:22009; }","duration":"106.565847ms","start":"2026-08-21T03:13:04.041338Z","end":"2026-08-21T03:13:04.147904Z","steps":["trace[838048822] 'read index received' (duration: 106.376103ms)","trace[838048822] 'applied index is now lower than readState.Index' (duration: 188.384µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:13:04.148234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.863534ms","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-21T03:13:04.148311Z","caller":"traceutil/trace.go:171","msg":"trace[1512003293] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21274; }","duration":"107.000507ms","start":"2026-08-21T03:13:04.04129Z","end":"2026-08-21T03:13:04.148291Z","steps":["trace[1512003293] 'agreement among raft nodes before linearized reading' (duration: 106.757662ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:13:04.314624Z","caller":"traceutil/trace.go:171","msg":"trace[493051560] transaction","detail":"{read_only:false; response_revision:21275; number_of_response:1; }","duration":"222.848489ms","start":"2026-08-21T03:13:04.091739Z","end":"2026-08-21T03:13:04.314588Z","steps":["trace[493051560] 'process raft request' (duration: 189.102583ms)","trace[493051560] 'compare' (duration: 33.587783ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:13:04.358479Z","caller":"traceutil/trace.go:171","msg":"trace[918654962] transaction","detail":"{read_only:false; response_revision:21276; number_of_response:1; }","duration":"206.494083ms","start":"2026-08-21T03:13:04.15196Z","end":"2026-08-21T03:13:04.358454Z","steps":["trace[918654962] 'process raft request' (duration: 206.36181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:13:04.751671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.508531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:13:04.751763Z","caller":"traceutil/trace.go:171","msg":"trace[757932023] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:21276; }","duration":"277.678695ms","start":"2026-08-21T03:13:04.474064Z","end":"2026-08-21T03:13:04.751743Z","steps":["trace[757932023] 'count revisions from in-memory index tree' (duration: 277.360898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:13:04.751887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.788575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:13:04.752063Z","caller":"traceutil/trace.go:171","msg":"trace[1791874084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21276; }","duration":"196.051142ms","start":"2026-08-21T03:13:04.555961Z","end":"2026-08-21T03:13:04.752012Z","steps":["trace[1791874084] 'range keys from in-memory index tree' (duration: 195.635431ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:13:04.988005Z","caller":"traceutil/trace.go:171","msg":"trace[31470160] transaction","detail":"{read_only:false; response_revision:21278; number_of_response:1; }","duration":"225.313639ms","start":"2026-08-21T03:13:04.762661Z","end":"2026-08-21T03:13:04.987974Z","steps":["trace[31470160] 'process raft request' (duration: 154.031175ms)","trace[31470160] 'compare' (duration: 71.10438ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:13:05.24207Z","caller":"traceutil/trace.go:171","msg":"trace[1979792905] transaction","detail":"{read_only:false; response_revision:21279; number_of_response:1; }","duration":"103.700198ms","start":"2026-08-21T03:13:05.138325Z","end":"2026-08-21T03:13:05.242025Z","steps":["trace[1979792905] 'process raft request' (duration: 103.397691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:13:05.439701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.717864ms","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-21T03:13:05.439815Z","caller":"traceutil/trace.go:171","msg":"trace[1435816694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21279; }","duration":"119.898379ms","start":"2026-08-21T03:13:05.319891Z","end":"2026-08-21T03:13:05.43979Z","steps":["trace[1435816694] 'range keys from in-memory index tree' (duration: 119.496199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:13:05.720484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.648696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-21T03:13:05.720594Z","caller":"traceutil/trace.go:171","msg":"trace[1865301029] linearizableReadLoop","detail":"{readStateIndex:22017; appliedIndex:22015; }","duration":"253.583772ms","start":"2026-08-21T03:13:05.46699Z","end":"2026-08-21T03:13:05.720574Z","steps":["trace[1865301029] 'read index received' (duration: 45.52054ms)","trace[1865301029] 'applied index is now lower than readState.Index' (duration: 208.061662ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:13:05.720688Z","caller":"traceutil/trace.go:171","msg":"trace[1026671691] transaction","detail":"{read_only:false; response_revision:21281; number_of_response:1; }","duration":"275.030582ms","start":"2026-08-21T03:13:05.445644Z","end":"2026-08-21T03:13:05.720674Z","steps":["trace[1026671691] 'process raft request' (duration: 140.076998ms)","trace[1026671691] 'compare' (duration: 134.028041ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:13:05.720954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.939822ms","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-21T03:13:05.720984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.476898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-21T03:13:05.721045Z","caller":"traceutil/trace.go:171","msg":"trace[681322880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21281; }","duration":"254.088825ms","start":"2026-08-21T03:13:05.466929Z","end":"2026-08-21T03:13:05.721018Z","steps":["trace[681322880] 'agreement among raft nodes before linearized reading' (duration: 253.848339ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:13:05.721063Z","caller":"traceutil/trace.go:171","msg":"trace[2136644422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21281; }","duration":"145.595511ms","start":"2026-08-21T03:13:05.575444Z","end":"2026-08-21T03:13:05.721039Z","steps":["trace[2136644422] 'agreement among raft nodes before linearized reading' (duration: 145.352145ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:13:05.916111Z","caller":"traceutil/trace.go:171","msg":"trace[1193166177] transaction","detail":"{read_only:false; response_revision:21283; number_of_response:1; }","duration":"190.307983ms","start":"2026-08-21T03:13:05.725766Z","end":"2026-08-21T03:13:05.916074Z","steps":["trace[1193166177] 'process raft request' (duration: 172.647626ms)","trace[1193166177] 'compare' (duration: 17.427821ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:13:31.800324Z","caller":"traceutil/trace.go:171","msg":"trace[1263200919] transaction","detail":"{read_only:false; response_revision:21414; number_of_response:1; }","duration":"119.820197ms","start":"2026-08-21T03:13:31.680469Z","end":"2026-08-21T03:13:31.800289Z","steps":["trace[1263200919] 'process raft request' (duration: 119.599462ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:14:15.308013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19701} {"level":"info","ts":"2026-08-21T03:14:15.423176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19701,"took":"111.187729ms","hash":2091389799,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":30007296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-21T03:14:15.423246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2091389799,"revision":19701,"compact-revision":17179} {"level":"warn","ts":"2026-08-21T03:17:06.521113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.573776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-08-21T03:17:06.521211Z","caller":"traceutil/trace.go:171","msg":"trace[1568908834] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:23396; }","duration":"108.692838ms","start":"2026-08-21T03:17:06.412496Z","end":"2026-08-21T03:17:06.521189Z","steps":["trace[1568908834] 'agreement among raft nodes before linearized reading' (duration: 108.500603ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:17:52.768393Z","caller":"traceutil/trace.go:171","msg":"trace[148155776] transaction","detail":"{read_only:false; response_revision:24169; number_of_response:1; }","duration":"100.93508ms","start":"2026-08-21T03:17:52.667383Z","end":"2026-08-21T03:17:52.768318Z","steps":["trace[148155776] 'process raft request' (duration: 100.768356ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:19:15.31505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21909} {"level":"info","ts":"2026-08-21T03:19:15.426216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21909,"took":"106.769062ms","hash":4007392700,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":43483136,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-21T03:19:15.426282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4007392700,"revision":21909,"compact-revision":19701} {"level":"info","ts":"2026-08-21T03:24:15.32181Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24995} {"level":"info","ts":"2026-08-21T03:24:15.474316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24995,"took":"147.602362ms","hash":2950819667,"current-db-size-bytes":57413632,"current-db-size":"57 MB","current-db-size-in-use-bytes":30437376,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-21T03:24:15.474399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2950819667,"revision":24995,"compact-revision":21909} {"level":"warn","ts":"2026-08-21T03:25:52.986961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068760,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-21T03:25:53.035999Z","caller":"traceutil/trace.go:171","msg":"trace[1467164874] transaction","detail":"{read_only:false; response_revision:27968; number_of_response:1; }","duration":"551.322389ms","start":"2026-08-21T03:25:52.484644Z","end":"2026-08-21T03:25:53.035967Z","steps":["trace[1467164874] 'process raft request' (duration: 551.150075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.036137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.48462Z","time spent":"551.425422ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:25:53.037327Z","caller":"traceutil/trace.go:171","msg":"trace[62558807] linearizableReadLoop","detail":"{readStateIndex:28900; appliedIndex:28897; }","duration":"550.938821ms","start":"2026-08-21T03:25:52.486352Z","end":"2026-08-21T03:25:53.037291Z","steps":["trace[62558807] 'read index received' (duration: 549.537356ms)","trace[62558807] 'applied index is now lower than readState.Index' (duration: 1.399715ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:25:53.037503Z","caller":"traceutil/trace.go:171","msg":"trace[415410843] transaction","detail":"{read_only:false; response_revision:27970; number_of_response:1; }","duration":"540.61485ms","start":"2026-08-21T03:25:52.496871Z","end":"2026-08-21T03:25:53.037486Z","steps":["trace[415410843] 'process raft request' (duration: 540.359914ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:25:53.037515Z","caller":"traceutil/trace.go:171","msg":"trace[996423788] transaction","detail":"{read_only:false; response_revision:27969; number_of_response:1; }","duration":"550.883549ms","start":"2026-08-21T03:25:52.486579Z","end":"2026-08-21T03:25:53.037463Z","steps":["trace[996423788] 'process raft request' (duration: 550.434148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.037675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.496852Z","time spent":"540.699182ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:25:53.037705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.181207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:25:53.037755Z","caller":"traceutil/trace.go:171","msg":"trace[1578482680] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:27970; }","duration":"537.256079ms","start":"2026-08-21T03:25:52.500486Z","end":"2026-08-21T03:25:53.037742Z","steps":["trace[1578482680] 'agreement among raft nodes before linearized reading' (duration: 537.173487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.037767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.436913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-08-21T03:25:53.03778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.486562Z","time spent":"551.071304ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:25:53.037798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.50047Z","time spent":"537.32074ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"warn","ts":"2026-08-21T03:25:53.037749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.06809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:25:53.037826Z","caller":"traceutil/trace.go:171","msg":"trace[659578142] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27970; }","duration":"456.533885ms","start":"2026-08-21T03:25:52.58128Z","end":"2026-08-21T03:25:53.037814Z","steps":["trace[659578142] 'agreement among raft nodes before linearized reading' (duration: 456.288769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.037863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.581267Z","time spent":"456.590057ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"info","ts":"2026-08-21T03:25:53.037886Z","caller":"traceutil/trace.go:171","msg":"trace[1280804738] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:27970; }","duration":"455.241095ms","start":"2026-08-21T03:25:52.582611Z","end":"2026-08-21T03:25:53.037852Z","steps":["trace[1280804738] 'agreement among raft nodes before linearized reading' (duration: 455.008829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.037953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.5826Z","time spent":"455.339927ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:25:53.037996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.389138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:25:53.038022Z","caller":"traceutil/trace.go:171","msg":"trace[178681265] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:27970; }","duration":"455.436899ms","start":"2026-08-21T03:25:52.582577Z","end":"2026-08-21T03:25:53.038014Z","steps":["trace[178681265] 'agreement among raft nodes before linearized reading' (duration: 455.395308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.038045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.582569Z","time spent":"455.47218ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:25:53.038108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.282715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-21T03:25:53.038178Z","caller":"traceutil/trace.go:171","msg":"trace[1115870066] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:27970; }","duration":"531.363046ms","start":"2026-08-21T03:25:52.506799Z","end":"2026-08-21T03:25:53.038162Z","steps":["trace[1115870066] 'agreement among raft nodes before linearized reading' (duration: 531.252384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.037914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.81818ms","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":"warn","ts":"2026-08-21T03:25:53.038221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.506792Z","time spent":"531.419618ms","remote":"127.0.0.1:37804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:25:53.038256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.219224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-21T03:25:53.038246Z","caller":"traceutil/trace.go:171","msg":"trace[621417100] 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:27970; }","duration":"485.211559ms","start":"2026-08-21T03:25:52.553011Z","end":"2026-08-21T03:25:53.038223Z","steps":["trace[621417100] 'agreement among raft nodes before linearized reading' (duration: 484.713997ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:25:53.038311Z","caller":"traceutil/trace.go:171","msg":"trace[55126973] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27970; }","duration":"455.288006ms","start":"2026-08-21T03:25:52.583007Z","end":"2026-08-21T03:25:53.038295Z","steps":["trace[55126973] 'agreement among raft nodes before linearized reading' (duration: 455.124082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.038348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.552999Z","time spent":"485.332492ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:25:53.038425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.298433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:25:53.038467Z","caller":"traceutil/trace.go:171","msg":"trace[2036284514] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:27970; }","duration":"538.361715ms","start":"2026-08-21T03:25:52.500094Z","end":"2026-08-21T03:25:53.038455Z","steps":["trace[2036284514] 'agreement among raft nodes before linearized reading' (duration: 538.302464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.038505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.500081Z","time spent":"538.416416ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:25:53.038289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.983602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:25:53.038604Z","caller":"traceutil/trace.go:171","msg":"trace[321992220] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:27970; }","duration":"456.29421ms","start":"2026-08-21T03:25:52.582297Z","end":"2026-08-21T03:25:53.038591Z","steps":["trace[321992220] 'agreement among raft nodes before linearized reading' (duration: 455.967352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.038641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.175111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:25:53.038327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.032493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:25:53.038684Z","caller":"traceutil/trace.go:171","msg":"trace[1178294390] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27970; }","duration":"538.231413ms","start":"2026-08-21T03:25:52.500442Z","end":"2026-08-21T03:25:53.038674Z","steps":["trace[1178294390] 'agreement among raft nodes before linearized reading' (duration: 538.168201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.038363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.582996Z","time spent":"455.357098ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"warn","ts":"2026-08-21T03:25:53.038725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.500435Z","time spent":"538.281644ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"info","ts":"2026-08-21T03:25:53.038688Z","caller":"traceutil/trace.go:171","msg":"trace[157373242] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27970; }","duration":"456.410933ms","start":"2026-08-21T03:25:52.58227Z","end":"2026-08-21T03:25:53.038681Z","steps":["trace[157373242] 'agreement among raft nodes before linearized reading' (duration: 456.038574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.038653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.582243Z","time spent":"456.400212ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"warn","ts":"2026-08-21T03:25:53.038832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.582258Z","time spent":"456.565726ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"warn","ts":"2026-08-21T03:25:53.038043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.574617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:25:53.039019Z","caller":"traceutil/trace.go:171","msg":"trace[1507981637] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:27970; }","duration":"538.55623ms","start":"2026-08-21T03:25:52.500424Z","end":"2026-08-21T03:25:53.03898Z","steps":["trace[1507981637] 'agreement among raft nodes before linearized reading' (duration: 537.560756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.03916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.500413Z","time spent":"538.695493ms","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:25:53.040774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.334636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement-ks-endpoints-m7bbs.18cdb2a8b0163032\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:27967 ","response":"range_response_count:406 size:310996"} {"level":"info","ts":"2026-08-21T03:25:53.040855Z","caller":"traceutil/trace.go:171","msg":"trace[1255361726] range","detail":"{range_begin:/registry/events/openstack/placement-ks-endpoints-m7bbs.18cdb2a8b0163032\u0000; range_end:/registry/events/openstack0; response_count:406; response_revision:27970; }","duration":"550.446528ms","start":"2026-08-21T03:25:52.490386Z","end":"2026-08-21T03:25:53.040832Z","steps":["trace[1255361726] 'agreement among raft nodes before linearized reading' (duration: 547.136108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.040924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.490373Z","time spent":"550.5157ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":406,"response size":311018,"request content":"key:\"/registry/events/openstack/placement-ks-endpoints-m7bbs.18cdb2a8b0163032\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:27967 "} {"level":"warn","ts":"2026-08-21T03:25:53.043212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.850813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-engine-845ccfc69c-985dk.18cdb360205fde9a\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:27967 ","response":"range_response_count:1000 size:781910"} {"level":"info","ts":"2026-08-21T03:25:53.043272Z","caller":"traceutil/trace.go:171","msg":"trace[1964540406] range","detail":"{range_begin:/registry/events/openstack/heat-engine-845ccfc69c-985dk.18cdb360205fde9a\u0000; range_end:/registry/events/openstack0; response_count:1000; response_revision:27970; }","duration":"556.953266ms","start":"2026-08-21T03:25:52.486304Z","end":"2026-08-21T03:25:53.043257Z","steps":["trace[1964540406] 'agreement among raft nodes before linearized reading' (duration: 551.454063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:53.043347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:52.486277Z","time spent":"557.023647ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":1406,"response size":781932,"request content":"key:\"/registry/events/openstack/heat-engine-845ccfc69c-985dk.18cdb360205fde9a\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:27967 "} {"level":"warn","ts":"2026-08-21T03:25:53.554304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:54.054976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:54.555908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:55.056578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:55.151739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999902206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:25:55.151834Z","caller":"traceutil/trace.go:171","msg":"trace[1275118658] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00006547s","start":"2026-08-21T03:25:53.151742Z","end":"2026-08-21T03:25:55.151808Z","steps":["trace[1275118658] 'agreement among raft nodes before linearized reading' (duration: 1.999951207s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:55.151891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.151711Z","time spent":"2.000168573s","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 03:25:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:55.557006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:56.057315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:56.55833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:57.059577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:57.152221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999895705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-21T03:25:57.152324Z","caller":"traceutil/trace.go:171","msg":"trace[612208750] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00006889s","start":"2026-08-21T03:25:55.152231Z","end":"2026-08-21T03:25:57.1523Z","steps":["trace[612208750] 'agreement among raft nodes before linearized reading' (duration: 1.999931946s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:57.152386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.152203Z","time spent":"2.000170533s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 03:25:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:57.560608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:58.061753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:58.36581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.36789Z","time spent":"4.997909261s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/21 03:25:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:58.486871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.4894Z","time spent":"4.99746337s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/21 03:25:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:58.559942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998649618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:25:58.560019Z","caller":"traceutil/trace.go:171","msg":"trace[1704820533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.998781451s","start":"2026-08-21T03:25:53.561215Z","end":"2026-08-21T03:25:58.559997Z","steps":["trace[1704820533] 'agreement among raft nodes before linearized reading' (duration: 4.998691319s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:58.56008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.561175Z","time spent":"4.998890484s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/21 03:25:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:58.562096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:58.863345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99830485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:25:58.863527Z","caller":"traceutil/trace.go:171","msg":"trace[1757497408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.998600637s","start":"2026-08-21T03:25:53.8649Z","end":"2026-08-21T03:25:58.863501Z","steps":["trace[1757497408] 'agreement among raft nodes before linearized reading' (duration: 4.998342261s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:58.863667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.864872Z","time spent":"4.998749911s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/21 03:25:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:59.062735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:25:59.132177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999267864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:25:59.132262Z","caller":"traceutil/trace.go:171","msg":"trace[847091020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999409047s","start":"2026-08-21T03:25:54.132833Z","end":"2026-08-21T03:25:59.132242Z","steps":["trace[847091020] 'agreement among raft nodes before linearized reading' (duration: 4.999302925s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:59.132314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.132813Z","time spent":"4.999492089s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/21 03:25:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:59.160728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999818333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:25:59.160833Z","caller":"traceutil/trace.go:171","msg":"trace[631602777] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999998578s","start":"2026-08-21T03:25:57.160812Z","end":"2026-08-21T03:25:59.160811Z","steps":["trace[631602777] 'agreement among raft nodes before linearized reading' (duration: 1.999856945s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:25:59.160892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:57.160791Z","time spent":"2.000090131s","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 03:25:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:59.181669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.18334Z","time spent":"4.998317411s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/21 03:25:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:25:59.563167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:26:00.04109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.043102Z","time spent":"4.997978292s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/21 03:26:00 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:26:00.041379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.044557Z","time spent":"4.996812475s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/21 03:26:00 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:26:00.041674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.041535Z","time spent":"7.000135543s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-21T03:26:00.042099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.046065Z","time spent":"4.996031136s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-21T03:26:00.054091Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-21T03:26:00.054248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000857281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-21T03:26:00.0543Z","caller":"traceutil/trace.go:171","msg":"trace[1974351454] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"7.000962053s","start":"2026-08-21T03:25:53.053316Z","end":"2026-08-21T03:26:00.054278Z","steps":["trace[1974351454] 'agreement among raft nodes before linearized reading' (duration: 7.000900091s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:00.054342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.053287Z","time spent":"7.001046025s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-21T03:26:00.450395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999859416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:26:00.450526Z","caller":"traceutil/trace.go:171","msg":"trace[1992708293] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000085591s","start":"2026-08-21T03:25:58.450414Z","end":"2026-08-21T03:26:00.450499Z","steps":["trace[1992708293] 'agreement among raft nodes before linearized reading' (duration: 1.999906287s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:00.450597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:58.450371Z","time spent":"2.000210834s","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 03:26:00 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:26:00.55448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:26:01.014465Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4c27a02226aab2c7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-21T03:26:01.056448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:26:01.161588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000625973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-21T03:26:01.161725Z","caller":"traceutil/trace.go:171","msg":"trace[1502800386] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000828068s","start":"2026-08-21T03:25:59.16087Z","end":"2026-08-21T03:26:01.161698Z","steps":["trace[1502800386] 'agreement among raft nodes before linearized reading' (duration: 2.000659244s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:01.161792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:59.160852Z","time spent":"2.000928941s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 03:26:01 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:26:01.352563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.351856Z","time spent":"7.000694948s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-21T03:26:01.556622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:26:01.767497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.767131Z","time spent":"7.000356688s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-21T03:26:02.057198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:26:02.557801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:26:03.037436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.991432676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:26:03.037597Z","caller":"traceutil/trace.go:171","msg":"trace[1593136658] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"2.991587699s","start":"2026-08-21T03:26:00.045955Z","end":"2026-08-21T03:26:03.037543Z","steps":["trace[1593136658] 'agreement among raft nodes before linearized reading' (duration: 2.991442036s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.037653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:00.045948Z","time spent":"2.991692163s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} 2026/08/21 03:26:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:26:03.052919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.690112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-21T03:26:03.053019Z","caller":"traceutil/trace.go:171","msg":"trace[1188818809] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"993.837496ms","start":"2026-08-21T03:26:02.059159Z","end":"2026-08-21T03:26:03.052996Z","steps":["trace[1188818809] 'agreement among raft nodes before linearized reading' (duration: 993.682112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.053074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:02.059084Z","time spent":"993.978259ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} 2026/08/21 03:26:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:26:03.057968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710902776367068779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:26:03.170206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000915542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-21T03:26:03.170297Z","caller":"traceutil/trace.go:171","msg":"trace[310116346] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001105736s","start":"2026-08-21T03:26:01.169172Z","end":"2026-08-21T03:26:03.170278Z","steps":["trace[310116346] 'agreement among raft nodes before linearized reading' (duration: 2.000929852s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.170353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:01.169162Z","time spent":"2.001175348s","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/21 03:26:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-21T03:26:03.2474Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"10.205690139s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-21T03:26:03.262863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.973721948s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.26304Z","caller":"traceutil/trace.go:171","msg":"trace[139589508] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27970; }","duration":"9.973922513s","start":"2026-08-21T03:25:53.289088Z","end":"2026-08-21T03:26:03.263011Z","steps":["trace[139589508] 'range keys from in-memory index tree' (duration: 9.973696708s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.264494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:58.995344Z","time spent":"4.269136589s","remote":"127.0.0.1:40786","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-21T03:26:03.303616Z","caller":"traceutil/trace.go:171","msg":"trace[181861363] transaction","detail":"{read_only:false; response_revision:27980; number_of_response:1; }","duration":"6.146615773s","start":"2026-08-21T03:25:57.156972Z","end":"2026-08-21T03:26:03.303588Z","steps":["trace[181861363] 'process raft request' (duration: 6.146271195s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.30376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:57.156939Z","time spent":"6.146750856s","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-21T03:26:03.303771Z","caller":"traceutil/trace.go:171","msg":"trace[49217682] transaction","detail":"{read_only:false; response_revision:27981; number_of_response:1; }","duration":"5.168148109s","start":"2026-08-21T03:25:58.135584Z","end":"2026-08-21T03:26:03.303732Z","steps":["trace[49217682] 'process raft request' (duration: 5.167871583s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.303944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:58.135546Z","time spent":"5.168308412s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:26:03.304061Z","caller":"traceutil/trace.go:171","msg":"trace[385897798] transaction","detail":"{read_only:false; response_revision:27982; number_of_response:1; }","duration":"4.191373228s","start":"2026-08-21T03:25:59.112678Z","end":"2026-08-21T03:26:03.304052Z","steps":["trace[385897798] 'process raft request' (duration: 4.191158512s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.304133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:59.112647Z","time spent":"4.19144246s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:26:03.304096Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"125.274µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-08-21T03:26:03.304363Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4c27a02226aab2c7","error":"lease not found"} {"level":"info","ts":"2026-08-21T03:26:03.304421Z","caller":"traceutil/trace.go:171","msg":"trace[446456814] linearizableReadLoop","detail":"{readStateIndex:28915; appliedIndex:28900; }","duration":"3.250183226s","start":"2026-08-21T03:26:00.054187Z","end":"2026-08-21T03:26:03.304371Z","steps":["trace[446456814] 'read index received' (duration: 3.193617247s)","trace[446456814] 'applied index is now lower than readState.Index' (duration: 56.564909ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:26:03.304474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.261492212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.304525Z","caller":"traceutil/trace.go:171","msg":"trace[956472911] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27982; }","duration":"8.261567113s","start":"2026-08-21T03:25:55.042935Z","end":"2026-08-21T03:26:03.304503Z","steps":["trace[956472911] 'agreement among raft nodes before linearized reading' (duration: 8.261496771s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.304613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.042917Z","time spent":"8.261684436s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"warn","ts":"2026-08-21T03:26:03.304666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.360729502s","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":"warn","ts":"2026-08-21T03:26:03.304472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.257993967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.30631Z","caller":"traceutil/trace.go:171","msg":"trace[456606379] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:27982; }","duration":"8.259896412s","start":"2026-08-21T03:25:55.046404Z","end":"2026-08-21T03:26:03.306301Z","steps":["trace[456606379] 'agreement among raft nodes before linearized reading' (duration: 8.257972017s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.306347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.046381Z","time spent":"8.259955873s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"info","ts":"2026-08-21T03:26:03.304794Z","caller":"traceutil/trace.go:171","msg":"trace[1910779357] transaction","detail":"{read_only:false; number_of_response:1; response_revision:27982; }","duration":"1.531701408s","start":"2026-08-21T03:26:01.773072Z","end":"2026-08-21T03:26:03.304774Z","steps":["trace[1910779357] 'process raft request' (duration: 1.531218647s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.30626Z","caller":"traceutil/trace.go:171","msg":"trace[1301103733] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:27982; }","duration":"8.362332351s","start":"2026-08-21T03:25:54.94389Z","end":"2026-08-21T03:26:03.306222Z","steps":["trace[1301103733] 'agreement among raft nodes before linearized reading' (duration: 8.360689691s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.306613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:01.773034Z","time spent":"1.533538362s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":724,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:26:03.304804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.994470786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-21T03:26:03.306639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.943838Z","time spent":"8.362790042s","remote":"127.0.0.1:51026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-21T03:26:03.30669Z","caller":"traceutil/trace.go:171","msg":"trace[1288496689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27982; }","duration":"8.996366s","start":"2026-08-21T03:25:54.310306Z","end":"2026-08-21T03:26:03.306672Z","steps":["trace[1288496689] 'agreement among raft nodes before linearized reading' (duration: 8.994392383s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.304876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.996129836s","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":"warn","ts":"2026-08-21T03:26:03.306748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.310293Z","time spent":"8.996443483s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-21T03:26:03.306757Z","caller":"traceutil/trace.go:171","msg":"trace[2004645004] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:27982; }","duration":"8.998054002s","start":"2026-08-21T03:25:54.308693Z","end":"2026-08-21T03:26:03.306747Z","steps":["trace[2004645004] 'agreement among raft nodes before linearized reading' (duration: 8.996127856s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.306782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.308669Z","time spent":"8.998105973s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-21T03:26:03.304878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.260828784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-08-21T03:26:03.306833Z","caller":"traceutil/trace.go:171","msg":"trace[2054879340] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27982; }","duration":"3.262823081s","start":"2026-08-21T03:26:00.043998Z","end":"2026-08-21T03:26:03.306821Z","steps":["trace[2054879340] 'agreement among raft nodes before linearized reading' (duration: 3.260817423s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.306871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:00.043977Z","time spent":"3.262883884s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-08-21T03:26:03.304917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.123441945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.306951Z","caller":"traceutil/trace.go:171","msg":"trace[69859546] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:27982; }","duration":"9.125472716s","start":"2026-08-21T03:25:54.181466Z","end":"2026-08-21T03:26:03.306938Z","steps":["trace[69859546] 'agreement among raft nodes before linearized reading' (duration: 9.123423396s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.306989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.181431Z","time spent":"9.125548007s","remote":"127.0.0.1:39502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.304935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.905675254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.307044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.154071253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-08-21T03:26:03.3071Z","caller":"traceutil/trace.go:171","msg":"trace[1806674502] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:27982; }","duration":"5.907888287s","start":"2026-08-21T03:25:57.399201Z","end":"2026-08-21T03:26:03.307089Z","steps":["trace[1806674502] 'agreement among raft nodes before linearized reading' (duration: 5.905698784s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.307119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.247556873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement-ks-endpoints-m7bbs.18cdb2a8b0163032\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:27967 ","response":"range_response_count:406 size:310996"} {"level":"warn","ts":"2026-08-21T03:26:03.307139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:57.399176Z","time spent":"5.907952349s","remote":"127.0.0.1:55032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-08-21T03:26:03.307134Z","caller":"traceutil/trace.go:171","msg":"trace[1977895115] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:27982; }","duration":"8.154180495s","start":"2026-08-21T03:25:55.152926Z","end":"2026-08-21T03:26:03.307107Z","steps":["trace[1977895115] 'agreement among raft nodes before linearized reading' (duration: 8.152315469s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.304977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.158871963s","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-21T03:26:03.307169Z","caller":"traceutil/trace.go:171","msg":"trace[1470354164] range","detail":"{range_begin:/registry/events/openstack/placement-ks-endpoints-m7bbs.18cdb2a8b0163032\u0000; range_end:/registry/events/openstack0; response_count:406; response_revision:27982; }","duration":"10.247600854s","start":"2026-08-21T03:25:53.059544Z","end":"2026-08-21T03:26:03.307145Z","steps":["trace[1470354164] 'agreement among raft nodes before linearized reading' (duration: 10.245606415s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.307187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.152911Z","time spent":"8.154263237s","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-08-21T03:26:03.307196Z","caller":"traceutil/trace.go:171","msg":"trace[713467255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27982; }","duration":"9.161079596s","start":"2026-08-21T03:25:54.1461Z","end":"2026-08-21T03:26:03.30718Z","steps":["trace[713467255] 'agreement among raft nodes before linearized reading' (duration: 9.158828792s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.307232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.059493Z","time spent":"10.247708106s","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":406,"response size":311018,"request content":"key:\"/registry/events/openstack/placement-ks-endpoints-m7bbs.18cdb2a8b0163032\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:27967 "} {"level":"warn","ts":"2026-08-21T03:26:03.307234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:54.146064Z","time spent":"9.161159198s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T03:26:03.30499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.121885897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:624"} {"level":"info","ts":"2026-08-21T03:26:03.307438Z","caller":"traceutil/trace.go:171","msg":"trace[2051564104] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:27982; }","duration":"4.124393507s","start":"2026-08-21T03:25:59.183033Z","end":"2026-08-21T03:26:03.307427Z","steps":["trace[2051564104] 'agreement among raft nodes before linearized reading' (duration: 4.121895697s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.307473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:59.182999Z","time spent":"4.124464219s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":646,"request content":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" "} {"level":"warn","ts":"2026-08-21T03:26:03.305023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.837595464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.307533Z","caller":"traceutil/trace.go:171","msg":"trace[1740167955] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:27982; }","duration":"9.840137786s","start":"2026-08-21T03:25:53.467381Z","end":"2026-08-21T03:26:03.307518Z","steps":["trace[1740167955] 'agreement among raft nodes before linearized reading' (duration: 9.837610554s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.30759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.467358Z","time spent":"9.840215568s","remote":"127.0.0.1:51028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.305056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.262004973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-system/controller-leader-election-capi\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-08-21T03:26:03.3051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.170827697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-rabbit-init-jkz2j\" ","response":"range_response_count:1 size:6828"} {"level":"info","ts":"2026-08-21T03:26:03.30775Z","caller":"traceutil/trace.go:171","msg":"trace[1333063879] range","detail":"{range_begin:/registry/pods/openstack/neutron-rabbit-init-jkz2j; range_end:; response_count:1; response_revision:27982; }","duration":"10.173510262s","start":"2026-08-21T03:25:53.134228Z","end":"2026-08-21T03:26:03.307739Z","steps":["trace[1333063879] 'agreement among raft nodes before linearized reading' (duration: 10.170824587s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.307785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.134208Z","time spent":"10.173567583s","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6850,"request content":"key:\"/registry/pods/openstack/neutron-rabbit-init-jkz2j\" "} {"level":"warn","ts":"2026-08-21T03:26:03.305091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.262117256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capo-system/controller-leader-election-capo\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-08-21T03:26:03.307883Z","caller":"traceutil/trace.go:171","msg":"trace[1418575182] range","detail":"{range_begin:/registry/leases/capo-system/controller-leader-election-capo; range_end:; response_count:1; response_revision:27982; }","duration":"3.264930853s","start":"2026-08-21T03:26:00.042941Z","end":"2026-08-21T03:26:03.307872Z","steps":["trace[1418575182] 'agreement among raft nodes before linearized reading' (duration: 3.262129345s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.307918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:00.042921Z","time spent":"3.264984235s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":563,"request content":"key:\"/registry/leases/capo-system/controller-leader-election-capo\" "} {"level":"warn","ts":"2026-08-21T03:26:03.305136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.217873236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.307988Z","caller":"traceutil/trace.go:171","msg":"trace[593775512] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:27982; }","duration":"10.220735764s","start":"2026-08-21T03:25:53.087229Z","end":"2026-08-21T03:26:03.307965Z","steps":["trace[593775512] 'agreement among raft nodes before linearized reading' (duration: 10.217884425s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.308044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:53.087213Z","time spent":"10.220815237s","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.305103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.936620478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-08-21T03:26:03.308233Z","caller":"traceutil/trace.go:171","msg":"trace[1355076919] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:27982; }","duration":"4.939817115s","start":"2026-08-21T03:25:58.368391Z","end":"2026-08-21T03:26:03.308208Z","steps":["trace[1355076919] 'agreement among raft nodes before linearized reading' (duration: 4.936471154s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.305212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.343536199s","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-21T03:26:03.30512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.076407268s","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-21T03:26:03.305279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.817367413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" ","response":"range_response_count:1 size:517"} {"level":"warn","ts":"2026-08-21T03:26:03.30527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.469296355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-21T03:26:03.305288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.753742663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-08-21T03:26:03.305325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.260869726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.260006595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.260137649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.259845192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-08-21T03:26:03.305392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.077617588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-08-21T03:26:03.305396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.221210097s","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":"warn","ts":"2026-08-21T03:26:03.305447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.262141177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164890834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97613417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.30548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.725689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.261095852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.616496369s","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":"warn","ts":"2026-08-21T03:26:03.305516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.249387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.308976Z","caller":"traceutil/trace.go:171","msg":"trace[1666016693] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:27982; }","duration":"8.265678212s","start":"2026-08-21T03:25:55.043291Z","end":"2026-08-21T03:26:03.308969Z","steps":["trace[1666016693] 'agreement among raft nodes before linearized reading' (duration: 8.262137417s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.309025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.043284Z","time spent":"8.265728413s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"warn","ts":"2026-08-21T03:26:03.305555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.010152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:26:03.309199Z","caller":"traceutil/trace.go:171","msg":"trace[1240897635] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:27982; }","duration":"447.68007ms","start":"2026-08-21T03:26:02.8615Z","end":"2026-08-21T03:26:03.309181Z","steps":["trace[1240897635] 'agreement among raft nodes before linearized reading' (duration: 444.030412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.309253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:02.861472Z","time spent":"447.755342ms","remote":"127.0.0.1:59578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"info","ts":"2026-08-21T03:26:03.309169Z","caller":"traceutil/trace.go:171","msg":"trace[2078150169] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:27982; }","duration":"294.765451ms","start":"2026-08-21T03:26:03.01423Z","end":"2026-08-21T03:26:03.308995Z","steps":["trace[2078150169] 'agreement among raft nodes before linearized reading' (duration: 291.264636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.305587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.763705ms","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-21T03:26:03.30948Z","caller":"traceutil/trace.go:171","msg":"trace[102596996] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:27982; }","duration":"778.69345ms","start":"2026-08-21T03:26:02.530775Z","end":"2026-08-21T03:26:03.309468Z","steps":["trace[102596996] 'agreement among raft nodes before linearized reading' (duration: 774.794676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.309523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:02.530754Z","time spent":"778.757401ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"info","ts":"2026-08-21T03:26:03.309572Z","caller":"traceutil/trace.go:171","msg":"trace[103556028] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:27982; }","duration":"8.265200411s","start":"2026-08-21T03:25:55.044356Z","end":"2026-08-21T03:26:03.309556Z","steps":["trace[103556028] 'agreement among raft nodes before linearized reading' (duration: 8.261105692s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.309612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.044345Z","time spent":"8.265255672s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:26:03.305593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.171187841s","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-21T03:26:03.309757Z","caller":"traceutil/trace.go:171","msg":"trace[717337030] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:27982; }","duration":"1.980464005s","start":"2026-08-21T03:26:01.329272Z","end":"2026-08-21T03:26:03.309736Z","steps":["trace[717337030] 'agreement among raft nodes before linearized reading' (duration: 1.976154401s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.309779Z","caller":"traceutil/trace.go:171","msg":"trace[1910611116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27982; }","duration":"2.175414013s","start":"2026-08-21T03:26:01.134353Z","end":"2026-08-21T03:26:03.309767Z","steps":["trace[1910611116] 'agreement among raft nodes before linearized reading' (duration: 2.171194551s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.309797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:01.329247Z","time spent":"1.980529247s","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.309819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:01.134322Z","time spent":"2.175485145s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-21T03:26:03.309928Z","caller":"traceutil/trace.go:171","msg":"trace[1802725100] 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:27982; }","duration":"1.620956138s","start":"2026-08-21T03:26:01.688957Z","end":"2026-08-21T03:26:03.309913Z","steps":["trace[1802725100] 'agreement among raft nodes before linearized reading' (duration: 1.61652671s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.31022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:01.688935Z","time spent":"1.62104489s","remote":"127.0.0.1:59808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-08-21T03:26:03.309941Z","caller":"traceutil/trace.go:171","msg":"trace[817477575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27982; }","duration":"139.184297ms","start":"2026-08-21T03:26:03.170747Z","end":"2026-08-21T03:26:03.309931Z","steps":["trace[817477575] 'agreement among raft nodes before linearized reading' (duration: 134.717719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.305617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.224030089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-21T03:26:03.310707Z","caller":"traceutil/trace.go:171","msg":"trace[1548392219] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:27982; }","duration":"2.229157533s","start":"2026-08-21T03:26:01.081535Z","end":"2026-08-21T03:26:03.310692Z","steps":["trace[1548392219] 'agreement among raft nodes before linearized reading' (duration: 2.223991178s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.305619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1482132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.439935132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-21T03:26:03.305658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.262628638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.305545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.742869061s","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-21T03:26:03.30569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.262594668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-21T03:26:03.310943Z","caller":"traceutil/trace.go:171","msg":"trace[1911014659] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:27982; }","duration":"8.267920947s","start":"2026-08-21T03:25:55.043014Z","end":"2026-08-21T03:26:03.310935Z","steps":["trace[1911014659] 'agreement among raft nodes before linearized reading' (duration: 8.262628019s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.310976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.043008Z","time spent":"8.267961798s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"info","ts":"2026-08-21T03:26:03.31102Z","caller":"traceutil/trace.go:171","msg":"trace[1647109726] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27982; }","duration":"8.267962438s","start":"2026-08-21T03:25:55.043044Z","end":"2026-08-21T03:26:03.311006Z","steps":["trace[1647109726] 'agreement among raft nodes before linearized reading' (duration: 8.262595978s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.308334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:58.36836Z","time spent":"4.939962129s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-08-21T03:26:03.30836Z","caller":"traceutil/trace.go:171","msg":"trace[8343123] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:27982; }","duration":"4.346739526s","start":"2026-08-21T03:25:58.961612Z","end":"2026-08-21T03:26:03.308352Z","steps":["trace[8343123] 'agreement among raft nodes before linearized reading' (duration: 4.34355794s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308381Z","caller":"traceutil/trace.go:171","msg":"trace[530997134] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27982; }","duration":"7.079710838s","start":"2026-08-21T03:25:56.228659Z","end":"2026-08-21T03:26:03.30837Z","steps":["trace[530997134] 'agreement among raft nodes before linearized reading' (duration: 7.076343447s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308411Z","caller":"traceutil/trace.go:171","msg":"trace[263372350] range","detail":"{range_begin:/registry/leases/orc-system/f35396c5.k-orc.cloud; range_end:; response_count:1; response_revision:27982; }","duration":"4.82053756s","start":"2026-08-21T03:25:58.487865Z","end":"2026-08-21T03:26:03.308403Z","steps":["trace[263372350] 'agreement among raft nodes before linearized reading' (duration: 4.817364583s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308435Z","caller":"traceutil/trace.go:171","msg":"trace[1239491845] range","detail":"{range_begin:/registry/leases/capi-system/controller-leader-election-capi; range_end:; response_count:1; response_revision:27982; }","duration":"3.264615586s","start":"2026-08-21T03:26:00.043013Z","end":"2026-08-21T03:26:03.307629Z","steps":["trace[1239491845] 'agreement among raft nodes before linearized reading' (duration: 3.262013553s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308454Z","caller":"traceutil/trace.go:171","msg":"trace[2025030206] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:27982; }","duration":"7.472517152s","start":"2026-08-21T03:25:55.835931Z","end":"2026-08-21T03:26:03.308449Z","steps":["trace[2025030206] 'agreement among raft nodes before linearized reading' (duration: 7.469262513s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308477Z","caller":"traceutil/trace.go:171","msg":"trace[1818235778] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:27982; }","duration":"4.756955011s","start":"2026-08-21T03:25:58.551515Z","end":"2026-08-21T03:26:03.30847Z","steps":["trace[1818235778] 'agreement among raft nodes before linearized reading' (duration: 4.753676172s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308501Z","caller":"traceutil/trace.go:171","msg":"trace[663103400] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:27982; }","duration":"8.264076114s","start":"2026-08-21T03:25:55.044418Z","end":"2026-08-21T03:26:03.308494Z","steps":["trace[663103400] 'agreement among raft nodes before linearized reading' (duration: 8.260886977s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308754Z","caller":"traceutil/trace.go:171","msg":"trace[1576108856] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:27982; }","duration":"7.08100786s","start":"2026-08-21T03:25:56.227721Z","end":"2026-08-21T03:26:03.308728Z","steps":["trace[1576108856] 'agreement among raft nodes before linearized reading' (duration: 7.077582878s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308838Z","caller":"traceutil/trace.go:171","msg":"trace[414294382] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:27982; }","duration":"8.26348718s","start":"2026-08-21T03:25:55.045312Z","end":"2026-08-21T03:26:03.308799Z","steps":["trace[414294382] 'agreement among raft nodes before linearized reading' (duration: 8.260000946s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.30887Z","caller":"traceutil/trace.go:171","msg":"trace[1331133553] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:27982; }","duration":"8.263666665s","start":"2026-08-21T03:25:55.045193Z","end":"2026-08-21T03:26:03.30886Z","steps":["trace[1331133553] 'agreement among raft nodes before linearized reading' (duration: 8.26014614s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308895Z","caller":"traceutil/trace.go:171","msg":"trace[1785241200] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:27982; }","duration":"8.263360077s","start":"2026-08-21T03:25:55.045527Z","end":"2026-08-21T03:26:03.308887Z","steps":["trace[1785241200] 'agreement among raft nodes before linearized reading' (duration: 8.259819272s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308924Z","caller":"traceutil/trace.go:171","msg":"trace[1890489550] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:27982; }","duration":"1.168396759s","start":"2026-08-21T03:26:02.140517Z","end":"2026-08-21T03:26:03.308914Z","steps":["trace[1890489550] 'agreement among raft nodes before linearized reading' (duration: 1.164905895s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.308952Z","caller":"traceutil/trace.go:171","msg":"trace[291777523] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:27982; }","duration":"8.224786753s","start":"2026-08-21T03:25:55.084155Z","end":"2026-08-21T03:26:03.308942Z","steps":["trace[291777523] 'agreement among raft nodes before linearized reading' (duration: 8.221219517s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.305524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.948888832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" ","response":"range_response_count:1 size:646"} {"level":"warn","ts":"2026-08-21T03:26:03.310838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:01.081513Z","time spent":"2.229301236s","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":89,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-08-21T03:26:03.310888Z","caller":"traceutil/trace.go:171","msg":"trace[592200399] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:27982; }","duration":"1.153470857s","start":"2026-08-21T03:26:02.157397Z","end":"2026-08-21T03:26:03.310868Z","steps":["trace[592200399] 'agreement among raft nodes before linearized reading' (duration: 1.14820281s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:26:03.310918Z","caller":"traceutil/trace.go:171","msg":"trace[113100698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27982; }","duration":"2.445254371s","start":"2026-08-21T03:26:00.865653Z","end":"2026-08-21T03:26:03.310907Z","steps":["trace[113100698] 'agreement among raft nodes before linearized reading' (duration: 2.439963183s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.311153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.043017Z","time spent":"8.268127881s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"info","ts":"2026-08-21T03:26:03.311186Z","caller":"traceutil/trace.go:171","msg":"trace[1230477916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27982; }","duration":"2.74856371s","start":"2026-08-21T03:26:00.56261Z","end":"2026-08-21T03:26:03.311174Z","steps":["trace[1230477916] 'agreement among raft nodes before linearized reading' (duration: 2.74277769s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.304984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.371488174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:26:03.311213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:58.96157Z","time spent":"4.349635056s","remote":"127.0.0.1:37784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.311512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:58.487818Z","time spent":"4.823676125s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":539,"request content":"key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" "} {"level":"warn","ts":"2026-08-21T03:26:03.311547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:56.228632Z","time spent":"7.082899115s","remote":"127.0.0.1:54796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-21T03:26:03.311554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:00.042997Z","time spent":"3.268547861s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":563,"request content":"key:\"/registry/leases/capi-system/controller-leader-election-capi\" "} {"level":"warn","ts":"2026-08-21T03:26:03.315251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.045184Z","time spent":"8.270034218s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:26:03.315421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.83591Z","time spent":"7.479497632s","remote":"127.0.0.1:57414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.315401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.045521Z","time spent":"8.269848665s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"warn","ts":"2026-08-21T03:26:03.315433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:56.227696Z","time spent":"7.087726343s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-21T03:26:03.315538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.045308Z","time spent":"8.270223594s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:26:03.315557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:02.140497Z","time spent":"1.17504927s","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.315676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.084142Z","time spent":"8.231526327s","remote":"127.0.0.1:39308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-08-21T03:26:03.315803Z","caller":"traceutil/trace.go:171","msg":"trace[1729447615] range","detail":"{range_begin:/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi; range_end:; response_count:1; response_revision:27982; }","duration":"1.959193202s","start":"2026-08-21T03:26:01.356591Z","end":"2026-08-21T03:26:03.315784Z","steps":["trace[1729447615] 'agreement among raft nodes before linearized reading' (duration: 1.948907992s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.31584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:01.356569Z","time spent":"1.959263684s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":668,"request content":"key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" "} {"level":"warn","ts":"2026-08-21T03:26:03.315983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:02.157374Z","time spent":"1.158594642s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.316099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:00.86561Z","time spent":"2.450481198s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-21T03:26:03.316217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:58.551504Z","time spent":"4.764703579s","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.90\" "} {"level":"warn","ts":"2026-08-21T03:26:03.316242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:26:00.56258Z","time spent":"2.753655192s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-21T03:26:03.316343Z","caller":"traceutil/trace.go:171","msg":"trace[1676800417] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:27982; }","duration":"6.382867419s","start":"2026-08-21T03:25:56.933455Z","end":"2026-08-21T03:26:03.316322Z","steps":["trace[1676800417] 'agreement among raft nodes before linearized reading' (duration: 6.371501574s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:26:03.31637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:56.933432Z","time spent":"6.382931561s","remote":"127.0.0.1:51568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:26:03.317009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:25:55.044401Z","time spent":"8.272565539s","remote":"127.0.0.1:54860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"info","ts":"2026-08-21T03:27:45.883896Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000007424.wal"}