{"level":"warn","ts":"2026-08-13T08:20:25.984969Z","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-13T08:20:25.985114Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.61:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.61:2380","--initial-cluster=instance=https://199.204.45.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.61: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-13T08:20:25.985203Z","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-13T08:20:25.985217Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-13T08:20:25.985239Z","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-13T08:20:25.985904Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"]} {"level":"info","ts":"2026-08-13T08:20:25.985985Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.61: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-13T08:20:25.988835Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.585328ms"} {"level":"info","ts":"2026-08-13T08:20:25.993154Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca256638ea38eb43","cluster-id":"dfd7ac400ae20102"} {"level":"info","ts":"2026-08-13T08:20:25.993239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T08:20:25.993271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 0"} {"level":"info","ts":"2026-08-13T08:20:25.99329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca256638ea38eb43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T08:20:25.993306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 1"} {"level":"info","ts":"2026-08-13T08:20:25.993354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"warn","ts":"2026-08-13T08:20:25.998104Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T08:20:25.99951Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T08:20:26.000659Z","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-13T08:20:26.004055Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca256638ea38eb43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T08:20:26.004241Z","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-13T08:20:26.004469Z","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-13T08:20:26.004339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca256638ea38eb43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T08:20:26.004515Z","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-13T08:20:26.005696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"info","ts":"2026-08-13T08:20:26.00612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","added-peer-id":"ca256638ea38eb43","added-peer-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-13T08:20:26.009341Z","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-13T08:20:26.009509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-13T08:20:26.009571Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-13T08:20:26.00973Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca256638ea38eb43","initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T08:20:26.009771Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T08:20:26.594524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T08:20:26.594587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T08:20:26.594626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgPreVoteResp from ca256638ea38eb43 at term 1"} {"level":"info","ts":"2026-08-13T08:20:26.594646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became candidate at term 2"} {"level":"info","ts":"2026-08-13T08:20:26.594657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgVoteResp from ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-13T08:20:26.594672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became leader at term 2"} {"level":"info","ts":"2026-08-13T08:20:26.59469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca256638ea38eb43 elected leader ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-13T08:20:26.595563Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:20:26.596578Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca256638ea38eb43","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.61:2379]}","request-path":"/0/members/ca256638ea38eb43/attributes","cluster-id":"dfd7ac400ae20102","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T08:20:26.596594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T08:20:26.596637Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T08:20:26.597011Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T08:20:26.597191Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T08:20:26.597272Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:20:26.597412Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:20:26.597461Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T08:20:26.601203Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T08:20:26.604856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.61:2379"} {"level":"info","ts":"2026-08-13T08:20:29.404735Z","caller":"traceutil/trace.go:171","msg":"trace[367398148] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"164.842405ms","start":"2026-08-13T08:20:29.239854Z","end":"2026-08-13T08:20:29.404696Z","steps":["trace[367398148] 'process raft request' (duration: 95.809981ms)","trace[367398148] 'compare' (duration: 68.84704ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:20:38.480628Z","caller":"traceutil/trace.go:171","msg":"trace[534166074] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"119.713876ms","start":"2026-08-13T08:20:38.360884Z","end":"2026-08-13T08:20:38.480598Z","steps":["trace[534166074] 'read index received' (duration: 119.479752ms)","trace[534166074] 'applied index is now lower than readState.Index' (duration: 232.534µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:20:38.480724Z","caller":"traceutil/trace.go:171","msg":"trace[156429289] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"188.677377ms","start":"2026-08-13T08:20:38.292021Z","end":"2026-08-13T08:20:38.480698Z","steps":["trace[156429289] 'process raft request' (duration: 188.3315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:20:38.480864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.95311ms","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-13T08:20:38.48102Z","caller":"traceutil/trace.go:171","msg":"trace[1576538201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"120.153063ms","start":"2026-08-13T08:20:38.360832Z","end":"2026-08-13T08:20:38.480985Z","steps":["trace[1576538201] 'agreement among raft nodes before linearized reading' (duration: 119.891248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:05.672039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.678206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:21:05.672206Z","caller":"traceutil/trace.go:171","msg":"trace[42994585] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"223.908511ms","start":"2026-08-13T08:21:05.448272Z","end":"2026-08-13T08:21:05.67218Z","steps":["trace[42994585] 'read index received' (duration: 111.141385ms)","trace[42994585] 'applied index is now lower than readState.Index' (duration: 112.765406ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:21:05.672318Z","caller":"traceutil/trace.go:171","msg":"trace[2111486876] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"234.711282ms","start":"2026-08-13T08:21:05.437574Z","end":"2026-08-13T08:21:05.672286Z","steps":["trace[2111486876] 'process raft request' (duration: 121.909565ms)","trace[2111486876] 'compare' (duration: 111.482992ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:21:05.672496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.200348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-08-13T08:21:05.672593Z","caller":"traceutil/trace.go:171","msg":"trace[34750738] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:603; }","duration":"224.3331ms","start":"2026-08-13T08:21:05.448237Z","end":"2026-08-13T08:21:05.67257Z","steps":["trace[34750738] 'agreement among raft nodes before linearized reading' (duration: 224.103715ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:21:05.686024Z","caller":"traceutil/trace.go:171","msg":"trace[1477980528] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"199.852115ms","start":"2026-08-13T08:21:05.486152Z","end":"2026-08-13T08:21:05.686004Z","steps":["trace[1477980528] 'process raft request' (duration: 199.497558ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:21:09.749583Z","caller":"traceutil/trace.go:171","msg":"trace[1390027198] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"255.877346ms","start":"2026-08-13T08:21:09.493665Z","end":"2026-08-13T08:21:09.749542Z","steps":["trace[1390027198] 'read index received' (duration: 255.574101ms)","trace[1390027198] 'applied index is now lower than readState.Index' (duration: 300.835µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:21:09.749697Z","caller":"traceutil/trace.go:171","msg":"trace[1708797182] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"563.397443ms","start":"2026-08-13T08:21:09.186235Z","end":"2026-08-13T08:21:09.749632Z","steps":["trace[1708797182] 'process raft request' (duration: 563.011186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:09.749964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.237413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-13T08:21:09.749976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:09.186204Z","time spent":"563.627917ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:21:09.750038Z","caller":"traceutil/trace.go:171","msg":"trace[683071371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"256.357745ms","start":"2026-08-13T08:21:09.493635Z","end":"2026-08-13T08:21:09.749993Z","steps":["trace[683071371] 'agreement among raft nodes before linearized reading' (duration: 256.07414ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:21:09.857077Z","caller":"traceutil/trace.go:171","msg":"trace[1832536597] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"359.061165ms","start":"2026-08-13T08:21:09.497962Z","end":"2026-08-13T08:21:09.857023Z","steps":["trace[1832536597] 'process raft request' (duration: 354.541081ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:21:09.857177Z","caller":"traceutil/trace.go:171","msg":"trace[1190691285] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"101.196281ms","start":"2026-08-13T08:21:09.755964Z","end":"2026-08-13T08:21:09.85716Z","steps":["trace[1190691285] 'process raft request' (duration: 100.979797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:09.85732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:09.497908Z","time spent":"359.294349ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:21:11.212274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569319337823846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:21:11.712937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569319337823846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:21:12.213714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569319337823846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:21:12.713888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569319337823846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:21:12.877949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999823334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-13T08:21:12.878076Z","caller":"traceutil/trace.go:171","msg":"trace[1900278844] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000033178s","start":"2026-08-13T08:21:10.878008Z","end":"2026-08-13T08:21:12.878041Z","steps":["trace[1900278844] 'agreement among raft nodes before linearized reading' (duration: 1.999876025s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:12.878134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:10.877974Z","time spent":"2.00015038s","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/13 08:21:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-13T08:21:13.155851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.953251ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:21:13.155985Z","caller":"traceutil/trace.go:171","msg":"trace[1013935944] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:623; }","duration":"445.096494ms","start":"2026-08-13T08:21:12.710864Z","end":"2026-08-13T08:21:13.155961Z","steps":["trace[1013935944] 'range keys from in-memory index tree' (duration: 444.934961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:13.157229Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.20676335s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T08:21:13.158418Z","caller":"traceutil/trace.go:171","msg":"trace[49571449] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"2.44664287s","start":"2026-08-13T08:21:10.711745Z","end":"2026-08-13T08:21:13.158388Z","steps":["trace[49571449] 'read index received' (duration: 2.445784115s)","trace[49571449] 'applied index is now lower than readState.Index' (duration: 857.595µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:21:13.158763Z","caller":"traceutil/trace.go:171","msg":"trace[1082796539] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"2.481718482s","start":"2026-08-13T08:21:10.676973Z","end":"2026-08-13T08:21:13.158691Z","steps":["trace[1082796539] 'process raft request' (duration: 2.480714683s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:13.158853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.447071458s","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-13T08:21:13.158937Z","caller":"traceutil/trace.go:171","msg":"trace[121212359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"2.447249942s","start":"2026-08-13T08:21:10.711665Z","end":"2026-08-13T08:21:13.158915Z","steps":["trace[121212359] 'agreement among raft nodes before linearized reading' (duration: 2.446988177s)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:21:13.158952Z","caller":"traceutil/trace.go:171","msg":"trace[1352146790] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"189.779858ms","start":"2026-08-13T08:21:12.969139Z","end":"2026-08-13T08:21:13.158919Z","steps":["trace[1352146790] 'process raft request' (duration: 189.629805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:13.159006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:10.711631Z","time spent":"2.447359333s","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T08:21:13.159066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:10.67693Z","time spent":"2.481996637s","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:21:13.159466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403578051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-13T08:21:13.159554Z","caller":"traceutil/trace.go:171","msg":"trace[702120457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.403738464s","start":"2026-08-13T08:21:11.75579Z","end":"2026-08-13T08:21:13.159529Z","steps":["trace[702120457] 'agreement among raft nodes before linearized reading' (duration: 1.403429038s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:13.159584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233323696s","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-13T08:21:13.159622Z","caller":"traceutil/trace.go:171","msg":"trace[492275943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"1.233407728s","start":"2026-08-13T08:21:11.926203Z","end":"2026-08-13T08:21:13.159611Z","steps":["trace[492275943] 'agreement among raft nodes before linearized reading' (duration: 1.233344346s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:13.159665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:11.92618Z","time spent":"1.233477658s","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:21:13.159613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:11.755764Z","time spent":"1.403834775s","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T08:21:13.159749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29868684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-13T08:21:13.159489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.877261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:21:13.159798Z","caller":"traceutil/trace.go:171","msg":"trace[146336051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.298735722s","start":"2026-08-13T08:21:11.861048Z","end":"2026-08-13T08:21:13.159784Z","steps":["trace[146336051] 'agreement among raft nodes before linearized reading' (duration: 1.298543508s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:13.159856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:11.860975Z","time spent":"1.298872134s","remote":"127.0.0.1:48984","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-13T08:21:13.1598Z","caller":"traceutil/trace.go:171","msg":"trace[1250636473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"281.249668ms","start":"2026-08-13T08:21:12.878541Z","end":"2026-08-13T08:21:13.15979Z","steps":["trace[1250636473] 'agreement among raft nodes before linearized reading' (duration: 280.898291ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:21:14.244736Z","caller":"traceutil/trace.go:171","msg":"trace[2118456372] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"364.198531ms","start":"2026-08-13T08:21:13.880506Z","end":"2026-08-13T08:21:14.244705Z","steps":["trace[2118456372] 'read index received' (duration: 286.485876ms)","trace[2118456372] 'applied index is now lower than readState.Index' (duration: 77.711795ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:21:14.244912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.392574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:21:14.244942Z","caller":"traceutil/trace.go:171","msg":"trace[309663060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"364.482226ms","start":"2026-08-13T08:21:13.880449Z","end":"2026-08-13T08:21:14.244931Z","steps":["trace[309663060] 'agreement among raft nodes before linearized reading' (duration: 364.381604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:21:14.244969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:13.880426Z","time spent":"364.536886ms","remote":"127.0.0.1:48756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T08:21:14.244943Z","caller":"traceutil/trace.go:171","msg":"trace[336522100] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"442.586277ms","start":"2026-08-13T08:21:13.802308Z","end":"2026-08-13T08:21:14.244894Z","steps":["trace[336522100] 'process raft request' (duration: 364.594537ms)","trace[336522100] 'compare' (duration: 77.647634ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:21:14.245146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:21:13.802275Z","time spent":"442.781151ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T08:21:28.541145Z","caller":"traceutil/trace.go:171","msg":"trace[1570525057] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"164.537645ms","start":"2026-08-13T08:21:28.376565Z","end":"2026-08-13T08:21:28.541103Z","steps":["trace[1570525057] 'process raft request' (duration: 163.392872ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:22:02.224215Z","caller":"traceutil/trace.go:171","msg":"trace[169000748] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"260.314782ms","start":"2026-08-13T08:22:01.963877Z","end":"2026-08-13T08:22:02.224192Z","steps":["trace[169000748] 'process raft request' (duration: 260.134939ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:22:02.596195Z","caller":"traceutil/trace.go:171","msg":"trace[299356990] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"111.008641ms","start":"2026-08-13T08:22:02.485117Z","end":"2026-08-13T08:22:02.596125Z","steps":["trace[299356990] 'read index received' (duration: 109.453981ms)","trace[299356990] 'applied index is now lower than readState.Index' (duration: 1.55295ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:22:02.596192Z","caller":"traceutil/trace.go:171","msg":"trace[731839412] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"360.460184ms","start":"2026-08-13T08:22:02.235679Z","end":"2026-08-13T08:22:02.596139Z","steps":["trace[731839412] 'process raft request' (duration: 358.986836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:02.596353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.225256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:22:02.596432Z","caller":"traceutil/trace.go:171","msg":"trace[1832759264] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:0; response_revision:1230; }","duration":"111.336088ms","start":"2026-08-13T08:22:02.485038Z","end":"2026-08-13T08:22:02.596374Z","steps":["trace[1832759264] 'agreement among raft nodes before linearized reading' (duration: 111.237866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:02.596556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:02.235669Z","time spent":"360.670588ms","remote":"127.0.0.1:48956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:22:03.049327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.521952ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:22:03.0496Z","caller":"traceutil/trace.go:171","msg":"trace[467611504] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1231; }","duration":"338.787257ms","start":"2026-08-13T08:22:02.710724Z","end":"2026-08-13T08:22:03.049511Z","steps":["trace[467611504] 'range keys from in-memory index tree' (duration: 338.489301ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:22:03.049406Z","caller":"traceutil/trace.go:171","msg":"trace[1700221391] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"436.536153ms","start":"2026-08-13T08:22:02.612832Z","end":"2026-08-13T08:22:03.049369Z","steps":["trace[1700221391] 'process raft request' (duration: 435.735468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:03.049827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:02.612813Z","time spent":"436.869989ms","remote":"127.0.0.1:55988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:22:03.055586Z","caller":"traceutil/trace.go:171","msg":"trace[2001944139] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1232; }","duration":"442.243332ms","start":"2026-08-13T08:22:02.613313Z","end":"2026-08-13T08:22:03.055557Z","steps":["trace[2001944139] 'process raft request' (duration: 441.936036ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:22:03.055688Z","caller":"traceutil/trace.go:171","msg":"trace[609145007] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"440.613282ms","start":"2026-08-13T08:22:02.615013Z","end":"2026-08-13T08:22:03.055626Z","steps":["trace[609145007] 'process raft request' (duration: 440.373187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:03.055768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:02.613295Z","time spent":"442.372274ms","remote":"127.0.0.1:55988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":70,"response count":0,"response size":1555,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:22:03.055921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:02.614984Z","time spent":"440.822905ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:22:03.05604Z","caller":"traceutil/trace.go:171","msg":"trace[1938476290] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"440.968159ms","start":"2026-08-13T08:22:02.615056Z","end":"2026-08-13T08:22:03.056024Z","steps":["trace[1938476290] 'process raft request' (duration: 440.418388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:03.05613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:02.615039Z","time spent":"441.067661ms","remote":"127.0.0.1:55984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T08:22:38.153001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.805704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:22:38.15311Z","caller":"traceutil/trace.go:171","msg":"trace[505531140] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1758; }","duration":"334.971487ms","start":"2026-08-13T08:22:37.81812Z","end":"2026-08-13T08:22:38.153092Z","steps":["trace[505531140] 'count revisions from in-memory index tree' (duration: 334.663892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:38.153146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:37.818089Z","time spent":"335.045669ms","remote":"127.0.0.1:45620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":14,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:22:39.41019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.221652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:22:39.41037Z","caller":"traceutil/trace.go:171","msg":"trace[1519453607] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"245.525168ms","start":"2026-08-13T08:22:39.164825Z","end":"2026-08-13T08:22:39.41035Z","steps":["trace[1519453607] 'compare' (duration: 243.880486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:40.4143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.948283ms","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-13T08:22:40.414405Z","caller":"traceutil/trace.go:171","msg":"trace[2100901529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1770; }","duration":"797.103457ms","start":"2026-08-13T08:22:39.61728Z","end":"2026-08-13T08:22:40.414383Z","steps":["trace[2100901529] 'range keys from in-memory index tree' (duration: 796.7591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:40.414445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:39.617265Z","time spent":"797.173198ms","remote":"127.0.0.1:48984","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-13T08:22:40.414647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.932019ms","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-13T08:22:40.414783Z","caller":"traceutil/trace.go:171","msg":"trace[1122507968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1770; }","duration":"801.135564ms","start":"2026-08-13T08:22:39.613626Z","end":"2026-08-13T08:22:40.414762Z","steps":["trace[1122507968] 'range keys from in-memory index tree' (duration: 800.780616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:40.414817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:39.613591Z","time spent":"801.219446ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T08:22:40.415052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.616807ms","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-13T08:22:40.415081Z","caller":"traceutil/trace.go:171","msg":"trace[153914350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1770; }","duration":"798.689478ms","start":"2026-08-13T08:22:39.616386Z","end":"2026-08-13T08:22:40.415075Z","steps":["trace[153914350] 'range keys from in-memory index tree' (duration: 798.532904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:40.415101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:39.616371Z","time spent":"798.724749ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T08:22:40.415409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.585096ms","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-13T08:22:40.415493Z","caller":"traceutil/trace.go:171","msg":"trace[151621599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1770; }","duration":"797.708509ms","start":"2026-08-13T08:22:39.617767Z","end":"2026-08-13T08:22:40.415475Z","steps":["trace[151621599] 'range keys from in-memory index tree' (duration: 797.366093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:22:40.415537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:22:39.617758Z","time spent":"797.77112ms","remote":"127.0.0.1:48984","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-13T08:22:49.347808Z","caller":"traceutil/trace.go:171","msg":"trace[1939084004] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"146.177716ms","start":"2026-08-13T08:22:49.201592Z","end":"2026-08-13T08:22:49.34777Z","steps":["trace[1939084004] 'process raft request' (duration: 145.941282ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:22:49.739157Z","caller":"traceutil/trace.go:171","msg":"trace[446466613] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"222.778872ms","start":"2026-08-13T08:22:49.516351Z","end":"2026-08-13T08:22:49.73913Z","steps":["trace[446466613] 'process raft request' (duration: 184.958738ms)","trace[446466613] 'compare' (duration: 37.711022ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:23:00.818432Z","caller":"traceutil/trace.go:171","msg":"trace[1180295677] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"139.827055ms","start":"2026-08-13T08:23:00.678571Z","end":"2026-08-13T08:23:00.818398Z","steps":["trace[1180295677] 'process raft request' (duration: 139.5505ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:23:33.62363Z","caller":"traceutil/trace.go:171","msg":"trace[784677429] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"102.638792ms","start":"2026-08-13T08:23:33.520951Z","end":"2026-08-13T08:23:33.62359Z","steps":["trace[784677429] 'process raft request' (duration: 76.88849ms)","trace[784677429] 'compare' (duration: 25.59607ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:23:40.053461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.7843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:23:40.053582Z","caller":"traceutil/trace.go:171","msg":"trace[154537200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2218; }","duration":"172.969773ms","start":"2026-08-13T08:23:39.880584Z","end":"2026-08-13T08:23:40.053554Z","steps":["trace[154537200] 'range keys from in-memory index tree' (duration: 172.668497ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:27:47.499023Z","caller":"traceutil/trace.go:171","msg":"trace[422412206] transaction","detail":"{read_only:false; response_revision:3929; number_of_response:1; }","duration":"102.157829ms","start":"2026-08-13T08:27:47.396839Z","end":"2026-08-13T08:27:47.498997Z","steps":["trace[422412206] 'process raft request' (duration: 101.974796ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:19.806605Z","caller":"traceutil/trace.go:171","msg":"trace[461427180] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"148.46868ms","start":"2026-08-13T08:29:19.65809Z","end":"2026-08-13T08:29:19.806559Z","steps":["trace[461427180] 'process raft request' (duration: 148.170115ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:26.855429Z","caller":"traceutil/trace.go:171","msg":"trace[130862755] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"108.585979ms","start":"2026-08-13T08:29:26.746808Z","end":"2026-08-13T08:29:26.855394Z","steps":["trace[130862755] 'process raft request' (duration: 108.228253ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:27.025099Z","caller":"traceutil/trace.go:171","msg":"trace[625936561] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"156.040957ms","start":"2026-08-13T08:29:26.869022Z","end":"2026-08-13T08:29:27.025063Z","steps":["trace[625936561] 'process raft request' (duration: 59.848607ms)","trace[625936561] 'compare' (duration: 95.909035ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:29:27.025465Z","caller":"traceutil/trace.go:171","msg":"trace[49598435] linearizableReadLoop","detail":"{readStateIndex:5524; appliedIndex:5520; }","duration":"119.746355ms","start":"2026-08-13T08:29:26.905686Z","end":"2026-08-13T08:29:27.025432Z","steps":["trace[49598435] 'read index received' (duration: 23.141377ms)","trace[49598435] 'applied index is now lower than readState.Index' (duration: 96.603688ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:29:27.025665Z","caller":"traceutil/trace.go:171","msg":"trace[1103658025] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"156.617898ms","start":"2026-08-13T08:29:26.869022Z","end":"2026-08-13T08:29:27.02564Z","steps":["trace[1103658025] 'process raft request' (duration: 156.230331ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:27.025702Z","caller":"traceutil/trace.go:171","msg":"trace[1781894257] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"156.7226ms","start":"2026-08-13T08:29:26.868967Z","end":"2026-08-13T08:29:27.02569Z","steps":["trace[1781894257] 'process raft request' (duration: 156.012227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:27.025705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.99899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-13T08:29:27.02575Z","caller":"traceutil/trace.go:171","msg":"trace[1817589431] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5326; }","duration":"120.088792ms","start":"2026-08-13T08:29:26.905644Z","end":"2026-08-13T08:29:27.025732Z","steps":["trace[1817589431] 'agreement among raft nodes before linearized reading' (duration: 119.904158ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:27.02584Z","caller":"traceutil/trace.go:171","msg":"trace[242101155] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"138.146081ms","start":"2026-08-13T08:29:26.88763Z","end":"2026-08-13T08:29:27.025776Z","steps":["trace[242101155] 'process raft request' (duration: 137.743793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:32.407828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.404478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:29:32.407954Z","caller":"traceutil/trace.go:171","msg":"trace[1146169404] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5411; }","duration":"149.628692ms","start":"2026-08-13T08:29:32.258301Z","end":"2026-08-13T08:29:32.40793Z","steps":["trace[1146169404] 'count revisions from in-memory index tree' (duration: 149.179504ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:40.803951Z","caller":"traceutil/trace.go:171","msg":"trace[1578600474] linearizableReadLoop","detail":"{readStateIndex:5817; appliedIndex:5815; }","duration":"224.49074ms","start":"2026-08-13T08:29:40.57943Z","end":"2026-08-13T08:29:40.803921Z","steps":["trace[1578600474] 'read index received' (duration: 44.239416ms)","trace[1578600474] 'applied index is now lower than readState.Index' (duration: 180.249194ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:29:40.804276Z","caller":"traceutil/trace.go:171","msg":"trace[451463637] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5612; }","duration":"224.874378ms","start":"2026-08-13T08:29:40.579389Z","end":"2026-08-13T08:29:40.804263Z","steps":["trace[451463637] 'process raft request' (duration: 149.606153ms)","trace[451463637] 'compare' (duration: 74.835716ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:29:40.804481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.91903ms","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":"warn","ts":"2026-08-13T08:29:40.804515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.513281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-13T08:29:40.804574Z","caller":"traceutil/trace.go:171","msg":"trace[187941375] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5612; }","duration":"223.052312ms","start":"2026-08-13T08:29:40.581498Z","end":"2026-08-13T08:29:40.80455Z","steps":["trace[187941375] 'agreement among raft nodes before linearized reading' (duration: 222.801947ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:40.804598Z","caller":"traceutil/trace.go:171","msg":"trace[1634763657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5612; }","duration":"104.654203ms","start":"2026-08-13T08:29:40.699922Z","end":"2026-08-13T08:29:40.804577Z","steps":["trace[1634763657] 'agreement among raft nodes before linearized reading' (duration: 104.411949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:40.804606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.839878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T08:29:40.804619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.155903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2428"} {"level":"info","ts":"2026-08-13T08:29:40.804708Z","caller":"traceutil/trace.go:171","msg":"trace[1069982350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5612; }","duration":"102.960121ms","start":"2026-08-13T08:29:40.701731Z","end":"2026-08-13T08:29:40.804692Z","steps":["trace[1069982350] 'agreement among raft nodes before linearized reading' (duration: 102.754407ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:40.80478Z","caller":"traceutil/trace.go:171","msg":"trace[926376447] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5612; }","duration":"225.410509ms","start":"2026-08-13T08:29:40.579337Z","end":"2026-08-13T08:29:40.804748Z","steps":["trace[926376447] 'agreement among raft nodes before linearized reading' (duration: 225.146374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:40.804798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.505139ms","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-13T08:29:40.804843Z","caller":"traceutil/trace.go:171","msg":"trace[1421169430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5612; }","duration":"106.623611ms","start":"2026-08-13T08:29:40.698208Z","end":"2026-08-13T08:29:40.804831Z","steps":["trace[1421169430] 'agreement among raft nodes before linearized reading' (duration: 106.397497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:40.804895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.556087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4kkbp\" ","response":"range_response_count:1 size:6447"} {"level":"warn","ts":"2026-08-13T08:29:40.806005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.821894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-13T08:29:40.806021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.954227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c304d72-61cb-4bab-9512-49622f23ac09\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-13T08:29:40.806124Z","caller":"traceutil/trace.go:171","msg":"trace[619098000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5612; }","duration":"106.976848ms","start":"2026-08-13T08:29:40.699094Z","end":"2026-08-13T08:29:40.806071Z","steps":["trace[619098000] 'agreement among raft nodes before linearized reading' (duration: 105.807106ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:40.806142Z","caller":"traceutil/trace.go:171","msg":"trace[843336656] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c304d72-61cb-4bab-9512-49622f23ac09; range_end:; response_count:1; response_revision:5612; }","duration":"167.12651ms","start":"2026-08-13T08:29:40.63897Z","end":"2026-08-13T08:29:40.806097Z","steps":["trace[843336656] 'agreement among raft nodes before linearized reading' (duration: 165.959078ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:40.80493Z","caller":"traceutil/trace.go:171","msg":"trace[1448312783] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4kkbp; range_end:; response_count:1; response_revision:5612; }","duration":"109.620069ms","start":"2026-08-13T08:29:40.6953Z","end":"2026-08-13T08:29:40.80492Z","steps":["trace[1448312783] 'agreement among raft nodes before linearized reading' (duration: 109.516547ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:41.085998Z","caller":"traceutil/trace.go:171","msg":"trace[226543243] transaction","detail":"{read_only:false; response_revision:5614; number_of_response:1; }","duration":"270.052541ms","start":"2026-08-13T08:29:40.815894Z","end":"2026-08-13T08:29:41.085947Z","steps":["trace[226543243] 'process raft request' (duration: 202.555296ms)","trace[226543243] 'compare' (duration: 66.769501ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:29:41.086102Z","caller":"traceutil/trace.go:171","msg":"trace[1403828263] linearizableReadLoop","detail":"{readStateIndex:5822; appliedIndex:5818; }","duration":"221.962871ms","start":"2026-08-13T08:29:40.864105Z","end":"2026-08-13T08:29:41.086067Z","steps":["trace[1403828263] 'read index received' (duration: 154.299773ms)","trace[1403828263] 'applied index is now lower than readState.Index' (duration: 67.662118ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:29:41.086283Z","caller":"traceutil/trace.go:171","msg":"trace[694130229] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"268.019781ms","start":"2026-08-13T08:29:40.81825Z","end":"2026-08-13T08:29:41.08627Z","steps":["trace[694130229] 'process raft request' (duration: 267.727275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:41.086385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.248856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:29:41.086454Z","caller":"traceutil/trace.go:171","msg":"trace[238498266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5617; }","duration":"207.380759ms","start":"2026-08-13T08:29:40.879055Z","end":"2026-08-13T08:29:41.086435Z","steps":["trace[238498266] 'agreement among raft nodes before linearized reading' (duration: 207.247346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:41.086511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.656182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-13T08:29:41.086564Z","caller":"traceutil/trace.go:171","msg":"trace[1073252008] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"269.769605ms","start":"2026-08-13T08:29:40.816762Z","end":"2026-08-13T08:29:41.086531Z","steps":["trace[1073252008] 'process raft request' (duration: 269.169243ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:41.086587Z","caller":"traceutil/trace.go:171","msg":"trace[1965438739] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5617; }","duration":"241.803754ms","start":"2026-08-13T08:29:40.844759Z","end":"2026-08-13T08:29:41.086562Z","steps":["trace[1965438739] 'agreement among raft nodes before linearized reading' (duration: 241.56666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:41.086598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.554771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-13T08:29:41.086628Z","caller":"traceutil/trace.go:171","msg":"trace[362842260] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"270.55424ms","start":"2026-08-13T08:29:40.816037Z","end":"2026-08-13T08:29:41.086592Z","steps":["trace[362842260] 'process raft request' (duration: 269.772885ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:41.086732Z","caller":"traceutil/trace.go:171","msg":"trace[23810295] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5617; }","duration":"269.725494ms","start":"2026-08-13T08:29:40.816972Z","end":"2026-08-13T08:29:41.086697Z","steps":["trace[23810295] 'agreement among raft nodes before linearized reading' (duration: 269.299616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:41.086933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.930448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c304d72-61cb-4bab-9512-49622f23ac09\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-13T08:29:41.087035Z","caller":"traceutil/trace.go:171","msg":"trace[908981307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c304d72-61cb-4bab-9512-49622f23ac09; range_end:; response_count:1; response_revision:5617; }","duration":"150.101341ms","start":"2026-08-13T08:29:40.936912Z","end":"2026-08-13T08:29:41.087014Z","steps":["trace[908981307] 'agreement among raft nodes before linearized reading' (duration: 149.54078ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:29:41.33751Z","caller":"traceutil/trace.go:171","msg":"trace[273286979] linearizableReadLoop","detail":"{readStateIndex:5824; appliedIndex:5823; }","duration":"162.243017ms","start":"2026-08-13T08:29:41.175247Z","end":"2026-08-13T08:29:41.33749Z","steps":["trace[273286979] 'read index received' (duration: 156.612178ms)","trace[273286979] 'applied index is now lower than readState.Index' (duration: 5.629699ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:29:41.337746Z","caller":"traceutil/trace.go:171","msg":"trace[1588515137] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"243.564039ms","start":"2026-08-13T08:29:41.094135Z","end":"2026-08-13T08:29:41.337699Z","steps":["trace[1588515137] 'process raft request' (duration: 237.815237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:41.337904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.690966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-08-13T08:29:41.337945Z","caller":"traceutil/trace.go:171","msg":"trace[1532482654] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5619; }","duration":"221.780287ms","start":"2026-08-13T08:29:41.116153Z","end":"2026-08-13T08:29:41.337933Z","steps":["trace[1532482654] 'agreement among raft nodes before linearized reading' (duration: 221.652485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:29:41.347019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.370576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-13T08:29:41.347121Z","caller":"traceutil/trace.go:171","msg":"trace[1866053880] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5620; }","duration":"167.500848ms","start":"2026-08-13T08:29:41.179594Z","end":"2026-08-13T08:29:41.347095Z","steps":["trace[1866053880] 'agreement among raft nodes before linearized reading' (duration: 167.278733ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:30:26.626059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2993} {"level":"info","ts":"2026-08-13T08:30:26.695369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2993,"took":"67.610567ms","hash":1689495490,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":33890304,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-13T08:30:26.695442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1689495490,"revision":2993,"compact-revision":-1} {"level":"info","ts":"2026-08-13T08:35:26.632933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6039} {"level":"info","ts":"2026-08-13T08:35:26.732911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6039,"took":"97.030211ms","hash":705059367,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":21860352,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-13T08:35:26.732974Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":705059367,"revision":6039,"compact-revision":2993} {"level":"info","ts":"2026-08-13T08:36:01.91148Z","caller":"traceutil/trace.go:171","msg":"trace[1241635648] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"231.155416ms","start":"2026-08-13T08:36:01.680297Z","end":"2026-08-13T08:36:01.911452Z","steps":["trace[1241635648] 'process raft request' (duration: 230.928652ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:36:02.054465Z","caller":"traceutil/trace.go:171","msg":"trace[1503828365] linearizableReadLoop","detail":"{readStateIndex:8779; appliedIndex:8777; }","duration":"174.77034ms","start":"2026-08-13T08:36:01.879669Z","end":"2026-08-13T08:36:02.054439Z","steps":["trace[1503828365] 'read index received' (duration: 31.653987ms)","trace[1503828365] 'applied index is now lower than readState.Index' (duration: 143.115453ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:36:02.05453Z","caller":"traceutil/trace.go:171","msg":"trace[1166065695] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"369.420206ms","start":"2026-08-13T08:36:01.685068Z","end":"2026-08-13T08:36:02.054488Z","steps":["trace[1166065695] 'process raft request' (duration: 326.432725ms)","trace[1166065695] 'compare' (duration: 42.813498ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:36:02.054648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.952503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:36:02.054722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:01.685051Z","time spent":"369.569929ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:36:02.054738Z","caller":"traceutil/trace.go:171","msg":"trace[929681591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8480; }","duration":"175.096196ms","start":"2026-08-13T08:36:01.879618Z","end":"2026-08-13T08:36:02.054714Z","steps":["trace[929681591] 'agreement among raft nodes before linearized reading' (duration: 174.918163ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:36:15.444186Z","caller":"traceutil/trace.go:171","msg":"trace[1990693249] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"157.594727ms","start":"2026-08-13T08:36:15.286556Z","end":"2026-08-13T08:36:15.444151Z","steps":["trace[1990693249] 'process raft request' (duration: 157.333452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:15.444427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.514731ms","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-13T08:36:15.444485Z","caller":"traceutil/trace.go:171","msg":"trace[1590051527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8549; }","duration":"151.624934ms","start":"2026-08-13T08:36:15.292845Z","end":"2026-08-13T08:36:15.44447Z","steps":["trace[1590051527] 'agreement among raft nodes before linearized reading' (duration: 151.393069ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:36:15.444103Z","caller":"traceutil/trace.go:171","msg":"trace[1934162341] linearizableReadLoop","detail":"{readStateIndex:8850; appliedIndex:8849; }","duration":"151.181865ms","start":"2026-08-13T08:36:15.292888Z","end":"2026-08-13T08:36:15.44407Z","steps":["trace[1934162341] 'read index received' (duration: 150.91709ms)","trace[1934162341] 'applied index is now lower than readState.Index' (duration: 263.135µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:36:15.445024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.673976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4kkbp\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-08-13T08:36:15.445066Z","caller":"traceutil/trace.go:171","msg":"trace[1005283836] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4kkbp; range_end:; response_count:1; response_revision:8549; }","duration":"123.748636ms","start":"2026-08-13T08:36:15.321306Z","end":"2026-08-13T08:36:15.445054Z","steps":["trace[1005283836] 'agreement among raft nodes before linearized reading' (duration: 123.641574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:15.445466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.780891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-13T08:36:15.445524Z","caller":"traceutil/trace.go:171","msg":"trace[1139353783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8549; }","duration":"129.883793ms","start":"2026-08-13T08:36:15.31563Z","end":"2026-08-13T08:36:15.445514Z","steps":["trace[1139353783] 'agreement among raft nodes before linearized reading' (duration: 129.267221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:15.796594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.152219ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-13T08:36:16.085513Z","caller":"traceutil/trace.go:171","msg":"trace[830114442] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"118.345376ms","start":"2026-08-13T08:36:15.967135Z","end":"2026-08-13T08:36:16.08548Z","steps":["trace[830114442] 'process raft request' (duration: 118.127532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:16.836587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569319337842727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T08:36:17.244833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.446167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:36:17.245077Z","caller":"traceutil/trace.go:171","msg":"trace[1779764066] linearizableReadLoop","detail":"{readStateIndex:8857; appliedIndex:8855; }","duration":"909.436884ms","start":"2026-08-13T08:36:16.335619Z","end":"2026-08-13T08:36:17.245056Z","steps":["trace[1779764066] 'read index received' (duration: 530.686021ms)","trace[1779764066] 'applied index is now lower than readState.Index' (duration: 378.749093ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:36:17.245173Z","caller":"traceutil/trace.go:171","msg":"trace[1983554648] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"1.029661395s","start":"2026-08-13T08:36:16.215495Z","end":"2026-08-13T08:36:17.245156Z","steps":["trace[1983554648] 'process raft request' (duration: 650.788309ms)","trace[1983554648] 'compare' (duration: 377.944108ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:36:17.245225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.21547Z","time spent":"1.029733687s","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":852,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T08:36:17.245733Z","caller":"traceutil/trace.go:171","msg":"trace[1295878079] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"422.260204ms","start":"2026-08-13T08:36:16.823431Z","end":"2026-08-13T08:36:17.245691Z","steps":["trace[1295878079] 'process raft request' (duration: 421.541861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:17.24595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.823416Z","time spent":"422.415138ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:36:17.24596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.328832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:36:17.246Z","caller":"traceutil/trace.go:171","msg":"trace[1672770049] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8555; }","duration":"910.419603ms","start":"2026-08-13T08:36:16.335572Z","end":"2026-08-13T08:36:17.245991Z","steps":["trace[1672770049] 'agreement among raft nodes before linearized reading' (duration: 910.120977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:17.246037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.335553Z","time spent":"910.477694ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":31,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:36:17.246103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.244911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:36:17.246175Z","caller":"traceutil/trace.go:171","msg":"trace[300636854] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8556; }","duration":"425.386324ms","start":"2026-08-13T08:36:16.820772Z","end":"2026-08-13T08:36:17.246158Z","steps":["trace[300636854] 'agreement among raft nodes before linearized reading' (duration: 425.208061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:17.246209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.820743Z","time spent":"425.460195ms","remote":"127.0.0.1:49020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:36:17.246305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.471318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T08:36:17.246373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.071698ms","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-13T08:36:17.2464Z","caller":"traceutil/trace.go:171","msg":"trace[964699423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8556; }","duration":"279.630981ms","start":"2026-08-13T08:36:16.966748Z","end":"2026-08-13T08:36:17.246379Z","steps":["trace[964699423] 'agreement among raft nodes before linearized reading' (duration: 279.356356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:17.246451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.330562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-13T08:36:17.246481Z","caller":"traceutil/trace.go:171","msg":"trace[559838896] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8556; }","duration":"425.391654ms","start":"2026-08-13T08:36:16.821083Z","end":"2026-08-13T08:36:17.246475Z","steps":["trace[559838896] 'agreement among raft nodes before linearized reading' (duration: 425.287762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:17.246497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.945942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-13T08:36:17.246502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.821063Z","time spent":"425.435004ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-13T08:36:17.246531Z","caller":"traceutil/trace.go:171","msg":"trace[2095119148] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8556; }","duration":"320.002753ms","start":"2026-08-13T08:36:16.926519Z","end":"2026-08-13T08:36:17.246522Z","steps":["trace[2095119148] 'agreement among raft nodes before linearized reading' (duration: 319.919301ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:36:17.24642Z","caller":"traceutil/trace.go:171","msg":"trace[634737985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8556; }","duration":"425.17732ms","start":"2026-08-13T08:36:16.821232Z","end":"2026-08-13T08:36:17.246409Z","steps":["trace[634737985] 'agreement among raft nodes before linearized reading' (duration: 425.017007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:17.246563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.926512Z","time spent":"320.043593ms","remote":"127.0.0.1:60768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-08-13T08:36:17.246572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.821203Z","time spent":"425.357383ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T08:36:17.246381Z","caller":"traceutil/trace.go:171","msg":"trace[369329994] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"335.911214ms","start":"2026-08-13T08:36:16.910459Z","end":"2026-08-13T08:36:17.24637Z","steps":["trace[369329994] 'process raft request' (duration: 335.398924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:36:17.246884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:36:16.91043Z","time spent":"336.405592ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:36:17.773916Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022a8.wal"} {"level":"info","ts":"2026-08-13T08:36:48.394025Z","caller":"traceutil/trace.go:171","msg":"trace[510922014] transaction","detail":"{read_only:false; response_revision:8781; number_of_response:1; }","duration":"185.893372ms","start":"2026-08-13T08:36:48.208105Z","end":"2026-08-13T08:36:48.393998Z","steps":["trace[510922014] 'process raft request' (duration: 185.722798ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:36:48.551781Z","caller":"traceutil/trace.go:171","msg":"trace[1597077679] transaction","detail":"{read_only:false; response_revision:8782; number_of_response:1; }","duration":"238.926582ms","start":"2026-08-13T08:36:48.312798Z","end":"2026-08-13T08:36:48.551725Z","steps":["trace[1597077679] 'process raft request' (duration: 194.570284ms)","trace[1597077679] 'compare' (duration: 44.062403ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:36:57.92756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.68173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:36:57.927784Z","caller":"traceutil/trace.go:171","msg":"trace[299548857] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"165.997625ms","start":"2026-08-13T08:36:57.761767Z","end":"2026-08-13T08:36:57.927765Z","steps":["trace[299548857] 'process raft request' (duration: 165.937024ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:36:57.927953Z","caller":"traceutil/trace.go:171","msg":"trace[2063145374] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"256.84225ms","start":"2026-08-13T08:36:57.671041Z","end":"2026-08-13T08:36:57.927884Z","steps":["trace[2063145374] 'process raft request' (duration: 100.745812ms)","trace[2063145374] 'compare' (duration: 155.516367ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:36:57.928114Z","caller":"traceutil/trace.go:171","msg":"trace[312056956] linearizableReadLoop","detail":"{readStateIndex:9192; appliedIndex:9191; }","duration":"171.170183ms","start":"2026-08-13T08:36:57.756932Z","end":"2026-08-13T08:36:57.928102Z","steps":["trace[312056956] 'read index received' (duration: 14.8375ms)","trace[312056956] 'applied index is now lower than readState.Index' (duration: 156.330803ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:36:57.928273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.928672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4kkbp\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-08-13T08:36:57.928311Z","caller":"traceutil/trace.go:171","msg":"trace[182368487] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4kkbp; range_end:; response_count:1; response_revision:8877; }","duration":"212.014314ms","start":"2026-08-13T08:36:57.716287Z","end":"2026-08-13T08:36:57.928301Z","steps":["trace[182368487] 'agreement among raft nodes before linearized reading' (duration: 211.862261ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:36:58.58259Z","caller":"traceutil/trace.go:171","msg":"trace[2146002878] transaction","detail":"{read_only:false; response_revision:8880; number_of_response:1; }","duration":"157.050786ms","start":"2026-08-13T08:36:58.425511Z","end":"2026-08-13T08:36:58.582562Z","steps":["trace[2146002878] 'process raft request' (duration: 156.839202ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:38:21.632626Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca256638ea38eb43","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T08:38:21.686202Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-13T08:38:21.686365Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-13T08:38:22.090686Z","caller":"traceutil/trace.go:171","msg":"trace[1269974543] transaction","detail":"{read_only:false; response_revision:9667; number_of_response:1; }","duration":"115.75963ms","start":"2026-08-13T08:38:21.974888Z","end":"2026-08-13T08:38:22.090647Z","steps":["trace[1269974543] 'process raft request' (duration: 115.412214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:38:22.274045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.270324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:38:22.274243Z","caller":"traceutil/trace.go:171","msg":"trace[1209682616] transaction","detail":"{read_only:false; response_revision:9669; number_of_response:1; }","duration":"177.865286ms","start":"2026-08-13T08:38:22.096342Z","end":"2026-08-13T08:38:22.274207Z","steps":["trace[1209682616] 'process raft request' (duration: 66.335037ms)","trace[1209682616] 'compare' (duration: 111.137651ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:40:26.640203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8116} {"level":"info","ts":"2026-08-13T08:40:26.706977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8116,"took":"63.723397ms","hash":1700867969,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":28721152,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-13T08:40:26.707034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1700867969,"revision":8116,"compact-revision":6039} {"level":"info","ts":"2026-08-13T08:45:26.647536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10529} {"level":"info","ts":"2026-08-13T08:45:26.732944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10529,"took":"82.660011ms","hash":1169239376,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":26333184,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-13T08:45:26.7331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1169239376,"revision":10529,"compact-revision":8116} {"level":"info","ts":"2026-08-13T08:50:10.510874Z","caller":"traceutil/trace.go:171","msg":"trace[2087779110] transaction","detail":"{read_only:false; response_revision:14595; number_of_response:1; }","duration":"110.902393ms","start":"2026-08-13T08:50:10.399945Z","end":"2026-08-13T08:50:10.510848Z","steps":["trace[2087779110] 'process raft request' (duration: 110.732869ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:50:10.511777Z","caller":"traceutil/trace.go:171","msg":"trace[715814111] transaction","detail":"{read_only:false; response_revision:14596; number_of_response:1; }","duration":"111.606997ms","start":"2026-08-13T08:50:10.400139Z","end":"2026-08-13T08:50:10.511746Z","steps":["trace[715814111] 'process raft request' (duration: 111.411813ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:50:26.655649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12656} {"level":"info","ts":"2026-08-13T08:50:26.734758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12656,"took":"75.828878ms","hash":2712336386,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":27176960,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-13T08:50:26.734838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2712336386,"revision":12656,"compact-revision":10529} {"level":"info","ts":"2026-08-13T08:50:40.120604Z","caller":"traceutil/trace.go:171","msg":"trace[926408495] transaction","detail":"{read_only:false; response_revision:14748; number_of_response:1; }","duration":"104.672484ms","start":"2026-08-13T08:50:40.015899Z","end":"2026-08-13T08:50:40.120571Z","steps":["trace[926408495] 'process raft request' (duration: 92.319415ms)","trace[926408495] 'compare' (duration: 12.225875ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:50:48.587026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.735763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T08:50:48.587138Z","caller":"traceutil/trace.go:171","msg":"trace[2005401961] linearizableReadLoop","detail":"{readStateIndex:15319; appliedIndex:15318; }","duration":"253.895701ms","start":"2026-08-13T08:50:48.333227Z","end":"2026-08-13T08:50:48.587123Z","steps":["trace[2005401961] 'read index received' (duration: 69.902564ms)","trace[2005401961] 'applied index is now lower than readState.Index' (duration: 183.991957ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:50:48.587219Z","caller":"traceutil/trace.go:171","msg":"trace[1574269288] transaction","detail":"{read_only:false; response_revision:14806; number_of_response:1; }","duration":"359.270787ms","start":"2026-08-13T08:50:48.227894Z","end":"2026-08-13T08:50:48.587164Z","steps":["trace[1574269288] 'process raft request' (duration: 175.30266ms)","trace[1574269288] 'compare' (duration: 183.626321ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:50:48.58743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.174498ms","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-13T08:50:48.587539Z","caller":"traceutil/trace.go:171","msg":"trace[1658449521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14806; }","duration":"254.32573ms","start":"2026-08-13T08:50:48.333189Z","end":"2026-08-13T08:50:48.587515Z","steps":["trace[1658449521] 'agreement among raft nodes before linearized reading' (duration: 253.991384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:50:48.587456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:50:48.227866Z","time spent":"359.473991ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T08:50:48.59498Z","caller":"traceutil/trace.go:171","msg":"trace[1100984765] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"135.652108ms","start":"2026-08-13T08:50:48.459297Z","end":"2026-08-13T08:50:48.594949Z","steps":["trace[1100984765] 'process raft request' (duration: 135.374582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:50:48.595119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.711012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-13T08:50:48.595257Z","caller":"traceutil/trace.go:171","msg":"trace[1665772193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14807; }","duration":"261.913557ms","start":"2026-08-13T08:50:48.333321Z","end":"2026-08-13T08:50:48.595235Z","steps":["trace[1665772193] 'agreement among raft nodes before linearized reading' (duration: 261.53859ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:51:11.271017Z","caller":"traceutil/trace.go:171","msg":"trace[143327732] linearizableReadLoop","detail":"{readStateIndex:15644; appliedIndex:15643; }","duration":"351.286823ms","start":"2026-08-13T08:51:10.919706Z","end":"2026-08-13T08:51:11.270993Z","steps":["trace[143327732] 'read index received' (duration: 351.125ms)","trace[143327732] 'applied index is now lower than readState.Index' (duration: 160.563µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:51:11.271115Z","caller":"traceutil/trace.go:171","msg":"trace[535229953] transaction","detail":"{read_only:false; response_revision:15119; number_of_response:1; }","duration":"421.174877ms","start":"2026-08-13T08:51:10.849906Z","end":"2026-08-13T08:51:11.271081Z","steps":["trace[535229953] 'process raft request' (duration: 420.933172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:51:11.271252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.87338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbde7515-89f5-48d2-a3fe-75620be3944d\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-08-13T08:51:11.271288Z","caller":"traceutil/trace.go:171","msg":"trace[593779948] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cbde7515-89f5-48d2-a3fe-75620be3944d; range_end:; response_count:1; response_revision:15119; }","duration":"370.957071ms","start":"2026-08-13T08:51:10.90032Z","end":"2026-08-13T08:51:11.271277Z","steps":["trace[593779948] 'agreement among raft nodes before linearized reading' (duration: 370.807499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:51:11.271278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:51:10.849888Z","time spent":"421.30844ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T08:51:11.271323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:51:10.900304Z","time spent":"371.012092ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3660,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbde7515-89f5-48d2-a3fe-75620be3944d\" "} {"level":"warn","ts":"2026-08-13T08:51:11.271366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.991916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:51:11.271445Z","caller":"traceutil/trace.go:171","msg":"trace[2070325696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15119; }","duration":"392.131069ms","start":"2026-08-13T08:51:10.879293Z","end":"2026-08-13T08:51:11.271424Z","steps":["trace[2070325696] 'agreement among raft nodes before linearized reading' (duration: 391.982616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:51:11.271509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T08:51:10.879271Z","time spent":"392.229201ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T08:51:11.273003Z","caller":"traceutil/trace.go:171","msg":"trace[1116763578] transaction","detail":"{read_only:false; response_revision:15120; number_of_response:1; }","duration":"298.454809ms","start":"2026-08-13T08:51:10.974522Z","end":"2026-08-13T08:51:11.272977Z","steps":["trace[1116763578] 'process raft request' (duration: 298.191814ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:51:11.273019Z","caller":"traceutil/trace.go:171","msg":"trace[1539179001] transaction","detail":"{read_only:false; response_revision:15121; number_of_response:1; }","duration":"281.891021ms","start":"2026-08-13T08:51:10.991085Z","end":"2026-08-13T08:51:11.272976Z","steps":["trace[1539179001] 'process raft request' (duration: 281.795029ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:51:11.273192Z","caller":"traceutil/trace.go:171","msg":"trace[1472118586] transaction","detail":"{read_only:false; response_revision:15122; number_of_response:1; }","duration":"147.669139ms","start":"2026-08-13T08:51:11.125496Z","end":"2026-08-13T08:51:11.273165Z","steps":["trace[1472118586] 'process raft request' (duration: 147.428765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:51:11.273258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.497221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-08-13T08:51:11.273263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.173768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T08:51:11.273313Z","caller":"traceutil/trace.go:171","msg":"trace[1757348717] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15122; }","duration":"257.579033ms","start":"2026-08-13T08:51:11.015718Z","end":"2026-08-13T08:51:11.273297Z","steps":["trace[1757348717] 'agreement among raft nodes before linearized reading' (duration: 257.396749ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:51:11.273347Z","caller":"traceutil/trace.go:171","msg":"trace[64965402] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:15122; }","duration":"226.332642ms","start":"2026-08-13T08:51:11.046992Z","end":"2026-08-13T08:51:11.273325Z","steps":["trace[64965402] 'agreement among raft nodes before linearized reading' (duration: 226.132668ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:52:04.629252Z","caller":"traceutil/trace.go:171","msg":"trace[581319829] linearizableReadLoop","detail":"{readStateIndex:16296; appliedIndex:16295; }","duration":"144.662121ms","start":"2026-08-13T08:52:04.484559Z","end":"2026-08-13T08:52:04.629221Z","steps":["trace[581319829] 'read index received' (duration: 104.473378ms)","trace[581319829] 'applied index is now lower than readState.Index' (duration: 40.187723ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:52:04.629347Z","caller":"traceutil/trace.go:171","msg":"trace[2012927896] transaction","detail":"{read_only:false; response_revision:15756; number_of_response:1; }","duration":"150.065256ms","start":"2026-08-13T08:52:04.479226Z","end":"2026-08-13T08:52:04.629291Z","steps":["trace[2012927896] 'process raft request' (duration: 109.989195ms)","trace[2012927896] 'compare' (duration: 39.865696ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:52:04.629487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.896616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T08:52:04.629555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.894426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:52:04.629607Z","caller":"traceutil/trace.go:171","msg":"trace[1372981204] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15756; }","duration":"145.052359ms","start":"2026-08-13T08:52:04.484507Z","end":"2026-08-13T08:52:04.629559Z","steps":["trace[1372981204] 'agreement among raft nodes before linearized reading' (duration: 144.880546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:52:04.629643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.50086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T08:52:04.629686Z","caller":"traceutil/trace.go:171","msg":"trace[551874037] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15756; }","duration":"145.047869ms","start":"2026-08-13T08:52:04.484609Z","end":"2026-08-13T08:52:04.629657Z","steps":["trace[551874037] 'agreement among raft nodes before linearized reading' (duration: 144.834685ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:52:04.629696Z","caller":"traceutil/trace.go:171","msg":"trace[114239237] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15756; }","duration":"143.590501ms","start":"2026-08-13T08:52:04.486087Z","end":"2026-08-13T08:52:04.629678Z","steps":["trace[114239237] 'agreement among raft nodes before linearized reading' (duration: 143.456659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:52:04.629782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.028671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-13T08:52:04.629941Z","caller":"traceutil/trace.go:171","msg":"trace[1458744379] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15756; }","duration":"142.205265ms","start":"2026-08-13T08:52:04.487704Z","end":"2026-08-13T08:52:04.629909Z","steps":["trace[1458744379] 'agreement among raft nodes before linearized reading' (duration: 141.800557ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:52:38.595462Z","caller":"traceutil/trace.go:171","msg":"trace[1446001494] transaction","detail":"{read_only:false; response_revision:15935; number_of_response:1; }","duration":"140.500992ms","start":"2026-08-13T08:52:38.45492Z","end":"2026-08-13T08:52:38.595421Z","steps":["trace[1446001494] 'process raft request' (duration: 140.246037ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:55:26.665038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14683} {"level":"info","ts":"2026-08-13T08:55:26.752067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14683,"took":"83.575274ms","hash":329639346,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":32321536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-13T08:55:26.75214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":329639346,"revision":14683,"compact-revision":12656} {"level":"info","ts":"2026-08-13T08:58:50.50712Z","caller":"traceutil/trace.go:171","msg":"trace[1884869022] linearizableReadLoop","detail":"{readStateIndex:19283; appliedIndex:19282; }","duration":"212.42227ms","start":"2026-08-13T08:58:50.294669Z","end":"2026-08-13T08:58:50.507092Z","steps":["trace[1884869022] 'read index received' (duration: 212.298398ms)","trace[1884869022] 'applied index is now lower than readState.Index' (duration: 122.942µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:58:50.507208Z","caller":"traceutil/trace.go:171","msg":"trace[712909010] transaction","detail":"{read_only:false; response_revision:18646; number_of_response:1; }","duration":"259.470104ms","start":"2026-08-13T08:58:50.247694Z","end":"2026-08-13T08:58:50.507164Z","steps":["trace[712909010] 'process raft request' (duration: 259.23201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:58:50.507311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.619054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-npqmm\" ","response":"range_response_count:1 size:20664"} {"level":"info","ts":"2026-08-13T08:58:50.507342Z","caller":"traceutil/trace.go:171","msg":"trace[917238522] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-npqmm; range_end:; response_count:1; response_revision:18646; }","duration":"212.709636ms","start":"2026-08-13T08:58:50.294623Z","end":"2026-08-13T08:58:50.507332Z","steps":["trace[917238522] 'agreement among raft nodes before linearized reading' (duration: 212.567663ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:58:51.054817Z","caller":"traceutil/trace.go:171","msg":"trace[1652829657] linearizableReadLoop","detail":"{readStateIndex:19287; appliedIndex:19285; }","duration":"175.242184ms","start":"2026-08-13T08:58:50.879551Z","end":"2026-08-13T08:58:51.054794Z","steps":["trace[1652829657] 'read index received' (duration: 137.225831ms)","trace[1652829657] 'applied index is now lower than readState.Index' (duration: 38.014543ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:58:51.054948Z","caller":"traceutil/trace.go:171","msg":"trace[324315003] transaction","detail":"{read_only:false; response_revision:18649; number_of_response:1; }","duration":"220.626037ms","start":"2026-08-13T08:58:50.834312Z","end":"2026-08-13T08:58:51.054938Z","steps":["trace[324315003] 'process raft request' (duration: 220.408223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:58:51.05511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.55292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T08:58:51.055139Z","caller":"traceutil/trace.go:171","msg":"trace[1695222289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18649; }","duration":"175.636261ms","start":"2026-08-13T08:58:50.879494Z","end":"2026-08-13T08:58:51.05513Z","steps":["trace[1695222289] 'agreement among raft nodes before linearized reading' (duration: 175.57223ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:58:51.055224Z","caller":"traceutil/trace.go:171","msg":"trace[694577680] transaction","detail":"{read_only:false; response_revision:18648; number_of_response:1; }","duration":"223.991472ms","start":"2026-08-13T08:58:50.831184Z","end":"2026-08-13T08:58:51.055175Z","steps":["trace[694577680] 'process raft request' (duration: 185.585922ms)","trace[694577680] 'compare' (duration: 37.699217ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T08:58:51.055561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.118176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-2hcvs\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-08-13T08:58:51.055656Z","caller":"traceutil/trace.go:171","msg":"trace[674963069] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-2hcvs; range_end:; response_count:1; response_revision:18649; }","duration":"141.27157ms","start":"2026-08-13T08:58:50.914364Z","end":"2026-08-13T08:58:51.055636Z","steps":["trace[674963069] 'agreement among raft nodes before linearized reading' (duration: 140.953034ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:59:01.638611Z","caller":"traceutil/trace.go:171","msg":"trace[1886132709] linearizableReadLoop","detail":"{readStateIndex:19494; appliedIndex:19493; }","duration":"111.946935ms","start":"2026-08-13T08:59:01.526636Z","end":"2026-08-13T08:59:01.638583Z","steps":["trace[1886132709] 'read index received' (duration: 111.774082ms)","trace[1886132709] 'applied index is now lower than readState.Index' (duration: 171.863µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T08:59:01.638753Z","caller":"traceutil/trace.go:171","msg":"trace[2052150301] transaction","detail":"{read_only:false; response_revision:18848; number_of_response:1; }","duration":"116.575024ms","start":"2026-08-13T08:59:01.522152Z","end":"2026-08-13T08:59:01.638727Z","steps":["trace[2052150301] 'process raft request' (duration: 116.267478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T08:59:01.638886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.244621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T08:59:01.63898Z","caller":"traceutil/trace.go:171","msg":"trace[227956806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18848; }","duration":"112.385654ms","start":"2026-08-13T08:59:01.526582Z","end":"2026-08-13T08:59:01.638968Z","steps":["trace[227956806] 'agreement among raft nodes before linearized reading' (duration: 112.17644ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T08:59:52.202271Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca256638ea38eb43","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T08:59:52.208578Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-13T08:59:52.208725Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-13T09:00:26.672044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17137} {"level":"info","ts":"2026-08-13T09:00:26.768792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17137,"took":"92.623934ms","hash":339851930,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-13T09:00:26.768863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":339851930,"revision":17137,"compact-revision":14683} {"level":"info","ts":"2026-08-13T09:04:33.940183Z","caller":"traceutil/trace.go:171","msg":"trace[414853349] transaction","detail":"{read_only:false; response_revision:21378; number_of_response:1; }","duration":"107.271025ms","start":"2026-08-13T09:04:33.832867Z","end":"2026-08-13T09:04:33.940138Z","steps":["trace[414853349] 'process raft request' (duration: 106.990409ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:04:33.998258Z","caller":"traceutil/trace.go:171","msg":"trace[1225711685] linearizableReadLoop","detail":"{readStateIndex:22103; appliedIndex:22101; }","duration":"117.513452ms","start":"2026-08-13T09:04:33.880697Z","end":"2026-08-13T09:04:33.99821Z","steps":["trace[1225711685] 'read index received' (duration: 59.102997ms)","trace[1225711685] 'applied index is now lower than readState.Index' (duration: 58.408885ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:04:33.998455Z","caller":"traceutil/trace.go:171","msg":"trace[372541116] transaction","detail":"{read_only:false; response_revision:21379; number_of_response:1; }","duration":"129.001773ms","start":"2026-08-13T09:04:33.869386Z","end":"2026-08-13T09:04:33.998388Z","steps":["trace[372541116] 'process raft request' (duration: 85.100738ms)","trace[372541116] 'compare' (duration: 43.538648ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:04:33.998567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.846329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:04:33.998619Z","caller":"traceutil/trace.go:171","msg":"trace[1413563263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21379; }","duration":"118.061092ms","start":"2026-08-13T09:04:33.880543Z","end":"2026-08-13T09:04:33.998604Z","steps":["trace[1413563263] 'agreement among raft nodes before linearized reading' (duration: 117.92189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:04:33.998887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.339342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-13T09:04:33.999018Z","caller":"traceutil/trace.go:171","msg":"trace[304172257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21379; }","duration":"113.532386ms","start":"2026-08-13T09:04:33.885455Z","end":"2026-08-13T09:04:33.998988Z","steps":["trace[304172257] 'agreement among raft nodes before linearized reading' (duration: 113.182389ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:05:04.448032Z","caller":"traceutil/trace.go:171","msg":"trace[1855149312] transaction","detail":"{read_only:false; response_revision:21564; number_of_response:1; }","duration":"283.252533ms","start":"2026-08-13T09:05:04.164736Z","end":"2026-08-13T09:05:04.447989Z","steps":["trace[1855149312] 'process raft request' (duration: 197.461641ms)","trace[1855149312] 'compare' (duration: 85.559748ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:05:04.462667Z","caller":"traceutil/trace.go:171","msg":"trace[433582408] transaction","detail":"{read_only:false; response_revision:21565; number_of_response:1; }","duration":"225.748217ms","start":"2026-08-13T09:05:04.236902Z","end":"2026-08-13T09:05:04.46265Z","steps":["trace[433582408] 'process raft request' (duration: 225.666785ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:05:04.719103Z","caller":"traceutil/trace.go:171","msg":"trace[96207614] transaction","detail":"{read_only:false; response_revision:21566; number_of_response:1; }","duration":"116.989842ms","start":"2026-08-13T09:05:04.602083Z","end":"2026-08-13T09:05:04.719073Z","steps":["trace[96207614] 'process raft request' (duration: 113.743629ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:05:04.728789Z","caller":"traceutil/trace.go:171","msg":"trace[1563222517] transaction","detail":"{read_only:false; response_revision:21567; number_of_response:1; }","duration":"122.55392ms","start":"2026-08-13T09:05:04.606216Z","end":"2026-08-13T09:05:04.728769Z","steps":["trace[1563222517] 'process raft request' (duration: 122.408677ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:05:05.009732Z","caller":"traceutil/trace.go:171","msg":"trace[828880793] transaction","detail":"{read_only:false; response_revision:21570; number_of_response:1; }","duration":"149.351405ms","start":"2026-08-13T09:05:04.860334Z","end":"2026-08-13T09:05:05.009686Z","steps":["trace[828880793] 'process raft request' (duration: 146.399818ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:05:05.214228Z","caller":"traceutil/trace.go:171","msg":"trace[1625396642] linearizableReadLoop","detail":"{readStateIndex:22302; appliedIndex:22301; }","duration":"136.501088ms","start":"2026-08-13T09:05:05.077686Z","end":"2026-08-13T09:05:05.214187Z","steps":["trace[1625396642] 'read index received' (duration: 86.891403ms)","trace[1625396642] 'applied index is now lower than readState.Index' (duration: 49.608045ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:05:05.214431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.690491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:05:05.214487Z","caller":"traceutil/trace.go:171","msg":"trace[1023373020] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21571; }","duration":"136.811824ms","start":"2026-08-13T09:05:05.077656Z","end":"2026-08-13T09:05:05.214468Z","steps":["trace[1023373020] 'agreement among raft nodes before linearized reading' (duration: 136.669491ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:05:05.214519Z","caller":"traceutil/trace.go:171","msg":"trace[1019915351] transaction","detail":"{read_only:false; response_revision:21571; number_of_response:1; }","duration":"164.460746ms","start":"2026-08-13T09:05:05.049849Z","end":"2026-08-13T09:05:05.214309Z","steps":["trace[1019915351] 'process raft request' (duration: 114.765869ms)","trace[1019915351] 'compare' (duration: 49.355541ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:05:26.678117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19682} {"level":"info","ts":"2026-08-13T09:05:26.820805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19682,"took":"137.604239ms","hash":3529264835,"current-db-size-bytes":59002880,"current-db-size":"59 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T09:05:26.820924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3529264835,"revision":19682,"compact-revision":17137} {"level":"warn","ts":"2026-08-13T09:09:13.121442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.638382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:09:13.121643Z","caller":"traceutil/trace.go:171","msg":"trace[211583884] linearizableReadLoop","detail":"{readStateIndex:24927; appliedIndex:24926; }","duration":"241.306917ms","start":"2026-08-13T09:09:12.880312Z","end":"2026-08-13T09:09:13.121619Z","steps":["trace[211583884] 'read index received' (duration: 70.062µs)","trace[211583884] 'applied index is now lower than readState.Index' (duration: 241.234685ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:09:13.121667Z","caller":"traceutil/trace.go:171","msg":"trace[1164302156] transaction","detail":"{read_only:false; response_revision:24119; number_of_response:1; }","duration":"305.567054ms","start":"2026-08-13T09:09:12.816059Z","end":"2026-08-13T09:09:13.121626Z","steps":["trace[1164302156] 'process raft request' (duration: 21.602096ms)","trace[1164302156] 'compare' (duration: 283.414627ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:09:13.121729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.425189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:09:13.121775Z","caller":"traceutil/trace.go:171","msg":"trace[962418809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24119; }","duration":"241.52009ms","start":"2026-08-13T09:09:12.880243Z","end":"2026-08-13T09:09:13.121763Z","steps":["trace[962418809] 'agreement among raft nodes before linearized reading' (duration: 241.437019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:09:13.121885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:09:12.816026Z","time spent":"305.745209ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:10:26.690564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21715} {"level":"info","ts":"2026-08-13T09:10:26.799212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21715,"took":"104.43109ms","hash":129743996,"current-db-size-bytes":59846656,"current-db-size":"60 MB","current-db-size-in-use-bytes":43646976,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-13T09:10:26.799279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":129743996,"revision":21715,"compact-revision":19682} {"level":"info","ts":"2026-08-13T09:11:36.497831Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000672b.wal"} {"level":"warn","ts":"2026-08-13T09:13:03.88946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.461095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:03.889628Z","caller":"traceutil/trace.go:171","msg":"trace[109261769] linearizableReadLoop","detail":"{readStateIndex:27259; appliedIndex:27258; }","duration":"183.400031ms","start":"2026-08-13T09:13:03.706201Z","end":"2026-08-13T09:13:03.889602Z","steps":["trace[109261769] 'read index received' (duration: 30.584639ms)","trace[109261769] 'applied index is now lower than readState.Index' (duration: 152.813442ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:03.88973Z","caller":"traceutil/trace.go:171","msg":"trace[1892612783] transaction","detail":"{read_only:false; response_revision:26392; number_of_response:1; }","duration":"215.754265ms","start":"2026-08-13T09:13:03.673891Z","end":"2026-08-13T09:13:03.889646Z","steps":["trace[1892612783] 'process raft request' (duration: 62.956083ms)","trace[1892612783] 'compare' (duration: 152.208701ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:03.889796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.600126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-13T09:13:03.889833Z","caller":"traceutil/trace.go:171","msg":"trace[800617900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26392; }","duration":"183.675707ms","start":"2026-08-13T09:13:03.706145Z","end":"2026-08-13T09:13:03.889821Z","steps":["trace[800617900] 'agreement among raft nodes before linearized reading' (duration: 183.523424ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:04.400926Z","caller":"traceutil/trace.go:171","msg":"trace[2006074431] linearizableReadLoop","detail":"{readStateIndex:27261; appliedIndex:27260; }","duration":"347.160545ms","start":"2026-08-13T09:13:04.053726Z","end":"2026-08-13T09:13:04.400887Z","steps":["trace[2006074431] 'read index received' (duration: 347.051903ms)","trace[2006074431] 'applied index is now lower than readState.Index' (duration: 107.402µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:04.401032Z","caller":"traceutil/trace.go:171","msg":"trace[1860562393] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"350.846036ms","start":"2026-08-13T09:13:04.050104Z","end":"2026-08-13T09:13:04.40095Z","steps":["trace[1860562393] 'process raft request' (duration: 350.587941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:04.401164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.43264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-13T09:13:04.401206Z","caller":"traceutil/trace.go:171","msg":"trace[938662294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26394; }","duration":"347.517631ms","start":"2026-08-13T09:13:04.053677Z","end":"2026-08-13T09:13:04.401195Z","steps":["trace[938662294] 'agreement among raft nodes before linearized reading' (duration: 347.329928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:04.401249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.053658Z","time spent":"347.583022ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T09:13:04.401354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.050068Z","time spent":"351.131421ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:13:04.435128Z","caller":"traceutil/trace.go:171","msg":"trace[796517730] transaction","detail":"{read_only:false; response_revision:26395; number_of_response:1; }","duration":"358.363691ms","start":"2026-08-13T09:13:04.076711Z","end":"2026-08-13T09:13:04.435075Z","steps":["trace[796517730] 'process raft request' (duration: 358.156127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:04.435304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.076683Z","time spent":"358.524423ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:13:04.748384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.059347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:04.748793Z","caller":"traceutil/trace.go:171","msg":"trace[1258588849] linearizableReadLoop","detail":"{readStateIndex:27263; appliedIndex:27262; }","duration":"285.09334ms","start":"2026-08-13T09:13:04.463572Z","end":"2026-08-13T09:13:04.748665Z","steps":["trace[1258588849] 'read index received' (duration: 117.672016ms)","trace[1258588849] 'applied index is now lower than readState.Index' (duration: 167.410834ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:04.748784Z","caller":"traceutil/trace.go:171","msg":"trace[2066584950] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"342.566316ms","start":"2026-08-13T09:13:04.406099Z","end":"2026-08-13T09:13:04.748665Z","steps":["trace[2066584950] 'process raft request' (duration: 175.132352ms)","trace[2066584950] 'compare' (duration: 166.553958ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:04.749015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.416946ms","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-13T09:13:04.749106Z","caller":"traceutil/trace.go:171","msg":"trace[2140509059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26397; }","duration":"285.581959ms","start":"2026-08-13T09:13:04.463502Z","end":"2026-08-13T09:13:04.749084Z","steps":["trace[2140509059] 'agreement among raft nodes before linearized reading' (duration: 285.273673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:04.749161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.406074Z","time spent":"342.987094ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:13:05.035461Z","caller":"traceutil/trace.go:171","msg":"trace[1492516806] linearizableReadLoop","detail":"{readStateIndex:27266; appliedIndex:27264; }","duration":"286.380334ms","start":"2026-08-13T09:13:04.748944Z","end":"2026-08-13T09:13:05.035324Z","steps":["trace[1492516806] 'read index received' (duration: 203.446078ms)","trace[1492516806] 'applied index is now lower than readState.Index' (duration: 82.932546ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:05.035591Z","caller":"traceutil/trace.go:171","msg":"trace[97919704] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"355.051847ms","start":"2026-08-13T09:13:04.680513Z","end":"2026-08-13T09:13:05.035565Z","steps":["trace[97919704] 'process raft request' (duration: 354.675829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:05.035728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.68048Z","time spent":"355.167359ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:13:05.035723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.944029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T09:13:05.035778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.865263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T09:13:05.035812Z","caller":"traceutil/trace.go:171","msg":"trace[938275087] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:26399; }","duration":"471.103682ms","start":"2026-08-13T09:13:04.564684Z","end":"2026-08-13T09:13:05.035788Z","steps":["trace[938275087] 'agreement among raft nodes before linearized reading' (duration: 470.937499ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:05.035833Z","caller":"traceutil/trace.go:171","msg":"trace[1897258287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26399; }","duration":"570.982015ms","start":"2026-08-13T09:13:04.464838Z","end":"2026-08-13T09:13:05.03582Z","steps":["trace[1897258287] 'agreement among raft nodes before linearized reading' (duration: 570.791201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:05.035861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.464828Z","time spent":"571.028986ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T09:13:05.035859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.564656Z","time spent":"471.192743ms","remote":"127.0.0.1:46856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-08-13T09:13:05.03628Z","caller":"traceutil/trace.go:171","msg":"trace[1113423220] transaction","detail":"{read_only:false; response_revision:26398; number_of_response:1; }","duration":"355.704739ms","start":"2026-08-13T09:13:04.679859Z","end":"2026-08-13T09:13:05.035564Z","steps":["trace[1113423220] 'process raft request' (duration: 272.515857ms)","trace[1113423220] 'compare' (duration: 82.439597ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:05.036397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.679817Z","time spent":"356.535825ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:13:05.449217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.356151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:05.449503Z","caller":"traceutil/trace.go:171","msg":"trace[342719803] transaction","detail":"{read_only:false; response_revision:26400; number_of_response:1; }","duration":"694.982452ms","start":"2026-08-13T09:13:04.754487Z","end":"2026-08-13T09:13:05.44947Z","steps":["trace[342719803] 'process raft request' (duration: 403.273755ms)","trace[342719803] 'compare' (duration: 291.056235ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:05.449619Z","caller":"traceutil/trace.go:171","msg":"trace[1578667790] transaction","detail":"{read_only:false; response_revision:26404; number_of_response:1; }","duration":"176.014819ms","start":"2026-08-13T09:13:05.273584Z","end":"2026-08-13T09:13:05.449599Z","steps":["trace[1578667790] 'process raft request' (duration: 175.970068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:05.449723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.754466Z","time spent":"695.136805ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:13:05.450009Z","caller":"traceutil/trace.go:171","msg":"trace[939137579] transaction","detail":"{read_only:false; response_revision:26401; number_of_response:1; }","duration":"635.620288ms","start":"2026-08-13T09:13:04.814377Z","end":"2026-08-13T09:13:05.449997Z","steps":["trace[939137579] 'process raft request' (duration: 634.969796ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:05.450025Z","caller":"traceutil/trace.go:171","msg":"trace[826076619] transaction","detail":"{read_only:false; response_revision:26402; number_of_response:1; }","duration":"408.937995ms","start":"2026-08-13T09:13:05.041023Z","end":"2026-08-13T09:13:05.449961Z","steps":["trace[826076619] 'process raft request' (duration: 408.448105ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:05.450114Z","caller":"traceutil/trace.go:171","msg":"trace[2057210967] transaction","detail":"{read_only:false; response_revision:26403; number_of_response:1; }","duration":"179.317043ms","start":"2026-08-13T09:13:05.270762Z","end":"2026-08-13T09:13:05.450079Z","steps":["trace[2057210967] 'process raft request' (duration: 178.754951ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:05.450176Z","caller":"traceutil/trace.go:171","msg":"trace[324512326] linearizableReadLoop","detail":"{readStateIndex:27268; appliedIndex:27266; }","duration":"414.576183ms","start":"2026-08-13T09:13:05.035581Z","end":"2026-08-13T09:13:05.450158Z","steps":["trace[324512326] 'read index received' (duration: 122.167552ms)","trace[324512326] 'applied index is now lower than readState.Index' (duration: 292.406631ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:05.450115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.814366Z","time spent":"635.711421ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:13:05.450274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.416114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T09:13:05.450275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:05.040997Z","time spent":"409.157249ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:13:05.450312Z","caller":"traceutil/trace.go:171","msg":"trace[144237145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26404; }","duration":"570.455665ms","start":"2026-08-13T09:13:04.879846Z","end":"2026-08-13T09:13:05.450302Z","steps":["trace[144237145] 'agreement among raft nodes before linearized reading' (duration: 570.385404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:05.45034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:04.879777Z","time spent":"570.556947ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T09:13:05.742931Z","caller":"traceutil/trace.go:171","msg":"trace[1340796384] transaction","detail":"{read_only:false; response_revision:26405; number_of_response:1; }","duration":"297.376756ms","start":"2026-08-13T09:13:05.445491Z","end":"2026-08-13T09:13:05.742868Z","steps":["trace[1340796384] 'process raft request' (duration: 278.535533ms)","trace[1340796384] 'compare' (duration: 18.431165ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:05.742934Z","caller":"traceutil/trace.go:171","msg":"trace[1232699336] linearizableReadLoop","detail":"{readStateIndex:27272; appliedIndex:27271; }","duration":"292.646535ms","start":"2026-08-13T09:13:05.450204Z","end":"2026-08-13T09:13:05.742851Z","steps":["trace[1232699336] 'read index received' (duration: 273.855673ms)","trace[1232699336] 'applied index is now lower than readState.Index' (duration: 18.788742ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:05.74349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.019223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-13T09:13:05.74357Z","caller":"traceutil/trace.go:171","msg":"trace[1108653425] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26405; }","duration":"574.167726ms","start":"2026-08-13T09:13:05.169383Z","end":"2026-08-13T09:13:05.743551Z","steps":["trace[1108653425] 'agreement among raft nodes before linearized reading' (duration: 573.635766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:05.743625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:05.16936Z","time spent":"574.250417ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":232,"response size":33,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-13T09:13:05.743607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.969022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T09:13:05.743713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.952872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:05.743751Z","caller":"traceutil/trace.go:171","msg":"trace[353911200] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26405; }","duration":"185.049054ms","start":"2026-08-13T09:13:05.558689Z","end":"2026-08-13T09:13:05.743738Z","steps":["trace[353911200] 'agreement among raft nodes before linearized reading' (duration: 184.974572ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:05.743731Z","caller":"traceutil/trace.go:171","msg":"trace[1076067570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26405; }","duration":"292.123555ms","start":"2026-08-13T09:13:05.451575Z","end":"2026-08-13T09:13:05.743698Z","steps":["trace[1076067570] 'agreement among raft nodes before linearized reading' (duration: 291.959592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:05.74376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.375584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:273004"} {"level":"warn","ts":"2026-08-13T09:13:05.743803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.11258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-13T09:13:05.743825Z","caller":"traceutil/trace.go:171","msg":"trace[1821548314] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26405; }","duration":"352.507837ms","start":"2026-08-13T09:13:05.391297Z","end":"2026-08-13T09:13:05.743805Z","steps":["trace[1821548314] 'agreement among raft nodes before linearized reading' (duration: 351.822904ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:05.743853Z","caller":"traceutil/trace.go:171","msg":"trace[1324727976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26405; }","duration":"512.188201ms","start":"2026-08-13T09:13:05.231642Z","end":"2026-08-13T09:13:05.74383Z","steps":["trace[1324727976] 'agreement among raft nodes before linearized reading' (duration: 512.034468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:05.743871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:05.391272Z","time spent":"352.588679ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":273028,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-08-13T09:13:05.743903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:05.23162Z","time spent":"512.274414ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T09:13:05.968513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.591331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:05.969761Z","caller":"traceutil/trace.go:171","msg":"trace[1270024361] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"220.850883ms","start":"2026-08-13T09:13:05.748879Z","end":"2026-08-13T09:13:05.96973Z","steps":["trace[1270024361] 'process raft request' (duration: 109.948207ms)","trace[1270024361] 'compare' (duration: 109.477899ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:06.06902Z","caller":"traceutil/trace.go:171","msg":"trace[132978571] linearizableReadLoop","detail":"{readStateIndex:27275; appliedIndex:27273; }","duration":"138.856363ms","start":"2026-08-13T09:13:05.930143Z","end":"2026-08-13T09:13:06.069Z","steps":["trace[132978571] 'read index received' (duration: 106.410499ms)","trace[132978571] 'applied index is now lower than readState.Index' (duration: 32.444614ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:06.069113Z","caller":"traceutil/trace.go:171","msg":"trace[1472077571] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"174.189894ms","start":"2026-08-13T09:13:05.894887Z","end":"2026-08-13T09:13:06.069077Z","steps":["trace[1472077571] 'process raft request' (duration: 173.899368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:06.069245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.086439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-13T09:13:06.06932Z","caller":"traceutil/trace.go:171","msg":"trace[1977567402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26407; }","duration":"139.234811ms","start":"2026-08-13T09:13:05.93007Z","end":"2026-08-13T09:13:06.069305Z","steps":["trace[1977567402] 'agreement among raft nodes before linearized reading' (duration: 139.049507ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:11.633292Z","caller":"traceutil/trace.go:171","msg":"trace[422148635] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"150.697042ms","start":"2026-08-13T09:13:11.482571Z","end":"2026-08-13T09:13:11.633268Z","steps":["trace[422148635] 'process raft request' (duration: 150.548739ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:11.633556Z","caller":"traceutil/trace.go:171","msg":"trace[1344805177] linearizableReadLoop","detail":"{readStateIndex:27313; appliedIndex:27313; }","duration":"148.351937ms","start":"2026-08-13T09:13:11.485175Z","end":"2026-08-13T09:13:11.633527Z","steps":["trace[1344805177] 'read index received' (duration: 148.335487ms)","trace[1344805177] 'applied index is now lower than readState.Index' (duration: 14.32µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:11.63377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.577821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-13T09:13:11.633799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.279526ms","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-13T09:13:11.633816Z","caller":"traceutil/trace.go:171","msg":"trace[1688041169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26444; }","duration":"148.672783ms","start":"2026-08-13T09:13:11.485134Z","end":"2026-08-13T09:13:11.633807Z","steps":["trace[1688041169] 'agreement among raft nodes before linearized reading' (duration: 148.48914ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:11.63384Z","caller":"traceutil/trace.go:171","msg":"trace[1784956594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26444; }","duration":"148.341707ms","start":"2026-08-13T09:13:11.485486Z","end":"2026-08-13T09:13:11.633828Z","steps":["trace[1784956594] 'agreement among raft nodes before linearized reading' (duration: 148.200374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:12.003981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.883939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:12.004248Z","caller":"traceutil/trace.go:171","msg":"trace[754413671] linearizableReadLoop","detail":"{readStateIndex:27315; appliedIndex:27313; }","duration":"334.542512ms","start":"2026-08-13T09:13:11.669689Z","end":"2026-08-13T09:13:12.004231Z","steps":["trace[754413671] 'read index received' (duration: 107.426348ms)","trace[754413671] 'applied index is now lower than readState.Index' (duration: 227.114964ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:12.004541Z","caller":"traceutil/trace.go:171","msg":"trace[1675175614] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"366.795753ms","start":"2026-08-13T09:13:11.637715Z","end":"2026-08-13T09:13:12.004511Z","steps":["trace[1675175614] 'process raft request' (duration: 366.391265ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:12.004619Z","caller":"traceutil/trace.go:171","msg":"trace[1911066959] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"367.309733ms","start":"2026-08-13T09:13:11.637293Z","end":"2026-08-13T09:13:12.004603Z","steps":["trace[1911066959] 'process raft request' (duration: 139.72239ms)","trace[1911066959] 'compare' (duration: 226.517712ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:12.00468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:11.637709Z","time spent":"366.903215ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:13:12.004694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:11.637272Z","time spent":"367.378444ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:13:12.004552Z","caller":"traceutil/trace.go:171","msg":"trace[634075168] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"240.204944ms","start":"2026-08-13T09:13:11.764325Z","end":"2026-08-13T09:13:12.00453Z","steps":["trace[634075168] 'process raft request' (duration: 239.865838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:12.005223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.52397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:12.005275Z","caller":"traceutil/trace.go:171","msg":"trace[1764008769] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26447; }","duration":"335.581522ms","start":"2026-08-13T09:13:11.669681Z","end":"2026-08-13T09:13:12.005263Z","steps":["trace[1764008769] 'agreement among raft nodes before linearized reading' (duration: 334.784136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:12.005309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:11.669641Z","time spent":"335.659594ms","remote":"127.0.0.1:52518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-08-13T09:13:12.004507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.035869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:12.006179Z","caller":"traceutil/trace.go:171","msg":"trace[422789196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26447; }","duration":"125.739211ms","start":"2026-08-13T09:13:11.880424Z","end":"2026-08-13T09:13:12.006163Z","steps":["trace[422789196] 'agreement among raft nodes before linearized reading' (duration: 124.049758ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:12.205174Z","caller":"traceutil/trace.go:171","msg":"trace[1245300656] transaction","detail":"{read_only:false; response_revision:26448; number_of_response:1; }","duration":"192.454726ms","start":"2026-08-13T09:13:12.012677Z","end":"2026-08-13T09:13:12.205132Z","steps":["trace[1245300656] 'process raft request' (duration: 191.398116ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:12.21582Z","caller":"traceutil/trace.go:171","msg":"trace[1167780791] linearizableReadLoop","detail":"{readStateIndex:27318; appliedIndex:27316; }","duration":"117.573664ms","start":"2026-08-13T09:13:12.098222Z","end":"2026-08-13T09:13:12.215795Z","steps":["trace[1167780791] 'read index received' (duration: 105.872278ms)","trace[1167780791] 'applied index is now lower than readState.Index' (duration: 11.700386ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:12.215856Z","caller":"traceutil/trace.go:171","msg":"trace[695491495] transaction","detail":"{read_only:false; response_revision:26449; number_of_response:1; }","duration":"123.567569ms","start":"2026-08-13T09:13:12.092255Z","end":"2026-08-13T09:13:12.215823Z","steps":["trace[695491495] 'process raft request' (duration: 123.346065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:12.216016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.780757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-13T09:13:12.216072Z","caller":"traceutil/trace.go:171","msg":"trace[1208374393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26450; }","duration":"117.89506ms","start":"2026-08-13T09:13:12.098167Z","end":"2026-08-13T09:13:12.216062Z","steps":["trace[1208374393] 'agreement among raft nodes before linearized reading' (duration: 117.726547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:12.512226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.69385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:12.512368Z","caller":"traceutil/trace.go:171","msg":"trace[1607661158] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"290.161937ms","start":"2026-08-13T09:13:12.222187Z","end":"2026-08-13T09:13:12.512349Z","steps":["trace[1607661158] 'process raft request' (duration: 151.251472ms)","trace[1607661158] 'compare' (duration: 138.373864ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:12.655044Z","caller":"traceutil/trace.go:171","msg":"trace[307973065] transaction","detail":"{read_only:false; response_revision:26453; number_of_response:1; }","duration":"181.593596ms","start":"2026-08-13T09:13:12.47342Z","end":"2026-08-13T09:13:12.655013Z","steps":["trace[307973065] 'process raft request' (duration: 181.25041ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:13.113132Z","caller":"traceutil/trace.go:171","msg":"trace[1704577960] linearizableReadLoop","detail":"{readStateIndex:27325; appliedIndex:27324; }","duration":"154.20433ms","start":"2026-08-13T09:13:12.958897Z","end":"2026-08-13T09:13:13.113101Z","steps":["trace[1704577960] 'read index received' (duration: 62.125896ms)","trace[1704577960] 'applied index is now lower than readState.Index' (duration: 92.076864ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:13.113261Z","caller":"traceutil/trace.go:171","msg":"trace[1374575100] transaction","detail":"{read_only:false; response_revision:26456; number_of_response:1; }","duration":"156.87766ms","start":"2026-08-13T09:13:12.956366Z","end":"2026-08-13T09:13:13.113244Z","steps":["trace[1374575100] 'process raft request' (duration: 64.634964ms)","trace[1374575100] 'compare' (duration: 91.88169ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:13.113349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.434984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T09:13:13.113458Z","caller":"traceutil/trace.go:171","msg":"trace[339183692] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26456; }","duration":"154.579316ms","start":"2026-08-13T09:13:12.958853Z","end":"2026-08-13T09:13:13.113432Z","steps":["trace[339183692] 'agreement among raft nodes before linearized reading' (duration: 154.433783ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:13.335776Z","caller":"traceutil/trace.go:171","msg":"trace[1686771724] linearizableReadLoop","detail":"{readStateIndex:27327; appliedIndex:27325; }","duration":"222.555154ms","start":"2026-08-13T09:13:13.113199Z","end":"2026-08-13T09:13:13.335755Z","steps":["trace[1686771724] 'read index received' (duration: 48.245718ms)","trace[1686771724] 'applied index is now lower than readState.Index' (duration: 174.308626ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:13.336125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.514915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-13T09:13:13.336157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.309976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-08-13T09:13:13.33617Z","caller":"traceutil/trace.go:171","msg":"trace[1917413686] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26458; }","duration":"209.620147ms","start":"2026-08-13T09:13:13.126537Z","end":"2026-08-13T09:13:13.336158Z","steps":["trace[1917413686] 'agreement among raft nodes before linearized reading' (duration: 209.529175ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:13.336186Z","caller":"traceutil/trace.go:171","msg":"trace[158513301] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26458; }","duration":"336.373877ms","start":"2026-08-13T09:13:12.999802Z","end":"2026-08-13T09:13:13.336176Z","steps":["trace[158513301] 'agreement among raft nodes before linearized reading' (duration: 336.277866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:13.336212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:12.999782Z","time spent":"336.423118ms","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-08-13T09:13:13.336201Z","caller":"traceutil/trace.go:171","msg":"trace[1317299319] transaction","detail":"{read_only:false; response_revision:26458; number_of_response:1; }","duration":"256.676053ms","start":"2026-08-13T09:13:13.079466Z","end":"2026-08-13T09:13:13.336142Z","steps":["trace[1317299319] 'process raft request' (duration: 256.134492ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:13.826746Z","caller":"traceutil/trace.go:171","msg":"trace[108021412] transaction","detail":"{read_only:false; response_revision:26460; number_of_response:1; }","duration":"186.309848ms","start":"2026-08-13T09:13:13.640404Z","end":"2026-08-13T09:13:13.826714Z","steps":["trace[108021412] 'process raft request' (duration: 113.515736ms)","trace[108021412] 'compare' (duration: 72.112258ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:14.050752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.114062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:14.051041Z","caller":"traceutil/trace.go:171","msg":"trace[1114978561] linearizableReadLoop","detail":"{readStateIndex:27330; appliedIndex:27329; }","duration":"172.134455ms","start":"2026-08-13T09:13:13.878806Z","end":"2026-08-13T09:13:14.05094Z","steps":["trace[1114978561] 'read index received' (duration: 62.622767ms)","trace[1114978561] 'applied index is now lower than readState.Index' (duration: 109.510108ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:14.05125Z","caller":"traceutil/trace.go:171","msg":"trace[498963295] transaction","detail":"{read_only:false; response_revision:26461; number_of_response:1; }","duration":"274.448364ms","start":"2026-08-13T09:13:13.776739Z","end":"2026-08-13T09:13:14.051187Z","steps":["trace[498963295] 'process raft request' (duration: 164.788553ms)","trace[498963295] 'compare' (duration: 108.953318ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:14.05137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.520412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:14.051467Z","caller":"traceutil/trace.go:171","msg":"trace[889733087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26462; }","duration":"172.712626ms","start":"2026-08-13T09:13:13.878728Z","end":"2026-08-13T09:13:14.051441Z","steps":["trace[889733087] 'agreement among raft nodes before linearized reading' (duration: 172.451131ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:14.235281Z","caller":"traceutil/trace.go:171","msg":"trace[1785247130] transaction","detail":"{read_only:false; response_revision:26463; number_of_response:1; }","duration":"178.750522ms","start":"2026-08-13T09:13:14.056475Z","end":"2026-08-13T09:13:14.235226Z","steps":["trace[1785247130] 'process raft request' (duration: 175.258564ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:14.24201Z","caller":"traceutil/trace.go:171","msg":"trace[1532171487] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"184.621925ms","start":"2026-08-13T09:13:14.057365Z","end":"2026-08-13T09:13:14.241987Z","steps":["trace[1532171487] 'process raft request' (duration: 184.097485ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:14.380564Z","caller":"traceutil/trace.go:171","msg":"trace[1530010369] linearizableReadLoop","detail":"{readStateIndex:27335; appliedIndex:27334; }","duration":"123.726612ms","start":"2026-08-13T09:13:14.256808Z","end":"2026-08-13T09:13:14.380535Z","steps":["trace[1530010369] 'read index received' (duration: 108.977329ms)","trace[1530010369] 'applied index is now lower than readState.Index' (duration: 14.747613ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:14.380683Z","caller":"traceutil/trace.go:171","msg":"trace[216363796] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"133.950429ms","start":"2026-08-13T09:13:14.246702Z","end":"2026-08-13T09:13:14.380652Z","steps":["trace[216363796] 'process raft request' (duration: 119.095683ms)","trace[216363796] 'compare' (duration: 14.609501ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:14.380775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.941886ms","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":"info","ts":"2026-08-13T09:13:14.380839Z","caller":"traceutil/trace.go:171","msg":"trace[4688480] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26466; }","duration":"124.05958ms","start":"2026-08-13T09:13:14.256761Z","end":"2026-08-13T09:13:14.38082Z","steps":["trace[4688480] 'agreement among raft nodes before linearized reading' (duration: 123.929466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:14.759514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.227434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-13T09:13:14.759599Z","caller":"traceutil/trace.go:171","msg":"trace[216476231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26466; }","duration":"243.366297ms","start":"2026-08-13T09:13:14.516213Z","end":"2026-08-13T09:13:14.759579Z","steps":["trace[216476231] 'range keys from in-memory index tree' (duration: 243.065441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:14.759751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.326641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:14.759827Z","caller":"traceutil/trace.go:171","msg":"trace[344386584] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"231.443177ms","start":"2026-08-13T09:13:14.528369Z","end":"2026-08-13T09:13:14.759812Z","steps":["trace[344386584] 'process raft request' (duration: 34.997294ms)","trace[344386584] 'compare' (duration: 195.886593ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:14.808603Z","caller":"traceutil/trace.go:171","msg":"trace[1058005560] transaction","detail":"{read_only:false; response_revision:26468; number_of_response:1; }","duration":"133.656813ms","start":"2026-08-13T09:13:14.674924Z","end":"2026-08-13T09:13:14.808581Z","steps":["trace[1058005560] 'process raft request' (duration: 133.50674ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:15.099691Z","caller":"traceutil/trace.go:171","msg":"trace[1213646400] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"335.44297ms","start":"2026-08-13T09:13:14.764218Z","end":"2026-08-13T09:13:15.099661Z","steps":["trace[1213646400] 'process raft request' (duration: 327.038098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:15.09987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:14.764194Z","time spent":"335.588812ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:13:15.111841Z","caller":"traceutil/trace.go:171","msg":"trace[1750746704] linearizableReadLoop","detail":"{readStateIndex:27339; appliedIndex:27337; }","duration":"234.077197ms","start":"2026-08-13T09:13:14.87774Z","end":"2026-08-13T09:13:15.111818Z","steps":["trace[1750746704] 'read index received' (duration: 213.532911ms)","trace[1750746704] 'applied index is now lower than readState.Index' (duration: 20.542976ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:15.112078Z","caller":"traceutil/trace.go:171","msg":"trace[1949167292] transaction","detail":"{read_only:false; response_revision:26470; number_of_response:1; }","duration":"240.227606ms","start":"2026-08-13T09:13:14.871841Z","end":"2026-08-13T09:13:15.112069Z","steps":["trace[1949167292] 'process raft request' (duration: 239.823588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:15.112229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.475915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:15.112287Z","caller":"traceutil/trace.go:171","msg":"trace[1428830832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26470; }","duration":"234.571337ms","start":"2026-08-13T09:13:14.877705Z","end":"2026-08-13T09:13:15.112276Z","steps":["trace[1428830832] 'agreement among raft nodes before linearized reading' (duration: 234.484686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:15.112468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.740207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T09:13:15.112487Z","caller":"traceutil/trace.go:171","msg":"trace[1994112673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26470; }","duration":"225.787638ms","start":"2026-08-13T09:13:14.886695Z","end":"2026-08-13T09:13:15.112483Z","steps":["trace[1994112673] 'agreement among raft nodes before linearized reading' (duration: 225.716147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:15.112612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.52511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:15.112632Z","caller":"traceutil/trace.go:171","msg":"trace[1429192862] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26470; }","duration":"110.57343ms","start":"2026-08-13T09:13:15.002053Z","end":"2026-08-13T09:13:15.112627Z","steps":["trace[1429192862] 'agreement among raft nodes before linearized reading' (duration: 110.537979ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:15.274787Z","caller":"traceutil/trace.go:171","msg":"trace[660894878] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"104.566404ms","start":"2026-08-13T09:13:15.170196Z","end":"2026-08-13T09:13:15.274762Z","steps":["trace[660894878] 'process raft request' (duration: 102.603776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:15.561365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.08206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:15.561589Z","caller":"traceutil/trace.go:171","msg":"trace[1208675789] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"218.076959ms","start":"2026-08-13T09:13:15.343488Z","end":"2026-08-13T09:13:15.561565Z","steps":["trace[1208675789] 'process raft request' (duration: 82.696513ms)","trace[1208675789] 'compare' (duration: 134.820036ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:16.158538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.00954ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-08-13T09:13:16.159163Z","caller":"traceutil/trace.go:171","msg":"trace[1356838210] linearizableReadLoop","detail":"{readStateIndex:27344; appliedIndex:27342; }","duration":"280.593182ms","start":"2026-08-13T09:13:15.878539Z","end":"2026-08-13T09:13:16.159132Z","steps":["trace[1356838210] 'read index received' (duration: 261.863202ms)","trace[1356838210] 'applied index is now lower than readState.Index' (duration: 18.72887ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:16.159263Z","caller":"traceutil/trace.go:171","msg":"trace[1045460493] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"325.397256ms","start":"2026-08-13T09:13:15.833855Z","end":"2026-08-13T09:13:16.159252Z","steps":["trace[1045460493] 'process raft request' (duration: 324.784984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:16.159419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:15.833818Z","time spent":"325.472726ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:13:16.162086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.538009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:16.162146Z","caller":"traceutil/trace.go:171","msg":"trace[456927486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26474; }","duration":"283.633791ms","start":"2026-08-13T09:13:15.878496Z","end":"2026-08-13T09:13:16.16213Z","steps":["trace[456927486] 'agreement among raft nodes before linearized reading' (duration: 280.729495ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:16.162231Z","caller":"traceutil/trace.go:171","msg":"trace[1796004449] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"104.325969ms","start":"2026-08-13T09:13:16.057883Z","end":"2026-08-13T09:13:16.162209Z","steps":["trace[1796004449] 'process raft request' (duration: 103.090245ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:16.366281Z","caller":"traceutil/trace.go:171","msg":"trace[967016060] linearizableReadLoop","detail":"{readStateIndex:27346; appliedIndex:27345; }","duration":"120.748635ms","start":"2026-08-13T09:13:16.245506Z","end":"2026-08-13T09:13:16.366255Z","steps":["trace[967016060] 'read index received' (duration: 81.01911ms)","trace[967016060] 'applied index is now lower than readState.Index' (duration: 39.728715ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:16.366308Z","caller":"traceutil/trace.go:171","msg":"trace[920512500] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"123.022669ms","start":"2026-08-13T09:13:16.243242Z","end":"2026-08-13T09:13:16.366264Z","steps":["trace[920512500] 'process raft request' (duration: 83.357996ms)","trace[920512500] 'compare' (duration: 39.433499ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:16.366567Z","caller":"traceutil/trace.go:171","msg":"trace[1329880633] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"119.208675ms","start":"2026-08-13T09:13:16.247328Z","end":"2026-08-13T09:13:16.366537Z","steps":["trace[1329880633] 'process raft request' (duration: 118.880729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:16.36665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.126782ms","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-13T09:13:16.366743Z","caller":"traceutil/trace.go:171","msg":"trace[819566310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26477; }","duration":"121.263645ms","start":"2026-08-13T09:13:16.245463Z","end":"2026-08-13T09:13:16.366726Z","steps":["trace[819566310] 'agreement among raft nodes before linearized reading' (duration: 120.885568ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:23.265375Z","caller":"traceutil/trace.go:171","msg":"trace[754146615] transaction","detail":"{read_only:false; response_revision:26584; number_of_response:1; }","duration":"132.309557ms","start":"2026-08-13T09:13:23.133027Z","end":"2026-08-13T09:13:23.265337Z","steps":["trace[754146615] 'process raft request' (duration: 132.076493ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:23.404752Z","caller":"traceutil/trace.go:171","msg":"trace[62434949] linearizableReadLoop","detail":"{readStateIndex:27457; appliedIndex:27455; }","duration":"223.176347ms","start":"2026-08-13T09:13:23.181553Z","end":"2026-08-13T09:13:23.404729Z","steps":["trace[62434949] 'read index received' (duration: 83.575889ms)","trace[62434949] 'applied index is now lower than readState.Index' (duration: 139.599608ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:23.404923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.358671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-13T09:13:23.404945Z","caller":"traceutil/trace.go:171","msg":"trace[1993404296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26585; }","duration":"223.433492ms","start":"2026-08-13T09:13:23.181504Z","end":"2026-08-13T09:13:23.404938Z","steps":["trace[1993404296] 'agreement among raft nodes before linearized reading' (duration: 223.31432ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:23.405096Z","caller":"traceutil/trace.go:171","msg":"trace[692323785] transaction","detail":"{read_only:false; response_revision:26585; number_of_response:1; }","duration":"270.239344ms","start":"2026-08-13T09:13:23.134824Z","end":"2026-08-13T09:13:23.405063Z","steps":["trace[692323785] 'process raft request' (duration: 269.785565ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:23.405403Z","caller":"traceutil/trace.go:171","msg":"trace[217052599] transaction","detail":"{read_only:false; response_revision:26586; number_of_response:1; }","duration":"105.748006ms","start":"2026-08-13T09:13:23.299636Z","end":"2026-08-13T09:13:23.405384Z","steps":["trace[217052599] 'process raft request' (duration: 105.645004ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:23.717382Z","caller":"traceutil/trace.go:171","msg":"trace[1510553280] transaction","detail":"{read_only:false; response_revision:26588; number_of_response:1; }","duration":"247.2172ms","start":"2026-08-13T09:13:23.470133Z","end":"2026-08-13T09:13:23.717351Z","steps":["trace[1510553280] 'process raft request' (duration: 223.743258ms)","trace[1510553280] 'compare' (duration: 23.339699ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:23.730349Z","caller":"traceutil/trace.go:171","msg":"trace[866414852] transaction","detail":"{read_only:false; response_revision:26589; number_of_response:1; }","duration":"137.823703ms","start":"2026-08-13T09:13:23.592489Z","end":"2026-08-13T09:13:23.730312Z","steps":["trace[866414852] 'process raft request' (duration: 137.609549ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:23.888062Z","caller":"traceutil/trace.go:171","msg":"trace[888432972] transaction","detail":"{read_only:false; response_revision:26590; number_of_response:1; }","duration":"151.14248ms","start":"2026-08-13T09:13:23.736894Z","end":"2026-08-13T09:13:23.888036Z","steps":["trace[888432972] 'process raft request' (duration: 150.754393ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.274161Z","caller":"traceutil/trace.go:171","msg":"trace[1922461422] linearizableReadLoop","detail":"{readStateIndex:27463; appliedIndex:27462; }","duration":"192.896765ms","start":"2026-08-13T09:13:24.081237Z","end":"2026-08-13T09:13:24.274134Z","steps":["trace[1922461422] 'read index received' (duration: 192.769242ms)","trace[1922461422] 'applied index is now lower than readState.Index' (duration: 126.133µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:24.274245Z","caller":"traceutil/trace.go:171","msg":"trace[154965692] transaction","detail":"{read_only:false; response_revision:26591; number_of_response:1; }","duration":"217.274273ms","start":"2026-08-13T09:13:24.056912Z","end":"2026-08-13T09:13:24.274186Z","steps":["trace[154965692] 'process raft request' (duration: 217.047679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:24.274496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.226541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-35fac85d-5f25-4215-9a27-717be14c7e73\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-13T09:13:24.274588Z","caller":"traceutil/trace.go:171","msg":"trace[861129733] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-35fac85d-5f25-4215-9a27-717be14c7e73; range_end:; response_count:1; response_revision:26591; }","duration":"193.369383ms","start":"2026-08-13T09:13:24.081199Z","end":"2026-08-13T09:13:24.274569Z","steps":["trace[861129733] 'agreement among raft nodes before linearized reading' (duration: 193.039537ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.312743Z","caller":"traceutil/trace.go:171","msg":"trace[1296220255] transaction","detail":"{read_only:false; response_revision:26592; number_of_response:1; }","duration":"130.085245ms","start":"2026-08-13T09:13:24.18264Z","end":"2026-08-13T09:13:24.312725Z","steps":["trace[1296220255] 'process raft request' (duration: 129.82994ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.312953Z","caller":"traceutil/trace.go:171","msg":"trace[1404490605] transaction","detail":"{read_only:false; response_revision:26593; number_of_response:1; }","duration":"127.384852ms","start":"2026-08-13T09:13:24.185556Z","end":"2026-08-13T09:13:24.312941Z","steps":["trace[1404490605] 'process raft request' (duration: 127.072946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:24.312997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.697762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-08-13T09:13:24.313028Z","caller":"traceutil/trace.go:171","msg":"trace[1086488804] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:26593; }","duration":"230.754023ms","start":"2026-08-13T09:13:24.082266Z","end":"2026-08-13T09:13:24.31302Z","steps":["trace[1086488804] 'agreement among raft nodes before linearized reading' (duration: 230.61911ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.470711Z","caller":"traceutil/trace.go:171","msg":"trace[1854271465] transaction","detail":"{read_only:false; response_revision:26594; number_of_response:1; }","duration":"150.728422ms","start":"2026-08-13T09:13:24.319953Z","end":"2026-08-13T09:13:24.470682Z","steps":["trace[1854271465] 'process raft request' (duration: 128.051956ms)","trace[1854271465] 'compare' (duration: 22.550944ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:24.472402Z","caller":"traceutil/trace.go:171","msg":"trace[109461484] transaction","detail":"{read_only:false; response_revision:26595; number_of_response:1; }","duration":"151.67397ms","start":"2026-08-13T09:13:24.320211Z","end":"2026-08-13T09:13:24.471885Z","steps":["trace[109461484] 'process raft request' (duration: 151.225111ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.592968Z","caller":"traceutil/trace.go:171","msg":"trace[690576631] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"115.635317ms","start":"2026-08-13T09:13:24.477305Z","end":"2026-08-13T09:13:24.59294Z","steps":["trace[690576631] 'process raft request' (duration: 115.569335ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.593211Z","caller":"traceutil/trace.go:171","msg":"trace[1874281798] transaction","detail":"{read_only:false; response_revision:26598; number_of_response:1; }","duration":"116.236417ms","start":"2026-08-13T09:13:24.476937Z","end":"2026-08-13T09:13:24.593173Z","steps":["trace[1874281798] 'process raft request' (duration: 115.155757ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.593459Z","caller":"traceutil/trace.go:171","msg":"trace[2074260665] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"116.417112ms","start":"2026-08-13T09:13:24.476985Z","end":"2026-08-13T09:13:24.593403Z","steps":["trace[2074260665] 'process raft request' (duration: 115.7941ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:24.911551Z","caller":"traceutil/trace.go:171","msg":"trace[900122020] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"312.287744ms","start":"2026-08-13T09:13:24.599241Z","end":"2026-08-13T09:13:24.911529Z","steps":["trace[900122020] 'process raft request' (duration: 312.160741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:24.911687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:24.599231Z","time spent":"312.383865ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:13:24.917177Z","caller":"traceutil/trace.go:171","msg":"trace[45418489] transaction","detail":"{read_only:false; response_revision:26602; number_of_response:1; }","duration":"110.003379ms","start":"2026-08-13T09:13:24.807139Z","end":"2026-08-13T09:13:24.917142Z","steps":["trace[45418489] 'process raft request' (duration: 109.755394ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:27.20581Z","caller":"traceutil/trace.go:171","msg":"trace[1623235516] linearizableReadLoop","detail":"{readStateIndex:27490; appliedIndex:27488; }","duration":"214.774126ms","start":"2026-08-13T09:13:26.991012Z","end":"2026-08-13T09:13:27.205786Z","steps":["trace[1623235516] 'read index received' (duration: 22.145796ms)","trace[1623235516] 'applied index is now lower than readState.Index' (duration: 192.62694ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:27.206005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.98096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-13T09:13:27.206023Z","caller":"traceutil/trace.go:171","msg":"trace[1579912527] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"282.418778ms","start":"2026-08-13T09:13:26.923577Z","end":"2026-08-13T09:13:27.205996Z","steps":["trace[1579912527] 'process raft request' (duration: 268.109702ms)","trace[1579912527] 'compare' (duration: 13.966359ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:27.206067Z","caller":"traceutil/trace.go:171","msg":"trace[1129800514] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26617; }","duration":"215.061112ms","start":"2026-08-13T09:13:26.990973Z","end":"2026-08-13T09:13:27.206034Z","steps":["trace[1129800514] 'agreement among raft nodes before linearized reading' (duration: 214.904489ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:27.412827Z","caller":"traceutil/trace.go:171","msg":"trace[1343485710] linearizableReadLoop","detail":"{readStateIndex:27492; appliedIndex:27490; }","duration":"124.323184ms","start":"2026-08-13T09:13:27.288481Z","end":"2026-08-13T09:13:27.412804Z","steps":["trace[1343485710] 'read index received' (duration: 112.975525ms)","trace[1343485710] 'applied index is now lower than readState.Index' (duration: 11.346509ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:27.412879Z","caller":"traceutil/trace.go:171","msg":"trace[1803177884] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"134.633533ms","start":"2026-08-13T09:13:27.27822Z","end":"2026-08-13T09:13:27.412854Z","steps":["trace[1803177884] 'process raft request' (duration: 134.49494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:27.413037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.535769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-35fac85d-5f25-4215-9a27-717be14c7e73\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-08-13T09:13:27.413123Z","caller":"traceutil/trace.go:171","msg":"trace[808349650] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-35fac85d-5f25-4215-9a27-717be14c7e73; range_end:; response_count:1; response_revision:26619; }","duration":"124.64517ms","start":"2026-08-13T09:13:27.288438Z","end":"2026-08-13T09:13:27.413083Z","steps":["trace[808349650] 'agreement among raft nodes before linearized reading' (duration: 124.458157ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:27.413385Z","caller":"traceutil/trace.go:171","msg":"trace[238751590] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"168.22131ms","start":"2026-08-13T09:13:27.244589Z","end":"2026-08-13T09:13:27.412811Z","steps":["trace[238751590] 'process raft request' (duration: 156.85547ms)","trace[238751590] 'compare' (duration: 10.996282ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:28.740208Z","caller":"traceutil/trace.go:171","msg":"trace[999250339] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"298.1354ms","start":"2026-08-13T09:13:28.44202Z","end":"2026-08-13T09:13:28.740155Z","steps":["trace[999250339] 'process raft request' (duration: 229.5664ms)","trace[999250339] 'compare' (duration: 68.143222ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:28.74027Z","caller":"traceutil/trace.go:171","msg":"trace[748965156] linearizableReadLoop","detail":"{readStateIndex:27509; appliedIndex:27506; }","duration":"231.795233ms","start":"2026-08-13T09:13:28.508446Z","end":"2026-08-13T09:13:28.740241Z","steps":["trace[748965156] 'read index received' (duration: 163.191192ms)","trace[748965156] 'applied index is now lower than readState.Index' (duration: 68.602991ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:28.740279Z","caller":"traceutil/trace.go:171","msg":"trace[2113865815] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"294.44895ms","start":"2026-08-13T09:13:28.445791Z","end":"2026-08-13T09:13:28.74024Z","steps":["trace[2113865815] 'process raft request' (duration: 294.270367ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:28.740397Z","caller":"traceutil/trace.go:171","msg":"trace[1261367960] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"254.199705ms","start":"2026-08-13T09:13:28.486186Z","end":"2026-08-13T09:13:28.740385Z","steps":["trace[1261367960] 'process raft request' (duration: 253.97362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:28.74066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.204661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T09:13:28.740714Z","caller":"traceutil/trace.go:171","msg":"trace[1765034671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26635; }","duration":"232.312104ms","start":"2026-08-13T09:13:28.50839Z","end":"2026-08-13T09:13:28.740702Z","steps":["trace[1765034671] 'agreement among raft nodes before linearized reading' (duration: 232.099359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:28.741034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.750854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-13T09:13:28.74103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.453059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-13T09:13:28.741124Z","caller":"traceutil/trace.go:171","msg":"trace[804332528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26635; }","duration":"134.853987ms","start":"2026-08-13T09:13:28.60624Z","end":"2026-08-13T09:13:28.741094Z","steps":["trace[804332528] 'agreement among raft nodes before linearized reading' (duration: 134.633733ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:28.74113Z","caller":"traceutil/trace.go:171","msg":"trace[140009512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26635; }","duration":"135.636912ms","start":"2026-08-13T09:13:28.605476Z","end":"2026-08-13T09:13:28.741113Z","steps":["trace[140009512] 'agreement among raft nodes before linearized reading' (duration: 134.990999ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:31.949014Z","caller":"traceutil/trace.go:171","msg":"trace[1575328620] linearizableReadLoop","detail":"{readStateIndex:27548; appliedIndex:27547; }","duration":"174.930498ms","start":"2026-08-13T09:13:31.774062Z","end":"2026-08-13T09:13:31.948992Z","steps":["trace[1575328620] 'read index received' (duration: 174.733355ms)","trace[1575328620] 'applied index is now lower than readState.Index' (duration: 196.153µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:31.949274Z","caller":"traceutil/trace.go:171","msg":"trace[1875918543] transaction","detail":"{read_only:false; response_revision:26673; number_of_response:1; }","duration":"193.483306ms","start":"2026-08-13T09:13:31.75578Z","end":"2026-08-13T09:13:31.949263Z","steps":["trace[1875918543] 'process raft request' (duration: 193.075668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:31.949476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.407628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:31.949519Z","caller":"traceutil/trace.go:171","msg":"trace[744652934] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26673; }","duration":"175.501269ms","start":"2026-08-13T09:13:31.774008Z","end":"2026-08-13T09:13:31.94951Z","steps":["trace[744652934] 'agreement among raft nodes before linearized reading' (duration: 175.422238ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:40.48233Z","caller":"traceutil/trace.go:171","msg":"trace[1503889432] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"117.577664ms","start":"2026-08-13T09:13:40.364717Z","end":"2026-08-13T09:13:40.482295Z","steps":["trace[1503889432] 'process raft request' (duration: 117.34341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:40.855389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.910889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:40.855758Z","caller":"traceutil/trace.go:171","msg":"trace[1322750670] linearizableReadLoop","detail":"{readStateIndex:27619; appliedIndex:27618; }","duration":"248.307541ms","start":"2026-08-13T09:13:40.607433Z","end":"2026-08-13T09:13:40.855741Z","steps":["trace[1322750670] 'read index received' (duration: 75.472µs)","trace[1322750670] 'applied index is now lower than readState.Index' (duration: 248.231119ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:40.85572Z","caller":"traceutil/trace.go:171","msg":"trace[82764798] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"486.66272ms","start":"2026-08-13T09:13:40.369017Z","end":"2026-08-13T09:13:40.85568Z","steps":["trace[82764798] 'process raft request' (duration: 232.355033ms)","trace[82764798] 'compare' (duration: 253.785757ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:13:40.855935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:13:40.368981Z","time spent":"486.836244ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:13:40.856006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.578416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-13T09:13:40.856034Z","caller":"traceutil/trace.go:171","msg":"trace[124694721] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:26744; }","duration":"248.645627ms","start":"2026-08-13T09:13:40.60738Z","end":"2026-08-13T09:13:40.856026Z","steps":["trace[124694721] 'agreement among raft nodes before linearized reading' (duration: 248.527785ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:41.061739Z","caller":"traceutil/trace.go:171","msg":"trace[1134245703] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27620; }","duration":"184.074925ms","start":"2026-08-13T09:13:40.877642Z","end":"2026-08-13T09:13:41.061717Z","steps":["trace[1134245703] 'read index received' (duration: 112.068198ms)","trace[1134245703] 'applied index is now lower than readState.Index' (duration: 72.005727ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:41.061844Z","caller":"traceutil/trace.go:171","msg":"trace[351433081] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"199.384519ms","start":"2026-08-13T09:13:40.862449Z","end":"2026-08-13T09:13:41.061833Z","steps":["trace[351433081] 'process raft request' (duration: 199.211375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:41.061972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.325619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:41.062014Z","caller":"traceutil/trace.go:171","msg":"trace[1375689321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26747; }","duration":"184.424971ms","start":"2026-08-13T09:13:40.877582Z","end":"2026-08-13T09:13:41.062007Z","steps":["trace[1375689321] 'agreement among raft nodes before linearized reading' (duration: 184.3504ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:41.062146Z","caller":"traceutil/trace.go:171","msg":"trace[770555831] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"199.654645ms","start":"2026-08-13T09:13:40.862483Z","end":"2026-08-13T09:13:41.062138Z","steps":["trace[770555831] 'process raft request' (duration: 199.204246ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:41.061744Z","caller":"traceutil/trace.go:171","msg":"trace[2078853945] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"199.309338ms","start":"2026-08-13T09:13:40.862414Z","end":"2026-08-13T09:13:41.061723Z","steps":["trace[2078853945] 'process raft request' (duration: 199.090143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:41.064151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.706662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:13:41.064223Z","caller":"traceutil/trace.go:171","msg":"trace[1052717670] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26749; }","duration":"164.888246ms","start":"2026-08-13T09:13:40.899318Z","end":"2026-08-13T09:13:41.064207Z","steps":["trace[1052717670] 'agreement among raft nodes before linearized reading' (duration: 164.695672ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:47.014577Z","caller":"traceutil/trace.go:171","msg":"trace[1729213353] transaction","detail":"{read_only:false; response_revision:26791; number_of_response:1; }","duration":"133.001121ms","start":"2026-08-13T09:13:46.881544Z","end":"2026-08-13T09:13:47.014546Z","steps":["trace[1729213353] 'process raft request' (duration: 132.634504ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:47.427127Z","caller":"traceutil/trace.go:171","msg":"trace[833107058] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"152.132878ms","start":"2026-08-13T09:13:47.274953Z","end":"2026-08-13T09:13:47.427086Z","steps":["trace[833107058] 'process raft request' (duration: 140.416373ms)","trace[833107058] 'compare' (duration: 11.42236ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:47.568028Z","caller":"traceutil/trace.go:171","msg":"trace[145018633] linearizableReadLoop","detail":"{readStateIndex:27675; appliedIndex:27674; }","duration":"118.533372ms","start":"2026-08-13T09:13:47.449452Z","end":"2026-08-13T09:13:47.567985Z","steps":["trace[145018633] 'read index received' (duration: 117.8714ms)","trace[145018633] 'applied index is now lower than readState.Index' (duration: 660.372µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:47.568184Z","caller":"traceutil/trace.go:171","msg":"trace[1974564874] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"120.204055ms","start":"2026-08-13T09:13:47.447868Z","end":"2026-08-13T09:13:47.568072Z","steps":["trace[1974564874] 'process raft request' (duration: 119.39601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:47.56828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.803857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-13T09:13:47.568327Z","caller":"traceutil/trace.go:171","msg":"trace[163941088] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26797; }","duration":"118.872739ms","start":"2026-08-13T09:13:47.44944Z","end":"2026-08-13T09:13:47.568313Z","steps":["trace[163941088] 'agreement among raft nodes before linearized reading' (duration: 118.691555ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:47.820479Z","caller":"traceutil/trace.go:171","msg":"trace[1793311924] transaction","detail":"{read_only:false; response_revision:26801; number_of_response:1; }","duration":"242.510799ms","start":"2026-08-13T09:13:47.577941Z","end":"2026-08-13T09:13:47.820451Z","steps":["trace[1793311924] 'process raft request' (duration: 242.137862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:54.854486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.873394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:13:54.854616Z","caller":"traceutil/trace.go:171","msg":"trace[875153072] transaction","detail":"{read_only:false; response_revision:26862; number_of_response:1; }","duration":"238.296358ms","start":"2026-08-13T09:13:54.616298Z","end":"2026-08-13T09:13:54.854595Z","steps":["trace[875153072] 'process raft request' (duration: 100.22405ms)","trace[875153072] 'compare' (duration: 137.65738ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:55.401563Z","caller":"traceutil/trace.go:171","msg":"trace[1099775364] linearizableReadLoop","detail":"{readStateIndex:27745; appliedIndex:27743; }","duration":"177.05496ms","start":"2026-08-13T09:13:55.224476Z","end":"2026-08-13T09:13:55.401531Z","steps":["trace[1099775364] 'read index received' (duration: 81.360306ms)","trace[1099775364] 'applied index is now lower than readState.Index' (duration: 95.693414ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:55.401693Z","caller":"traceutil/trace.go:171","msg":"trace[1789114097] transaction","detail":"{read_only:false; response_revision:26865; number_of_response:1; }","duration":"207.008306ms","start":"2026-08-13T09:13:55.194635Z","end":"2026-08-13T09:13:55.401643Z","steps":["trace[1789114097] 'process raft request' (duration: 111.185ms)","trace[1789114097] 'compare' (duration: 95.417748ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:13:55.401736Z","caller":"traceutil/trace.go:171","msg":"trace[1993398737] transaction","detail":"{read_only:false; response_revision:26866; number_of_response:1; }","duration":"190.400066ms","start":"2026-08-13T09:13:55.211321Z","end":"2026-08-13T09:13:55.401721Z","steps":["trace[1993398737] 'process raft request' (duration: 190.139531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:55.402059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.132713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-13T09:13:55.402075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.720587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T09:13:55.40214Z","caller":"traceutil/trace.go:171","msg":"trace[1274997677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26866; }","duration":"107.244735ms","start":"2026-08-13T09:13:55.294876Z","end":"2026-08-13T09:13:55.40212Z","steps":["trace[1274997677] 'agreement among raft nodes before linearized reading' (duration: 107.01568ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:55.402156Z","caller":"traceutil/trace.go:171","msg":"trace[42803248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26866; }","duration":"103.86254ms","start":"2026-08-13T09:13:55.298273Z","end":"2026-08-13T09:13:55.402136Z","steps":["trace[42803248] 'agreement among raft nodes before linearized reading' (duration: 103.649236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:13:55.402215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.716433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-13T09:13:55.402309Z","caller":"traceutil/trace.go:171","msg":"trace[1610773316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26866; }","duration":"177.854445ms","start":"2026-08-13T09:13:55.224434Z","end":"2026-08-13T09:13:55.402289Z","steps":["trace[1610773316] 'agreement among raft nodes before linearized reading' (duration: 177.56643ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:55.604278Z","caller":"traceutil/trace.go:171","msg":"trace[1878631307] transaction","detail":"{read_only:false; response_revision:26868; number_of_response:1; }","duration":"195.863952ms","start":"2026-08-13T09:13:55.408388Z","end":"2026-08-13T09:13:55.604252Z","steps":["trace[1878631307] 'process raft request' (duration: 195.619407ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:55.604496Z","caller":"traceutil/trace.go:171","msg":"trace[226990167] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"195.889802ms","start":"2026-08-13T09:13:55.40859Z","end":"2026-08-13T09:13:55.60448Z","steps":["trace[226990167] 'process raft request' (duration: 195.619117ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:13:55.604645Z","caller":"traceutil/trace.go:171","msg":"trace[30364692] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"155.050015ms","start":"2026-08-13T09:13:55.449574Z","end":"2026-08-13T09:13:55.604624Z","steps":["trace[30364692] 'process raft request' (duration: 154.834671ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:07.705657Z","caller":"traceutil/trace.go:171","msg":"trace[177147512] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"175.01476ms","start":"2026-08-13T09:14:07.530618Z","end":"2026-08-13T09:14:07.705633Z","steps":["trace[177147512] 'process raft request' (duration: 130.33797ms)","trace[177147512] 'compare' (duration: 44.540187ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:07.828874Z","caller":"traceutil/trace.go:171","msg":"trace[1553550989] transaction","detail":"{read_only:false; response_revision:26954; number_of_response:1; }","duration":"233.66683ms","start":"2026-08-13T09:14:07.595181Z","end":"2026-08-13T09:14:07.828848Z","steps":["trace[1553550989] 'process raft request' (duration: 222.873432ms)","trace[1553550989] 'compare' (duration: 10.252708ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:07.828891Z","caller":"traceutil/trace.go:171","msg":"trace[857701796] linearizableReadLoop","detail":"{readStateIndex:27837; appliedIndex:27834; }","duration":"169.679628ms","start":"2026-08-13T09:14:07.659185Z","end":"2026-08-13T09:14:07.828864Z","steps":["trace[857701796] 'read index received' (duration: 1.732554ms)","trace[857701796] 'applied index is now lower than readState.Index' (duration: 167.945764ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:07.829138Z","caller":"traceutil/trace.go:171","msg":"trace[799813141] transaction","detail":"{read_only:false; response_revision:26955; number_of_response:1; }","duration":"176.558169ms","start":"2026-08-13T09:14:07.652551Z","end":"2026-08-13T09:14:07.829109Z","steps":["trace[799813141] 'process raft request' (duration: 176.179052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:07.829392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.874861ms","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-13T09:14:07.829855Z","caller":"traceutil/trace.go:171","msg":"trace[501539847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26955; }","duration":"170.654386ms","start":"2026-08-13T09:14:07.659149Z","end":"2026-08-13T09:14:07.829803Z","steps":["trace[501539847] 'agreement among raft nodes before linearized reading' (duration: 169.81315ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:07.856551Z","caller":"traceutil/trace.go:171","msg":"trace[2070465306] transaction","detail":"{read_only:false; response_revision:26956; number_of_response:1; }","duration":"149.241215ms","start":"2026-08-13T09:14:07.70728Z","end":"2026-08-13T09:14:07.856521Z","steps":["trace[2070465306] 'process raft request' (duration: 149.068181ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:07.856747Z","caller":"traceutil/trace.go:171","msg":"trace[2114115912] transaction","detail":"{read_only:false; response_revision:26957; number_of_response:1; }","duration":"145.790907ms","start":"2026-08-13T09:14:07.710922Z","end":"2026-08-13T09:14:07.856713Z","steps":["trace[2114115912] 'process raft request' (duration: 145.560653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:07.856929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.280574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-13T09:14:07.856954Z","caller":"traceutil/trace.go:171","msg":"trace[1314551525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26958; }","duration":"109.358176ms","start":"2026-08-13T09:14:07.747589Z","end":"2026-08-13T09:14:07.856948Z","steps":["trace[1314551525] 'agreement among raft nodes before linearized reading' (duration: 109.258944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:07.856961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.739101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T09:14:07.857012Z","caller":"traceutil/trace.go:171","msg":"trace[676059098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26958; }","duration":"193.827373ms","start":"2026-08-13T09:14:07.663171Z","end":"2026-08-13T09:14:07.856998Z","steps":["trace[676059098] 'agreement among raft nodes before linearized reading' (duration: 193.643949ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:07.99115Z","caller":"traceutil/trace.go:171","msg":"trace[1954059524] transaction","detail":"{read_only:false; response_revision:26959; number_of_response:1; }","duration":"156.536904ms","start":"2026-08-13T09:14:07.834582Z","end":"2026-08-13T09:14:07.991119Z","steps":["trace[1954059524] 'process raft request' (duration: 140.851422ms)","trace[1954059524] 'compare' (duration: 15.345676ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:14:08.424708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.153728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:14:08.424925Z","caller":"traceutil/trace.go:171","msg":"trace[1989634620] transaction","detail":"{read_only:false; response_revision:26962; number_of_response:1; }","duration":"277.193727ms","start":"2026-08-13T09:14:08.147714Z","end":"2026-08-13T09:14:08.424908Z","steps":["trace[1989634620] 'process raft request' (duration: 277.145906ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:08.425011Z","caller":"traceutil/trace.go:171","msg":"trace[265158634] transaction","detail":"{read_only:false; response_revision:26960; number_of_response:1; }","duration":"563.374528ms","start":"2026-08-13T09:14:07.861614Z","end":"2026-08-13T09:14:08.424988Z","steps":["trace[265158634] 'process raft request' (duration: 314.861102ms)","trace[265158634] 'compare' (duration: 248.037526ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:08.425044Z","caller":"traceutil/trace.go:171","msg":"trace[1886686638] transaction","detail":"{read_only:false; response_revision:26961; number_of_response:1; }","duration":"563.122924ms","start":"2026-08-13T09:14:07.861914Z","end":"2026-08-13T09:14:08.425037Z","steps":["trace[1886686638] 'process raft request' (duration: 562.879609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:08.425083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:07.861901Z","time spent":"563.156434ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:14:08.425107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:07.861593Z","time spent":"563.462151ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:14:08.425115Z","caller":"traceutil/trace.go:171","msg":"trace[642724792] linearizableReadLoop","detail":"{readStateIndex:27843; appliedIndex:27840; }","duration":"543.622288ms","start":"2026-08-13T09:14:07.881468Z","end":"2026-08-13T09:14:08.42509Z","steps":["trace[642724792] 'read index received' (duration: 93.981609ms)","trace[642724792] 'applied index is now lower than readState.Index' (duration: 449.638029ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:14:08.425365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.189229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-13T09:14:08.42535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.820664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:08.425396Z","caller":"traceutil/trace.go:171","msg":"trace[390531277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26962; }","duration":"503.286501ms","start":"2026-08-13T09:14:07.922098Z","end":"2026-08-13T09:14:08.425384Z","steps":["trace[390531277] 'agreement among raft nodes before linearized reading' (duration: 503.126158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:08.425424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:07.922075Z","time spent":"503.342753ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T09:14:08.42542Z","caller":"traceutil/trace.go:171","msg":"trace[1226912161] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:26962; }","duration":"312.930407ms","start":"2026-08-13T09:14:08.112469Z","end":"2026-08-13T09:14:08.425399Z","steps":["trace[1226912161] 'agreement among raft nodes before linearized reading' (duration: 312.804724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:08.425459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:08.112452Z","time spent":"312.999668ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"warn","ts":"2026-08-13T09:14:08.425586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.108787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-13T09:14:08.425652Z","caller":"traceutil/trace.go:171","msg":"trace[1833109014] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26962; }","duration":"544.199099ms","start":"2026-08-13T09:14:07.881439Z","end":"2026-08-13T09:14:08.425638Z","steps":["trace[1833109014] 'agreement among raft nodes before linearized reading' (duration: 543.749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:08.425677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:07.881425Z","time spent":"544.24545ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-13T09:14:12.634813Z","caller":"traceutil/trace.go:171","msg":"trace[1176463178] transaction","detail":"{read_only:false; response_revision:26991; number_of_response:1; }","duration":"126.480006ms","start":"2026-08-13T09:14:12.508296Z","end":"2026-08-13T09:14:12.634776Z","steps":["trace[1176463178] 'process raft request' (duration: 113.073208ms)","trace[1176463178] 'compare' (duration: 13.246345ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:12.968799Z","caller":"traceutil/trace.go:171","msg":"trace[329449758] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"133.737097ms","start":"2026-08-13T09:14:12.835008Z","end":"2026-08-13T09:14:12.968745Z","steps":["trace[329449758] 'process raft request' (duration: 133.466131ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:13.070346Z","caller":"traceutil/trace.go:171","msg":"trace[1993407084] linearizableReadLoop","detail":"{readStateIndex:27876; appliedIndex:27875; }","duration":"101.639518ms","start":"2026-08-13T09:14:12.968661Z","end":"2026-08-13T09:14:13.070301Z","steps":["trace[1993407084] 'read index received' (duration: 31.919905ms)","trace[1993407084] 'applied index is now lower than readState.Index' (duration: 69.717943ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:13.070482Z","caller":"traceutil/trace.go:171","msg":"trace[1272834368] transaction","detail":"{read_only:false; response_revision:26993; number_of_response:1; }","duration":"156.614847ms","start":"2026-08-13T09:14:12.913852Z","end":"2026-08-13T09:14:13.070467Z","steps":["trace[1272834368] 'process raft request' (duration: 86.68034ms)","trace[1272834368] 'compare' (duration: 69.611361ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:14:13.070585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.337343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:13.070674Z","caller":"traceutil/trace.go:171","msg":"trace[1570723049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26993; }","duration":"100.477186ms","start":"2026-08-13T09:14:12.970175Z","end":"2026-08-13T09:14:13.070653Z","steps":["trace[1570723049] 'agreement among raft nodes before linearized reading' (duration: 100.334013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:13.07086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.518657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T09:14:13.070912Z","caller":"traceutil/trace.go:171","msg":"trace[1939930647] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26993; }","duration":"181.630589ms","start":"2026-08-13T09:14:12.889268Z","end":"2026-08-13T09:14:13.070899Z","steps":["trace[1939930647] 'agreement among raft nodes before linearized reading' (duration: 181.534288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:13.314149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.164915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:14:13.314504Z","caller":"traceutil/trace.go:171","msg":"trace[1695542358] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"148.342098ms","start":"2026-08-13T09:14:13.166107Z","end":"2026-08-13T09:14:13.314449Z","steps":["trace[1695542358] 'process raft request' (duration: 41.763255ms)","trace[1695542358] 'compare' (duration: 105.972281ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:18.588128Z","caller":"traceutil/trace.go:171","msg":"trace[1797800959] transaction","detail":"{read_only:false; response_revision:27033; number_of_response:1; }","duration":"126.828284ms","start":"2026-08-13T09:14:18.46126Z","end":"2026-08-13T09:14:18.588088Z","steps":["trace[1797800959] 'process raft request' (duration: 126.459906ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:18.588258Z","caller":"traceutil/trace.go:171","msg":"trace[1851840292] linearizableReadLoop","detail":"{readStateIndex:27917; appliedIndex:27916; }","duration":"118.086415ms","start":"2026-08-13T09:14:18.470123Z","end":"2026-08-13T09:14:18.588209Z","steps":["trace[1851840292] 'read index received' (duration: 117.556094ms)","trace[1851840292] 'applied index is now lower than readState.Index' (duration: 525.441µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:14:18.588515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.36517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-13T09:14:18.588605Z","caller":"traceutil/trace.go:171","msg":"trace[1089922165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27033; }","duration":"118.506163ms","start":"2026-08-13T09:14:18.470079Z","end":"2026-08-13T09:14:18.588585Z","steps":["trace[1089922165] 'agreement among raft nodes before linearized reading' (duration: 118.240218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:18.588911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.692736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T09:14:18.589089Z","caller":"traceutil/trace.go:171","msg":"trace[866180973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27033; }","duration":"118.921461ms","start":"2026-08-13T09:14:18.470132Z","end":"2026-08-13T09:14:18.589053Z","steps":["trace[866180973] 'agreement among raft nodes before linearized reading' (duration: 118.395291ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:18.792222Z","caller":"traceutil/trace.go:171","msg":"trace[1978900036] transaction","detail":"{read_only:false; response_revision:27034; number_of_response:1; }","duration":"198.766719ms","start":"2026-08-13T09:14:18.593425Z","end":"2026-08-13T09:14:18.792191Z","steps":["trace[1978900036] 'process raft request' (duration: 198.569075ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:18.805475Z","caller":"traceutil/trace.go:171","msg":"trace[194118822] linearizableReadLoop","detail":"{readStateIndex:27919; appliedIndex:27917; }","duration":"180.68372ms","start":"2026-08-13T09:14:18.624768Z","end":"2026-08-13T09:14:18.805452Z","steps":["trace[194118822] 'read index received' (duration: 167.286591ms)","trace[194118822] 'applied index is now lower than readState.Index' (duration: 13.396049ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:18.805661Z","caller":"traceutil/trace.go:171","msg":"trace[506561651] transaction","detail":"{read_only:false; response_revision:27035; number_of_response:1; }","duration":"212.077165ms","start":"2026-08-13T09:14:18.593498Z","end":"2026-08-13T09:14:18.805575Z","steps":["trace[506561651] 'process raft request' (duration: 211.82267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:18.805757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.315346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-13T09:14:18.805815Z","caller":"traceutil/trace.go:171","msg":"trace[1805021607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27035; }","duration":"149.426938ms","start":"2026-08-13T09:14:18.656376Z","end":"2026-08-13T09:14:18.805803Z","steps":["trace[1805021607] 'agreement among raft nodes before linearized reading' (duration: 149.285976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:18.805789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.012146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-13T09:14:18.80602Z","caller":"traceutil/trace.go:171","msg":"trace[2031460179] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27035; }","duration":"181.243501ms","start":"2026-08-13T09:14:18.624746Z","end":"2026-08-13T09:14:18.80599Z","steps":["trace[2031460179] 'agreement among raft nodes before linearized reading' (duration: 180.862224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:19.490606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.802182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:14:19.490811Z","caller":"traceutil/trace.go:171","msg":"trace[2078125566] transaction","detail":"{read_only:false; response_revision:27039; number_of_response:1; }","duration":"147.325308ms","start":"2026-08-13T09:14:19.343468Z","end":"2026-08-13T09:14:19.490793Z","steps":["trace[2078125566] 'process raft request' (duration: 147.240606ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:19.490911Z","caller":"traceutil/trace.go:171","msg":"trace[435716165] transaction","detail":"{read_only:false; response_revision:27038; number_of_response:1; }","duration":"308.159006ms","start":"2026-08-13T09:14:19.182698Z","end":"2026-08-13T09:14:19.490857Z","steps":["trace[435716165] 'process raft request' (duration: 152.018738ms)","trace[435716165] 'compare' (duration: 155.085477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:14:19.491053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.555033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T09:14:19.49092Z","caller":"traceutil/trace.go:171","msg":"trace[1207102054] linearizableReadLoop","detail":"{readStateIndex:27922; appliedIndex:27921; }","duration":"241.40797ms","start":"2026-08-13T09:14:19.249469Z","end":"2026-08-13T09:14:19.490877Z","steps":["trace[1207102054] 'read index received' (duration: 85.19352ms)","trace[1207102054] 'applied index is now lower than readState.Index' (duration: 156.2113ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:19.491138Z","caller":"traceutil/trace.go:171","msg":"trace[1069674648] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:27039; }","duration":"241.705835ms","start":"2026-08-13T09:14:19.249414Z","end":"2026-08-13T09:14:19.491119Z","steps":["trace[1069674648] 'agreement among raft nodes before linearized reading' (duration: 241.553062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:19.49113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:19.18267Z","time spent":"308.348769ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:14:19.816144Z","caller":"traceutil/trace.go:171","msg":"trace[1312945147] linearizableReadLoop","detail":"{readStateIndex:27924; appliedIndex:27923; }","duration":"213.522433ms","start":"2026-08-13T09:14:19.602591Z","end":"2026-08-13T09:14:19.816113Z","steps":["trace[1312945147] 'read index received' (duration: 213.279688ms)","trace[1312945147] 'applied index is now lower than readState.Index' (duration: 241.085µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:19.816213Z","caller":"traceutil/trace.go:171","msg":"trace[1991330436] transaction","detail":"{read_only:false; response_revision:27040; number_of_response:1; }","duration":"271.836637ms","start":"2026-08-13T09:14:19.544318Z","end":"2026-08-13T09:14:19.816154Z","steps":["trace[1991330436] 'process raft request' (duration: 271.563471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:19.816391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.759337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:19.816447Z","caller":"traceutil/trace.go:171","msg":"trace[602589577] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:27040; }","duration":"213.88096ms","start":"2026-08-13T09:14:19.60255Z","end":"2026-08-13T09:14:19.816431Z","steps":["trace[602589577] 'agreement among raft nodes before linearized reading' (duration: 213.747907ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:19.943006Z","caller":"traceutil/trace.go:171","msg":"trace[1812190080] transaction","detail":"{read_only:false; response_revision:27041; number_of_response:1; }","duration":"120.790046ms","start":"2026-08-13T09:14:19.822192Z","end":"2026-08-13T09:14:19.942982Z","steps":["trace[1812190080] 'process raft request' (duration: 118.450401ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:20.267798Z","caller":"traceutil/trace.go:171","msg":"trace[1767493812] transaction","detail":"{read_only:false; response_revision:27045; number_of_response:1; }","duration":"157.247199ms","start":"2026-08-13T09:14:20.110522Z","end":"2026-08-13T09:14:20.26777Z","steps":["trace[1767493812] 'process raft request' (duration: 157.046395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:20.593121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.09477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:20.593265Z","caller":"traceutil/trace.go:171","msg":"trace[1482111836] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:27045; }","duration":"233.285613ms","start":"2026-08-13T09:14:20.359954Z","end":"2026-08-13T09:14:20.59324Z","steps":["trace[1482111836] 'count revisions from in-memory index tree' (duration: 233.015388ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:20.607678Z","caller":"traceutil/trace.go:171","msg":"trace[362943729] transaction","detail":"{read_only:false; response_revision:27046; number_of_response:1; }","duration":"124.649781ms","start":"2026-08-13T09:14:20.483007Z","end":"2026-08-13T09:14:20.607657Z","steps":["trace[362943729] 'process raft request' (duration: 124.531369ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:20.788348Z","caller":"traceutil/trace.go:171","msg":"trace[267423362] transaction","detail":"{read_only:false; response_revision:27047; number_of_response:1; }","duration":"194.069548ms","start":"2026-08-13T09:14:20.594263Z","end":"2026-08-13T09:14:20.788332Z","steps":["trace[267423362] 'process raft request' (duration: 119.42784ms)","trace[267423362] 'compare' (duration: 73.285271ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:20.787214Z","caller":"traceutil/trace.go:171","msg":"trace[532926529] linearizableReadLoop","detail":"{readStateIndex:27931; appliedIndex:27930; }","duration":"178.998208ms","start":"2026-08-13T09:14:20.608177Z","end":"2026-08-13T09:14:20.787175Z","steps":["trace[532926529] 'read index received' (duration: 105.452671ms)","trace[532926529] 'applied index is now lower than readState.Index' (duration: 73.544157ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:14:20.78914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.611593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:20.789183Z","caller":"traceutil/trace.go:171","msg":"trace[1415144461] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:27047; }","duration":"215.721476ms","start":"2026-08-13T09:14:20.573446Z","end":"2026-08-13T09:14:20.789168Z","steps":["trace[1415144461] 'agreement among raft nodes before linearized reading' (duration: 215.608494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:20.789557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.367634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-13T09:14:20.789587Z","caller":"traceutil/trace.go:171","msg":"trace[621130248] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:27047; }","duration":"180.419316ms","start":"2026-08-13T09:14:20.609161Z","end":"2026-08-13T09:14:20.78958Z","steps":["trace[621130248] 'agreement among raft nodes before linearized reading' (duration: 180.294633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:41.282099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.006526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:41.282251Z","caller":"traceutil/trace.go:171","msg":"trace[1650265054] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27354; }","duration":"182.22082ms","start":"2026-08-13T09:14:41.100001Z","end":"2026-08-13T09:14:41.282222Z","steps":["trace[1650265054] 'range keys from in-memory index tree' (duration: 181.845163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:41.282577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.70735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:41.282692Z","caller":"traceutil/trace.go:171","msg":"trace[240235910] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27354; }","duration":"182.865672ms","start":"2026-08-13T09:14:41.099788Z","end":"2026-08-13T09:14:41.282653Z","steps":["trace[240235910] 'range keys from in-memory index tree' (duration: 182.597397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:41.282758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.330502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:41.282839Z","caller":"traceutil/trace.go:171","msg":"trace[1038974172] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27354; }","duration":"183.507085ms","start":"2026-08-13T09:14:41.099313Z","end":"2026-08-13T09:14:41.28282Z","steps":["trace[1038974172] 'range keys from in-memory index tree' (duration: 183.252871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:41.282824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.983935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:41.282933Z","caller":"traceutil/trace.go:171","msg":"trace[1578601942] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27354; }","duration":"183.152559ms","start":"2026-08-13T09:14:41.099763Z","end":"2026-08-13T09:14:41.282916Z","steps":["trace[1578601942] 'range keys from in-memory index tree' (duration: 182.865573ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:41.476609Z","caller":"traceutil/trace.go:171","msg":"trace[1266936293] linearizableReadLoop","detail":"{readStateIndex:28246; appliedIndex:28245; }","duration":"172.291138ms","start":"2026-08-13T09:14:41.304288Z","end":"2026-08-13T09:14:41.476579Z","steps":["trace[1266936293] 'read index received' (duration: 172.123405ms)","trace[1266936293] 'applied index is now lower than readState.Index' (duration: 165.913µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:41.476773Z","caller":"traceutil/trace.go:171","msg":"trace[2044362255] transaction","detail":"{read_only:false; response_revision:27355; number_of_response:1; }","duration":"221.429735ms","start":"2026-08-13T09:14:41.25528Z","end":"2026-08-13T09:14:41.47671Z","steps":["trace[2044362255] 'process raft request' (duration: 221.092459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:41.476947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.562663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-13T09:14:41.477054Z","caller":"traceutil/trace.go:171","msg":"trace[1158068088] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:27355; }","duration":"172.754477ms","start":"2026-08-13T09:14:41.304276Z","end":"2026-08-13T09:14:41.477031Z","steps":["trace[1158068088] 'agreement among raft nodes before linearized reading' (duration: 172.424191ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:41.67337Z","caller":"traceutil/trace.go:171","msg":"trace[511715522] transaction","detail":"{read_only:false; response_revision:27356; number_of_response:1; }","duration":"365.013261ms","start":"2026-08-13T09:14:41.308319Z","end":"2026-08-13T09:14:41.673332Z","steps":["trace[511715522] 'process raft request' (duration: 364.811367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:41.673587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:41.308298Z","time spent":"365.212045ms","remote":"127.0.0.1:48902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18370,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:14:41.92777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.435117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T09:14:41.92797Z","caller":"traceutil/trace.go:171","msg":"trace[241811308] transaction","detail":"{read_only:false; response_revision:27358; number_of_response:1; }","duration":"346.941633ms","start":"2026-08-13T09:14:41.581006Z","end":"2026-08-13T09:14:41.927947Z","steps":["trace[241811308] 'process raft request' (duration: 346.863212ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:41.928Z","caller":"traceutil/trace.go:171","msg":"trace[1305571407] transaction","detail":"{read_only:false; response_revision:27357; number_of_response:1; }","duration":"347.105337ms","start":"2026-08-13T09:14:41.580862Z","end":"2026-08-13T09:14:41.927968Z","steps":["trace[1305571407] 'process raft request' (duration: 222.384464ms)","trace[1305571407] 'compare' (duration: 124.187463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T09:14:41.92807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:41.580978Z","time spent":"347.056646ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T09:14:41.928148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T09:14:41.580826Z","time spent":"347.239899ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T09:14:42.142972Z","caller":"traceutil/trace.go:171","msg":"trace[1204679590] transaction","detail":"{read_only:false; response_revision:27359; number_of_response:1; }","duration":"194.020957ms","start":"2026-08-13T09:14:41.948914Z","end":"2026-08-13T09:14:42.142935Z","steps":["trace[1204679590] 'process raft request' (duration: 190.826646ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:42.145327Z","caller":"traceutil/trace.go:171","msg":"trace[555957789] linearizableReadLoop","detail":"{readStateIndex:28253; appliedIndex:28249; }","duration":"105.684676ms","start":"2026-08-13T09:14:42.03962Z","end":"2026-08-13T09:14:42.145305Z","steps":["trace[555957789] 'read index received' (duration: 100.122889ms)","trace[555957789] 'applied index is now lower than readState.Index' (duration: 5.561047ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T09:14:42.145391Z","caller":"traceutil/trace.go:171","msg":"trace[1840258310] transaction","detail":"{read_only:false; response_revision:27360; number_of_response:1; }","duration":"117.85707ms","start":"2026-08-13T09:14:42.027514Z","end":"2026-08-13T09:14:42.145371Z","steps":["trace[1840258310] 'process raft request' (duration: 117.610625ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:42.145382Z","caller":"traceutil/trace.go:171","msg":"trace[1471652544] transaction","detail":"{read_only:false; response_revision:27361; number_of_response:1; }","duration":"109.57496ms","start":"2026-08-13T09:14:42.035766Z","end":"2026-08-13T09:14:42.145341Z","steps":["trace[1471652544] 'process raft request' (duration: 109.469268ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:14:42.145542Z","caller":"traceutil/trace.go:171","msg":"trace[195618071] transaction","detail":"{read_only:false; response_revision:27362; number_of_response:1; }","duration":"108.99985ms","start":"2026-08-13T09:14:42.036528Z","end":"2026-08-13T09:14:42.145528Z","steps":["trace[195618071] 'process raft request' (duration: 108.741295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T09:14:42.145801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.154155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T09:14:42.145921Z","caller":"traceutil/trace.go:171","msg":"trace[2099435860] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:27362; }","duration":"106.336768ms","start":"2026-08-13T09:14:42.039559Z","end":"2026-08-13T09:14:42.145896Z","steps":["trace[2099435860] 'agreement among raft nodes before linearized reading' (duration: 106.137844ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:15:26.697138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24921} {"level":"info","ts":"2026-08-13T09:15:26.852502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24921,"took":"148.557732ms","hash":2443556180,"current-db-size-bytes":61956096,"current-db-size":"62 MB","current-db-size-in-use-bytes":36487168,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-13T09:15:26.852586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2443556180,"revision":24921,"compact-revision":21715} {"level":"info","ts":"2026-08-13T09:17:39.910416Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca256638ea38eb43","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T09:17:39.915982Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-13T09:17:39.916146Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-13T09:20:26.705064Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27765} {"level":"info","ts":"2026-08-13T09:20:26.847315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27765,"took":"135.184365ms","hash":4115665,"current-db-size-bytes":61956096,"current-db-size":"62 MB","current-db-size-in-use-bytes":34881536,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-13T09:20:26.847474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4115665,"revision":27765,"compact-revision":24921} {"level":"info","ts":"2026-08-13T09:25:26.712819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30458} {"level":"info","ts":"2026-08-13T09:25:26.863311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30458,"took":"143.78171ms","hash":1375544246,"current-db-size-bytes":61956096,"current-db-size":"62 MB","current-db-size-in-use-bytes":30978048,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T09:25:26.863407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1375544246,"revision":30458,"compact-revision":27765} {"level":"info","ts":"2026-08-13T09:30:26.722549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32712} {"level":"info","ts":"2026-08-13T09:30:26.871173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32712,"took":"142.199561ms","hash":3075849987,"current-db-size-bytes":61956096,"current-db-size":"62 MB","current-db-size-in-use-bytes":30674944,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T09:30:26.871226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3075849987,"revision":32712,"compact-revision":30458} {"level":"info","ts":"2026-08-13T09:34:25.049275Z","caller":"traceutil/trace.go:171","msg":"trace[217997505] transaction","detail":"{read_only:false; response_revision:36751; number_of_response:1; }","duration":"102.804682ms","start":"2026-08-13T09:34:24.946452Z","end":"2026-08-13T09:34:25.049256Z","steps":["trace[217997505] 'process raft request' (duration: 102.68034ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T09:35:26.730041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34947} {"level":"info","ts":"2026-08-13T09:35:26.87542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34947,"took":"141.60378ms","hash":3445347507,"current-db-size-bytes":61956096,"current-db-size":"62 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T09:35:26.87552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3445347507,"revision":34947,"compact-revision":32712} {"level":"info","ts":"2026-08-13T09:38:52.203239Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca256638ea38eb43","local-member-applied-index":40005,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T09:38:52.20799Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-08-13T09:38:52.20809Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-08-13T09:40:26.738051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37238} {"level":"info","ts":"2026-08-13T09:40:26.857843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37238,"took":"114.867197ms","hash":1487014479,"current-db-size-bytes":62803968,"current-db-size":"63 MB","current-db-size-in-use-bytes":32755712,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-13T09:40:26.85791Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1487014479,"revision":37238,"compact-revision":34947} {"level":"info","ts":"2026-08-13T09:45:26.745306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39523} {"level":"info","ts":"2026-08-13T09:45:26.871457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39523,"took":"121.008515ms","hash":4076806486,"current-db-size-bytes":62803968,"current-db-size":"63 MB","current-db-size-in-use-bytes":31105024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T09:45:26.871622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4076806486,"revision":39523,"compact-revision":37238}