{"level":"warn","ts":"2026-07-29T14:06:23.892514Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T14:06:23.892616Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-07-29T14:06:23.892688Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T14:06:23.892703Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-29T14:06:23.89272Z","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-07-29T14:06:23.893336Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-07-29T14:06:23.893408Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-07-29T14:06:23.897352Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.72948ms"} {"level":"info","ts":"2026-07-29T14:06:23.903215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-07-29T14:06:23.903386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-29T14:06:23.903441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-07-29T14:06:23.903459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-29T14:06:23.903474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-07-29T14:06:23.903539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-07-29T14:06:23.914972Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-29T14:06:23.916299Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-29T14:06:23.917333Z","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-07-29T14:06:23.91921Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-29T14:06:23.920034Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-29T14:06:23.920137Z","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-07-29T14:06:23.920174Z","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-07-29T14:06:23.920182Z","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-07-29T14:06:23.920468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-07-29T14:06:23.920566Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-07-29T14:06:23.926648Z","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-07-29T14:06:23.926797Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-29T14:06:23.926876Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-07-29T14:06:23.926973Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-29T14:06:23.927017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-29T14:06:24.003914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-29T14:06:24.003998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-29T14:06:24.004034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-07-29T14:06:24.004056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-07-29T14:06:24.004068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-29T14:06:24.004086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-07-29T14:06:24.004106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-07-29T14:06:24.00507Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T14:06:24.005976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T14:06:24.005975Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-29T14:06:24.006049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T14:06:24.006343Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-29T14:06:24.006388Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-29T14:06:24.006608Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T14:06:24.006726Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T14:06:24.006761Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T14:06:24.009325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-07-29T14:06:24.011126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-29T14:06:39.591739Z","caller":"traceutil/trace.go:171","msg":"trace[1193586513] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"113.49453ms","start":"2026-07-29T14:06:39.478176Z","end":"2026-07-29T14:06:39.59167Z","steps":["trace[1193586513] 'process raft request' (duration: 113.215795ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:06:39.869676Z","caller":"traceutil/trace.go:171","msg":"trace[2098611770] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"274.092204ms","start":"2026-07-29T14:06:39.595548Z","end":"2026-07-29T14:06:39.86964Z","steps":["trace[2098611770] 'process raft request' (duration: 267.703815ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:06:39.870351Z","caller":"traceutil/trace.go:171","msg":"trace[160176110] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"122.572071ms","start":"2026-07-29T14:06:39.74776Z","end":"2026-07-29T14:06:39.870332Z","steps":["trace[160176110] 'process raft request' (duration: 121.806026ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.721926Z","caller":"traceutil/trace.go:171","msg":"trace[36415043] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"209.213697ms","start":"2026-07-29T14:07:00.512692Z","end":"2026-07-29T14:07:00.721906Z","steps":["trace[36415043] 'process raft request' (duration: 209.111665ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.767671Z","caller":"traceutil/trace.go:171","msg":"trace[1253038873] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"246.547617ms","start":"2026-07-29T14:07:00.52109Z","end":"2026-07-29T14:07:00.767637Z","steps":["trace[1253038873] 'process raft request' (duration: 246.280652ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.767909Z","caller":"traceutil/trace.go:171","msg":"trace[1697654506] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"249.275959ms","start":"2026-07-29T14:07:00.518619Z","end":"2026-07-29T14:07:00.767895Z","steps":["trace[1697654506] 'process raft request' (duration: 248.642617ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768109Z","caller":"traceutil/trace.go:171","msg":"trace[1530739159] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"228.157032ms","start":"2026-07-29T14:07:00.539923Z","end":"2026-07-29T14:07:00.76808Z","steps":["trace[1530739159] 'process raft request' (duration: 227.820776ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768157Z","caller":"traceutil/trace.go:171","msg":"trace[628200751] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"220.250563ms","start":"2026-07-29T14:07:00.547889Z","end":"2026-07-29T14:07:00.76814Z","steps":["trace[628200751] 'process raft request' (duration: 219.910417ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768203Z","caller":"traceutil/trace.go:171","msg":"trace[1348713739] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"237.788482ms","start":"2026-07-29T14:07:00.530409Z","end":"2026-07-29T14:07:00.768198Z","steps":["trace[1348713739] 'process raft request' (duration: 237.144451ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768299Z","caller":"traceutil/trace.go:171","msg":"trace[1309293846] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"235.735914ms","start":"2026-07-29T14:07:00.53254Z","end":"2026-07-29T14:07:00.768276Z","steps":["trace[1309293846] 'process raft request' (duration: 235.127813ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768317Z","caller":"traceutil/trace.go:171","msg":"trace[602581973] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"237.288783ms","start":"2026-07-29T14:07:00.531014Z","end":"2026-07-29T14:07:00.768303Z","steps":["trace[602581973] 'process raft request' (duration: 236.58195ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768181Z","caller":"traceutil/trace.go:171","msg":"trace[1331038216] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"239.573396ms","start":"2026-07-29T14:07:00.5286Z","end":"2026-07-29T14:07:00.768174Z","steps":["trace[1331038216] 'process raft request' (duration: 238.847523ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768445Z","caller":"traceutil/trace.go:171","msg":"trace[566308526] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"204.973748ms","start":"2026-07-29T14:07:00.563464Z","end":"2026-07-29T14:07:00.768438Z","steps":["trace[566308526] 'process raft request' (duration: 204.403126ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:00.768121Z","caller":"traceutil/trace.go:171","msg":"trace[1865390294] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"236.016719ms","start":"2026-07-29T14:07:00.532061Z","end":"2026-07-29T14:07:00.768078Z","steps":["trace[1865390294] 'process raft request' (duration: 235.570941ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:06.734821Z","caller":"traceutil/trace.go:171","msg":"trace[871806838] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"138.934377ms","start":"2026-07-29T14:07:06.595854Z","end":"2026-07-29T14:07:06.734788Z","steps":["trace[871806838] 'process raft request' (duration: 138.829845ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:06.773054Z","caller":"traceutil/trace.go:171","msg":"trace[272573793] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"153.805507ms","start":"2026-07-29T14:07:06.619193Z","end":"2026-07-29T14:07:06.772998Z","steps":["trace[272573793] 'process raft request' (duration: 152.988832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:07:06.988353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.432549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T14:07:06.988774Z","caller":"traceutil/trace.go:171","msg":"trace[1094749890] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"154.594351ms","start":"2026-07-29T14:07:06.834129Z","end":"2026-07-29T14:07:06.988723Z","steps":["trace[1094749890] 'process raft request' (duration: 23.373998ms)","trace[1094749890] 'compare' (duration: 130.265766ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:07:07.575214Z","caller":"traceutil/trace.go:171","msg":"trace[705505049] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"180.07174ms","start":"2026-07-29T14:07:07.395096Z","end":"2026-07-29T14:07:07.575168Z","steps":["trace[705505049] 'process raft request' (duration: 179.437328ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:07:23.579867Z","caller":"traceutil/trace.go:171","msg":"trace[546038044] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"146.438988ms","start":"2026-07-29T14:07:23.433408Z","end":"2026-07-29T14:07:23.579847Z","steps":["trace[546038044] 'process raft request' (duration: 146.331226ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:08:04.006238Z","caller":"traceutil/trace.go:171","msg":"trace[1308445573] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"218.504672ms","start":"2026-07-29T14:08:03.787701Z","end":"2026-07-29T14:08:04.006206Z","steps":["trace[1308445573] 'process raft request' (duration: 218.077504ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:08:28.022755Z","caller":"traceutil/trace.go:171","msg":"trace[1796636661] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"177.330148ms","start":"2026-07-29T14:08:27.845391Z","end":"2026-07-29T14:08:28.022721Z","steps":["trace[1796636661] 'process raft request' (duration: 177.130904ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:08:28.161734Z","caller":"traceutil/trace.go:171","msg":"trace[1832574238] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"161.70322ms","start":"2026-07-29T14:08:27.999997Z","end":"2026-07-29T14:08:28.161701Z","steps":["trace[1832574238] 'process raft request' (duration: 161.455795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:08:28.855866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.411219ms","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-07-29T14:08:28.855985Z","caller":"traceutil/trace.go:171","msg":"trace[555691952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"324.612552ms","start":"2026-07-29T14:08:28.531345Z","end":"2026-07-29T14:08:28.855958Z","steps":["trace[555691952] 'range keys from in-memory index tree' (duration: 324.139593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:08:28.856043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T14:08:28.531317Z","time spent":"324.713945ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-29T14:08:30.302226Z","caller":"traceutil/trace.go:171","msg":"trace[27000120] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"119.094462ms","start":"2026-07-29T14:08:30.183106Z","end":"2026-07-29T14:08:30.3022Z","steps":["trace[27000120] 'process raft request' (duration: 118.932509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:08:45.060752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.697632ms","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-07-29T14:08:45.060833Z","caller":"traceutil/trace.go:171","msg":"trace[765604877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1542; }","duration":"111.830524ms","start":"2026-07-29T14:08:44.948986Z","end":"2026-07-29T14:08:45.060816Z","steps":["trace[765604877] 'range keys from in-memory index tree' (duration: 111.542768ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:09:17.17664Z","caller":"traceutil/trace.go:171","msg":"trace[2134659280] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"169.454189ms","start":"2026-07-29T14:09:17.007157Z","end":"2026-07-29T14:09:17.176612Z","steps":["trace[2134659280] 'read index received' (duration: 169.283466ms)","trace[2134659280] 'applied index is now lower than readState.Index' (duration: 169.623µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:09:17.176759Z","caller":"traceutil/trace.go:171","msg":"trace[1725946774] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"189.789946ms","start":"2026-07-29T14:09:16.986929Z","end":"2026-07-29T14:09:17.176719Z","steps":["trace[1725946774] 'process raft request' (duration: 189.518941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:09:17.176855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.681073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-29T14:09:17.176903Z","caller":"traceutil/trace.go:171","msg":"trace[964568105] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"169.784835ms","start":"2026-07-29T14:09:17.007102Z","end":"2026-07-29T14:09:17.176887Z","steps":["trace[964568105] 'agreement among raft nodes before linearized reading' (duration: 169.618752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:09:17.66705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.412638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-29T14:09:17.667159Z","caller":"traceutil/trace.go:171","msg":"trace[1257603075] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:2133; }","duration":"159.54016ms","start":"2026-07-29T14:09:17.507578Z","end":"2026-07-29T14:09:17.667119Z","steps":["trace[1257603075] 'range keys from in-memory index tree' (duration: 159.246315ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:09:54.924595Z","caller":"traceutil/trace.go:171","msg":"trace[75445012] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"112.088437ms","start":"2026-07-29T14:09:54.812478Z","end":"2026-07-29T14:09:54.924566Z","steps":["trace[75445012] 'process raft request' (duration: 111.844833ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:09:55.537599Z","caller":"traceutil/trace.go:171","msg":"trace[772634065] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"162.62375ms","start":"2026-07-29T14:09:55.374949Z","end":"2026-07-29T14:09:55.537572Z","steps":["trace[772634065] 'process raft request' (duration: 162.487658ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:09:55.548272Z","caller":"traceutil/trace.go:171","msg":"trace[1279970646] transaction","detail":"{read_only:false; response_revision:2428; number_of_response:1; }","duration":"169.54802ms","start":"2026-07-29T14:09:55.3787Z","end":"2026-07-29T14:09:55.548248Z","steps":["trace[1279970646] 'process raft request' (duration: 169.161362ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:09:56.211078Z","caller":"traceutil/trace.go:171","msg":"trace[1337719464] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"132.030838ms","start":"2026-07-29T14:09:56.079019Z","end":"2026-07-29T14:09:56.211049Z","steps":["trace[1337719464] 'process raft request' (duration: 131.982537ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:09:56.211478Z","caller":"traceutil/trace.go:171","msg":"trace[1626746946] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"246.295229ms","start":"2026-07-29T14:09:55.965125Z","end":"2026-07-29T14:09:56.211421Z","steps":["trace[1626746946] 'process raft request' (duration: 148.483748ms)","trace[1626746946] 'compare' (duration: 97.24559ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:09:56.211391Z","caller":"traceutil/trace.go:171","msg":"trace[1599398907] linearizableReadLoop","detail":"{readStateIndex:2520; appliedIndex:2519; }","duration":"228.738996ms","start":"2026-07-29T14:09:55.982605Z","end":"2026-07-29T14:09:56.211344Z","steps":["trace[1599398907] 'read index received' (duration: 131.011797ms)","trace[1599398907] 'applied index is now lower than readState.Index' (duration: 97.722559ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:09:56.211579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.95501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-29T14:09:56.211632Z","caller":"traceutil/trace.go:171","msg":"trace[2021087993] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2432; }","duration":"229.051223ms","start":"2026-07-29T14:09:55.982566Z","end":"2026-07-29T14:09:56.211617Z","steps":["trace[2021087993] 'agreement among raft nodes before linearized reading' (duration: 228.869859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:09:56.461479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.965326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T14:09:56.461662Z","caller":"traceutil/trace.go:171","msg":"trace[668309511] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"147.003238ms","start":"2026-07-29T14:09:56.314614Z","end":"2026-07-29T14:09:56.461617Z","steps":["trace[668309511] 'process raft request' (duration: 18.622603ms)","trace[668309511] 'compare' (duration: 127.819343ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:11:08.677667Z","caller":"traceutil/trace.go:171","msg":"trace[517978277] transaction","detail":"{read_only:false; response_revision:2964; number_of_response:1; }","duration":"122.542615ms","start":"2026-07-29T14:11:08.555086Z","end":"2026-07-29T14:11:08.677628Z","steps":["trace[517978277] 'process raft request' (duration: 122.059125ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:11:38.994083Z","caller":"traceutil/trace.go:171","msg":"trace[620049160] transaction","detail":"{read_only:false; response_revision:3172; number_of_response:1; }","duration":"101.04739ms","start":"2026-07-29T14:11:38.893019Z","end":"2026-07-29T14:11:38.994066Z","steps":["trace[620049160] 'process raft request' (duration: 87.323356ms)","trace[620049160] 'compare' (duration: 13.555311ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:11:38.996172Z","caller":"traceutil/trace.go:171","msg":"trace[2124713213] transaction","detail":"{read_only:false; response_revision:3173; number_of_response:1; }","duration":"103.100271ms","start":"2026-07-29T14:11:38.893052Z","end":"2026-07-29T14:11:38.996153Z","steps":["trace[2124713213] 'process raft request' (duration: 102.954278ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:16:24.433632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3076} {"level":"info","ts":"2026-07-29T14:16:24.521627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3076,"took":"86.82487ms","hash":333093132,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":8499200,"current-db-size-in-use":"8.5 MB"} {"level":"info","ts":"2026-07-29T14:16:24.521696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":333093132,"revision":3076,"compact-revision":-1} {"level":"info","ts":"2026-07-29T14:21:24.440294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5000} {"level":"info","ts":"2026-07-29T14:21:24.486414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5000,"took":"44.720533ms","hash":2767586520,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":11845632,"current-db-size-in-use":"12 MB"} {"level":"info","ts":"2026-07-29T14:21:24.486525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2767586520,"revision":5000,"compact-revision":3076} {"level":"info","ts":"2026-07-29T14:25:02.922454Z","caller":"traceutil/trace.go:171","msg":"trace[1420933055] linearizableReadLoop","detail":"{readStateIndex:9312; appliedIndex:9311; }","duration":"159.550492ms","start":"2026-07-29T14:25:02.762849Z","end":"2026-07-29T14:25:02.9224Z","steps":["trace[1420933055] 'read index received' (duration: 76.535304ms)","trace[1420933055] 'applied index is now lower than readState.Index' (duration: 83.012478ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:25:02.922804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.941089ms","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-07-29T14:25:02.92289Z","caller":"traceutil/trace.go:171","msg":"trace[1438383374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9021; }","duration":"160.086872ms","start":"2026-07-29T14:25:02.762784Z","end":"2026-07-29T14:25:02.922871Z","steps":["trace[1438383374] 'agreement among raft nodes before linearized reading' (duration: 159.842937ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:26:24.446997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7282} {"level":"info","ts":"2026-07-29T14:26:24.501867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7282,"took":"53.633158ms","hash":2292463750,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":13004800,"current-db-size-in-use":"13 MB"} {"level":"info","ts":"2026-07-29T14:26:24.501936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2292463750,"revision":7282,"compact-revision":5000} {"level":"info","ts":"2026-07-29T14:26:29.379721Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T14:26:29.384339Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-29T14:26:29.384444Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-29T14:31:04.558849Z","caller":"traceutil/trace.go:171","msg":"trace[1534820026] transaction","detail":"{read_only:false; response_revision:11827; number_of_response:1; }","duration":"268.884236ms","start":"2026-07-29T14:31:04.289905Z","end":"2026-07-29T14:31:04.558789Z","steps":["trace[1534820026] 'process raft request' (duration: 245.097949ms)","trace[1534820026] 'compare' (duration: 23.375359ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:31:04.650628Z","caller":"traceutil/trace.go:171","msg":"trace[189050883] linearizableReadLoop","detail":"{readStateIndex:12205; appliedIndex:12203; }","duration":"261.769208ms","start":"2026-07-29T14:31:04.388819Z","end":"2026-07-29T14:31:04.650588Z","steps":["trace[189050883] 'read index received' (duration: 146.259519ms)","trace[189050883] 'applied index is now lower than readState.Index' (duration: 115.508309ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:31:04.650779Z","caller":"traceutil/trace.go:171","msg":"trace[209172569] transaction","detail":"{read_only:false; response_revision:11828; number_of_response:1; }","duration":"268.440108ms","start":"2026-07-29T14:31:04.382285Z","end":"2026-07-29T14:31:04.650725Z","steps":["trace[209172569] 'process raft request' (duration: 268.10898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:31:04.650949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.110777ms","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-07-29T14:31:04.651061Z","caller":"traceutil/trace.go:171","msg":"trace[333515798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11828; }","duration":"262.27755ms","start":"2026-07-29T14:31:04.388737Z","end":"2026-07-29T14:31:04.651014Z","steps":["trace[333515798] 'agreement among raft nodes before linearized reading' (duration: 262.031345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:31:04.65105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.9334ms","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:7"} {"level":"info","ts":"2026-07-29T14:31:04.651229Z","caller":"traceutil/trace.go:171","msg":"trace[748479848] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11828; }","duration":"101.231335ms","start":"2026-07-29T14:31:04.549954Z","end":"2026-07-29T14:31:04.651186Z","steps":["trace[748479848] 'agreement among raft nodes before linearized reading' (duration: 100.877138ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:31:24.458324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9658} {"level":"info","ts":"2026-07-29T14:31:24.525714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9658,"took":"65.01134ms","hash":1407216908,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":14843904,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-29T14:31:24.525798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1407216908,"revision":9658,"compact-revision":7282} {"level":"info","ts":"2026-07-29T14:32:42.4753Z","caller":"traceutil/trace.go:171","msg":"trace[1838225997] linearizableReadLoop","detail":"{readStateIndex:13420; appliedIndex:13419; }","duration":"109.266129ms","start":"2026-07-29T14:32:42.365999Z","end":"2026-07-29T14:32:42.475265Z","steps":["trace[1838225997] 'read index received' (duration: 9.350169ms)","trace[1838225997] 'applied index is now lower than readState.Index' (duration: 99.9144ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:32:42.4754Z","caller":"traceutil/trace.go:171","msg":"trace[2066337206] transaction","detail":"{read_only:false; response_revision:13007; number_of_response:1; }","duration":"123.731738ms","start":"2026-07-29T14:32:42.351605Z","end":"2026-07-29T14:32:42.475337Z","steps":["trace[2066337206] 'process raft request' (duration: 23.822297ms)","trace[2066337206] 'compare' (duration: 99.669805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:32:42.475487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.465473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T14:32:42.475586Z","caller":"traceutil/trace.go:171","msg":"trace[1007006692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13007; }","duration":"109.637557ms","start":"2026-07-29T14:32:42.365926Z","end":"2026-07-29T14:32:42.475564Z","steps":["trace[1007006692] 'agreement among raft nodes before linearized reading' (duration: 109.457704ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:32:42.944334Z","caller":"traceutil/trace.go:171","msg":"trace[779912914] linearizableReadLoop","detail":"{readStateIndex:13421; appliedIndex:13420; }","duration":"145.17876ms","start":"2026-07-29T14:32:42.799116Z","end":"2026-07-29T14:32:42.944294Z","steps":["trace[779912914] 'read index received' (duration: 69.159708ms)","trace[779912914] 'applied index is now lower than readState.Index' (duration: 76.017022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:32:42.944968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.590227ms","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-07-29T14:32:42.945192Z","caller":"traceutil/trace.go:171","msg":"trace[1188173800] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13007; }","duration":"146.111787ms","start":"2026-07-29T14:32:42.799052Z","end":"2026-07-29T14:32:42.945164Z","steps":["trace[1188173800] 'agreement among raft nodes before linearized reading' (duration: 145.409554ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:36:24.464678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12151} {"level":"info","ts":"2026-07-29T14:36:24.532732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12151,"took":"65.149234ms","hash":449270449,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":15577088,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-29T14:36:24.532807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":449270449,"revision":12151,"compact-revision":9658} {"level":"info","ts":"2026-07-29T14:41:24.471857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14409} {"level":"info","ts":"2026-07-29T14:41:24.54972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14409,"took":"75.673787ms","hash":66583322,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":16596992,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-07-29T14:41:24.549828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":66583322,"revision":14409,"compact-revision":12151} {"level":"info","ts":"2026-07-29T14:45:01.321731Z","caller":"traceutil/trace.go:171","msg":"trace[664170479] linearizableReadLoop","detail":"{readStateIndex:18959; appliedIndex:18958; }","duration":"128.236475ms","start":"2026-07-29T14:45:01.193459Z","end":"2026-07-29T14:45:01.321695Z","steps":["trace[664170479] 'read index received' (duration: 36.738566ms)","trace[664170479] 'applied index is now lower than readState.Index' (duration: 91.496329ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:01.321894Z","caller":"traceutil/trace.go:171","msg":"trace[1455525625] transaction","detail":"{read_only:false; response_revision:18382; number_of_response:1; }","duration":"130.650062ms","start":"2026-07-29T14:45:01.191231Z","end":"2026-07-29T14:45:01.321881Z","steps":["trace[1455525625] 'process raft request' (duration: 39ms)","trace[1455525625] 'compare' (duration: 91.289035ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:01.322101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.359626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-2rfrj\" ","response":"range_response_count:1 size:18591"} {"level":"info","ts":"2026-07-29T14:45:01.322198Z","caller":"traceutil/trace.go:171","msg":"trace[14926274] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-2rfrj; range_end:; response_count:1; response_revision:18382; }","duration":"104.505268ms","start":"2026-07-29T14:45:01.217674Z","end":"2026-07-29T14:45:01.32218Z","steps":["trace[14926274] 'agreement among raft nodes before linearized reading' (duration: 104.262174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:01.322294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.805586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-07-29T14:45:01.3224Z","caller":"traceutil/trace.go:171","msg":"trace[653302372] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:18382; }","duration":"128.991459ms","start":"2026-07-29T14:45:01.193383Z","end":"2026-07-29T14:45:01.322375Z","steps":["trace[653302372] 'agreement among raft nodes before linearized reading' (duration: 128.668813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:01.594383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.527127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T14:45:01.59462Z","caller":"traceutil/trace.go:171","msg":"trace[263012111] transaction","detail":"{read_only:false; response_revision:18383; number_of_response:1; }","duration":"266.462772ms","start":"2026-07-29T14:45:01.328085Z","end":"2026-07-29T14:45:01.594548Z","steps":["trace[263012111] 'process raft request' (duration: 109.655448ms)","trace[263012111] 'compare' (duration: 156.381975ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:01.594661Z","caller":"traceutil/trace.go:171","msg":"trace[1515016889] linearizableReadLoop","detail":"{readStateIndex:18960; appliedIndex:18959; }","duration":"266.402441ms","start":"2026-07-29T14:45:01.328224Z","end":"2026-07-29T14:45:01.594626Z","steps":["trace[1515016889] 'read index received' (duration: 109.477415ms)","trace[1515016889] 'applied index is now lower than readState.Index' (duration: 156.921386ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:01.595406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.101791ms","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-07-29T14:45:01.595454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.399648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-29T14:45:01.595511Z","caller":"traceutil/trace.go:171","msg":"trace[1770027800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18384; }","duration":"131.253093ms","start":"2026-07-29T14:45:01.464234Z","end":"2026-07-29T14:45:01.595488Z","steps":["trace[1770027800] 'agreement among raft nodes before linearized reading' (duration: 130.671602ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:01.595542Z","caller":"traceutil/trace.go:171","msg":"trace[983017731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18384; }","duration":"142.558791ms","start":"2026-07-29T14:45:01.45296Z","end":"2026-07-29T14:45:01.595518Z","steps":["trace[983017731] 'agreement among raft nodes before linearized reading' (duration: 142.264265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:01.595902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.647714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:636426"} {"level":"info","ts":"2026-07-29T14:45:01.596028Z","caller":"traceutil/trace.go:171","msg":"trace[797861287] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:18384; }","duration":"267.834118ms","start":"2026-07-29T14:45:01.328168Z","end":"2026-07-29T14:45:01.596002Z","steps":["trace[797861287] 'agreement among raft nodes before linearized reading' (duration: 266.614475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:01.597729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.905338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:636426"} {"level":"info","ts":"2026-07-29T14:45:01.597854Z","caller":"traceutil/trace.go:171","msg":"trace[1935690543] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:18384; }","duration":"269.067281ms","start":"2026-07-29T14:45:01.328752Z","end":"2026-07-29T14:45:01.59782Z","steps":["trace[1935690543] 'agreement among raft nodes before linearized reading' (duration: 266.075564ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:01.772375Z","caller":"traceutil/trace.go:171","msg":"trace[388034661] transaction","detail":"{read_only:false; response_revision:18386; number_of_response:1; }","duration":"170.746901ms","start":"2026-07-29T14:45:01.601589Z","end":"2026-07-29T14:45:01.772336Z","steps":["trace[388034661] 'process raft request' (duration: 110.514913ms)","trace[388034661] 'compare' (duration: 60.016793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:02.023533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.076189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-29T14:45:02.023652Z","caller":"traceutil/trace.go:171","msg":"trace[158454713] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18386; }","duration":"145.227912ms","start":"2026-07-29T14:45:01.878396Z","end":"2026-07-29T14:45:02.023623Z","steps":["trace[158454713] 'range keys from in-memory index tree' (duration: 144.914496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:02.023663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.961275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-29T14:45:02.023909Z","caller":"traceutil/trace.go:171","msg":"trace[844610973] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18386; }","duration":"146.21571ms","start":"2026-07-29T14:45:01.877609Z","end":"2026-07-29T14:45:02.023825Z","steps":["trace[844610973] 'range keys from in-memory index tree' (duration: 145.734491ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:02.225777Z","caller":"traceutil/trace.go:171","msg":"trace[215356312] transaction","detail":"{read_only:false; response_revision:18388; number_of_response:1; }","duration":"150.808318ms","start":"2026-07-29T14:45:02.074933Z","end":"2026-07-29T14:45:02.225741Z","steps":["trace[215356312] 'process raft request' (duration: 150.537283ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:02.392842Z","caller":"traceutil/trace.go:171","msg":"trace[1724029806] transaction","detail":"{read_only:false; response_revision:18389; number_of_response:1; }","duration":"105.305914ms","start":"2026-07-29T14:45:02.287504Z","end":"2026-07-29T14:45:02.39281Z","steps":["trace[1724029806] 'process raft request' (duration: 65.151102ms)","trace[1724029806] 'compare' (duration: 39.894486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:17.648736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.407435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-2rfrj\" ","response":"range_response_count:1 size:18591"} {"level":"info","ts":"2026-07-29T14:45:17.64884Z","caller":"traceutil/trace.go:171","msg":"trace[1112836861] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-2rfrj; range_end:; response_count:1; response_revision:18484; }","duration":"144.566538ms","start":"2026-07-29T14:45:17.504252Z","end":"2026-07-29T14:45:17.648819Z","steps":["trace[1112836861] 'range keys from in-memory index tree' (duration: 144.265612ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:22.400608Z","caller":"traceutil/trace.go:171","msg":"trace[2054466997] transaction","detail":"{read_only:false; response_revision:18534; number_of_response:1; }","duration":"104.253054ms","start":"2026-07-29T14:45:22.296321Z","end":"2026-07-29T14:45:22.400574Z","steps":["trace[2054466997] 'process raft request' (duration: 92.13879ms)","trace[2054466997] 'compare' (duration: 11.764526ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:23.018129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.448518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T14:45:23.018747Z","caller":"traceutil/trace.go:171","msg":"trace[623919213] transaction","detail":"{read_only:false; response_revision:18548; number_of_response:1; }","duration":"448.502829ms","start":"2026-07-29T14:45:22.570194Z","end":"2026-07-29T14:45:23.018697Z","steps":["trace[623919213] 'process raft request' (duration: 343.360518ms)","trace[623919213] 'compare' (duration: 104.123561ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:23.018771Z","caller":"traceutil/trace.go:171","msg":"trace[974207699] linearizableReadLoop","detail":"{readStateIndex:19131; appliedIndex:19130; }","duration":"432.020643ms","start":"2026-07-29T14:45:22.586676Z","end":"2026-07-29T14:45:23.018697Z","steps":["trace[974207699] 'read index received' (duration: 326.894463ms)","trace[974207699] 'applied index is now lower than readState.Index' (duration: 105.1214ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:23.018852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T14:45:22.570183Z","time spent":"448.617812ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T14:45:23.052603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.884914ms","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-07-29T14:45:23.052609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.915575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-29T14:45:23.052697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.407335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-29T14:45:23.052732Z","caller":"traceutil/trace.go:171","msg":"trace[1357558341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18548; }","duration":"466.074957ms","start":"2026-07-29T14:45:22.586619Z","end":"2026-07-29T14:45:23.052694Z","steps":["trace[1357558341] 'agreement among raft nodes before linearized reading' (duration: 432.167016ms)","trace[1357558341] 'range keys from in-memory index tree' (duration: 33.611026ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:23.052775Z","caller":"traceutil/trace.go:171","msg":"trace[1817909289] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:18548; }","duration":"299.532557ms","start":"2026-07-29T14:45:22.753219Z","end":"2026-07-29T14:45:23.052752Z","steps":["trace[1817909289] 'agreement among raft nodes before linearized reading' (duration: 299.291483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:23.052794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T14:45:22.586597Z","time spent":"466.18639ms","remote":"127.0.0.1:33982","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":"info","ts":"2026-07-29T14:45:23.052708Z","caller":"traceutil/trace.go:171","msg":"trace[501315125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18548; }","duration":"299.047427ms","start":"2026-07-29T14:45:22.753635Z","end":"2026-07-29T14:45:23.052683Z","steps":["trace[501315125] 'agreement among raft nodes before linearized reading' (duration: 298.846423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:23.052869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T14:45:22.752531Z","time spent":"300.325122ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T14:45:23.052599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.574092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T14:45:23.052972Z","caller":"traceutil/trace.go:171","msg":"trace[204672230] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:18548; }","duration":"279.98174ms","start":"2026-07-29T14:45:22.772968Z","end":"2026-07-29T14:45:23.05295Z","steps":["trace[204672230] 'agreement among raft nodes before linearized reading' (duration: 279.503151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:23.052603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.320348ms","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-07-29T14:45:23.05303Z","caller":"traceutil/trace.go:171","msg":"trace[1353976931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18548; }","duration":"163.800698ms","start":"2026-07-29T14:45:22.889215Z","end":"2026-07-29T14:45:23.053016Z","steps":["trace[1353976931] 'agreement among raft nodes before linearized reading' (duration: 163.241217ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.184868Z","caller":"traceutil/trace.go:171","msg":"trace[439737118] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18549; }","duration":"154.01462ms","start":"2026-07-29T14:45:23.030817Z","end":"2026-07-29T14:45:23.184832Z","steps":["trace[439737118] 'process raft request' (duration: 153.783256ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.184901Z","caller":"traceutil/trace.go:171","msg":"trace[1441944101] transaction","detail":"{read_only:false; response_revision:18550; number_of_response:1; }","duration":"125.971381ms","start":"2026-07-29T14:45:23.058909Z","end":"2026-07-29T14:45:23.184881Z","steps":["trace[1441944101] 'process raft request' (duration: 125.814868ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.185351Z","caller":"traceutil/trace.go:171","msg":"trace[1155845278] transaction","detail":"{read_only:false; response_revision:18553; number_of_response:1; }","duration":"126.219635ms","start":"2026-07-29T14:45:23.059117Z","end":"2026-07-29T14:45:23.185337Z","steps":["trace[1155845278] 'process raft request' (duration: 126.129014ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.185332Z","caller":"traceutil/trace.go:171","msg":"trace[2113937553] linearizableReadLoop","detail":"{readStateIndex:19134; appliedIndex:19133; }","duration":"132.846163ms","start":"2026-07-29T14:45:23.05245Z","end":"2026-07-29T14:45:23.185297Z","steps":["trace[2113937553] 'read index received' (duration: 36.770826ms)","trace[2113937553] 'applied index is now lower than readState.Index' (duration: 96.074287ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:23.185433Z","caller":"traceutil/trace.go:171","msg":"trace[672310413] transaction","detail":"{read_only:false; response_revision:18551; number_of_response:1; }","duration":"126.473701ms","start":"2026-07-29T14:45:23.058934Z","end":"2026-07-29T14:45:23.185408Z","steps":["trace[672310413] 'process raft request' (duration: 125.89977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:23.185589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.715518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-29T14:45:23.185623Z","caller":"traceutil/trace.go:171","msg":"trace[1395233076] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18553; }","duration":"147.80575ms","start":"2026-07-29T14:45:23.037806Z","end":"2026-07-29T14:45:23.185612Z","steps":["trace[1395233076] 'agreement among raft nodes before linearized reading' (duration: 147.630016ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.185368Z","caller":"traceutil/trace.go:171","msg":"trace[1468981279] transaction","detail":"{read_only:false; response_revision:18552; number_of_response:1; }","duration":"126.316227ms","start":"2026-07-29T14:45:23.059018Z","end":"2026-07-29T14:45:23.185334Z","steps":["trace[1468981279] 'process raft request' (duration: 126.072333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:23.18564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.680923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-07-29T14:45:23.185688Z","caller":"traceutil/trace.go:171","msg":"trace[2128614936] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:18553; }","duration":"154.780594ms","start":"2026-07-29T14:45:23.030891Z","end":"2026-07-29T14:45:23.185672Z","steps":["trace[2128614936] 'agreement among raft nodes before linearized reading' (duration: 154.615171ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.208349Z","caller":"traceutil/trace.go:171","msg":"trace[1943403682] transaction","detail":"{read_only:false; response_revision:18554; number_of_response:1; }","duration":"113.580683ms","start":"2026-07-29T14:45:23.094733Z","end":"2026-07-29T14:45:23.208313Z","steps":["trace[1943403682] 'process raft request' (duration: 113.323628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:23.20852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.872946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/neutron-server\" ","response":"range_response_count:1 size:10366"} {"level":"info","ts":"2026-07-29T14:45:23.20863Z","caller":"traceutil/trace.go:171","msg":"trace[1418485983] range","detail":"{range_begin:/registry/deployments/openstack/neutron-server; range_end:; response_count:1; response_revision:18554; }","duration":"143.022389ms","start":"2026-07-29T14:45:23.065582Z","end":"2026-07-29T14:45:23.208605Z","steps":["trace[1418485983] 'agreement among raft nodes before linearized reading' (duration: 142.655381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:23.209075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.536682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/neutron-server-certs-sdklm\" ","response":"range_response_count:1 size:2481"} {"level":"info","ts":"2026-07-29T14:45:23.209176Z","caller":"traceutil/trace.go:171","msg":"trace[2087738707] range","detail":"{range_begin:/registry/secrets/openstack/neutron-server-certs-sdklm; range_end:; response_count:1; response_revision:18554; }","duration":"114.710925ms","start":"2026-07-29T14:45:23.094442Z","end":"2026-07-29T14:45:23.209153Z","steps":["trace[2087738707] 'agreement among raft nodes before linearized reading' (duration: 114.363849ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.327005Z","caller":"traceutil/trace.go:171","msg":"trace[2030498481] transaction","detail":"{read_only:false; response_revision:18555; number_of_response:1; }","duration":"136.36084ms","start":"2026-07-29T14:45:23.190608Z","end":"2026-07-29T14:45:23.326969Z","steps":["trace[2030498481] 'process raft request' (duration: 94.034347ms)","trace[2030498481] 'compare' (duration: 42.071939ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:23.327284Z","caller":"traceutil/trace.go:171","msg":"trace[744704481] transaction","detail":"{read_only:false; response_revision:18556; number_of_response:1; }","duration":"131.327443ms","start":"2026-07-29T14:45:23.195894Z","end":"2026-07-29T14:45:23.327222Z","steps":["trace[744704481] 'process raft request' (duration: 131.002897ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:23.327578Z","caller":"traceutil/trace.go:171","msg":"trace[1358747169] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18557; }","duration":"115.98637ms","start":"2026-07-29T14:45:23.211582Z","end":"2026-07-29T14:45:23.327568Z","steps":["trace[1358747169] 'process raft request' (duration: 115.51626ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:24.095374Z","caller":"traceutil/trace.go:171","msg":"trace[983964784] transaction","detail":"{read_only:false; response_revision:18566; number_of_response:1; }","duration":"136.81035ms","start":"2026-07-29T14:45:23.958529Z","end":"2026-07-29T14:45:24.095339Z","steps":["trace[983964784] 'process raft request' (duration: 94.141171ms)","trace[983964784] 'compare' (duration: 42.221961ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:24.095896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.577179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jqv6z\" ","response":"range_response_count:1 size:20566"} {"level":"warn","ts":"2026-07-29T14:45:24.095916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.519225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-07-29T14:45:24.095982Z","caller":"traceutil/trace.go:171","msg":"trace[1190616867] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jqv6z; range_end:; response_count:1; response_revision:18567; }","duration":"129.710522ms","start":"2026-07-29T14:45:23.966252Z","end":"2026-07-29T14:45:24.095963Z","steps":["trace[1190616867] 'agreement among raft nodes before linearized reading' (duration: 129.434347ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:24.096009Z","caller":"traceutil/trace.go:171","msg":"trace[331858156] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:18567; }","duration":"108.646138ms","start":"2026-07-29T14:45:23.987337Z","end":"2026-07-29T14:45:24.095984Z","steps":["trace[331858156] 'agreement among raft nodes before linearized reading' (duration: 108.383063ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:24.25617Z","caller":"traceutil/trace.go:171","msg":"trace[1607842729] linearizableReadLoop","detail":"{readStateIndex:19154; appliedIndex:19152; }","duration":"104.03819ms","start":"2026-07-29T14:45:24.152096Z","end":"2026-07-29T14:45:24.256134Z","steps":["trace[1607842729] 'read index received' (duration: 9.86789ms)","trace[1607842729] 'applied index is now lower than readState.Index' (duration: 94.16908ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:24.256245Z","caller":"traceutil/trace.go:171","msg":"trace[48994529] transaction","detail":"{read_only:false; response_revision:18569; number_of_response:1; }","duration":"147.772981ms","start":"2026-07-29T14:45:24.108432Z","end":"2026-07-29T14:45:24.256205Z","steps":["trace[48994529] 'process raft request' (duration: 136.437163ms)","trace[48994529] 'compare' (duration: 11.110254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:24.25657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.436457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-29T14:45:24.256674Z","caller":"traceutil/trace.go:171","msg":"trace[1745605789] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18569; }","duration":"104.59973ms","start":"2026-07-29T14:45:24.152053Z","end":"2026-07-29T14:45:24.256653Z","steps":["trace[1745605789] 'agreement among raft nodes before linearized reading' (duration: 104.242104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:24.274204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.552556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-29T14:45:24.274299Z","caller":"traceutil/trace.go:171","msg":"trace[664636003] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18570; }","duration":"121.669648ms","start":"2026-07-29T14:45:24.152598Z","end":"2026-07-29T14:45:24.274267Z","steps":["trace[664636003] 'agreement among raft nodes before linearized reading' (duration: 121.404103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:24.274855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.863095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-frq4x\" ","response":"range_response_count:1 size:22022"} {"level":"info","ts":"2026-07-29T14:45:24.274905Z","caller":"traceutil/trace.go:171","msg":"trace[402582343] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-frq4x; range_end:; response_count:1; response_revision:18570; }","duration":"117.926347ms","start":"2026-07-29T14:45:24.156964Z","end":"2026-07-29T14:45:24.274891Z","steps":["trace[402582343] 'agreement among raft nodes before linearized reading' (duration: 117.807054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:24.27523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.921124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-29T14:45:24.275278Z","caller":"traceutil/trace.go:171","msg":"trace[940775967] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18570; }","duration":"120.997735ms","start":"2026-07-29T14:45:24.154267Z","end":"2026-07-29T14:45:24.275265Z","steps":["trace[940775967] 'agreement among raft nodes before linearized reading' (duration: 120.879122ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:47.499906Z","caller":"traceutil/trace.go:171","msg":"trace[540333227] transaction","detail":"{read_only:false; response_revision:18745; number_of_response:1; }","duration":"107.388504ms","start":"2026-07-29T14:45:47.392487Z","end":"2026-07-29T14:45:47.499876Z","steps":["trace[540333227] 'process raft request' (duration: 107.17311ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:47.773856Z","caller":"traceutil/trace.go:171","msg":"trace[103811626] transaction","detail":"{read_only:false; response_revision:18751; number_of_response:1; }","duration":"105.687102ms","start":"2026-07-29T14:45:47.668134Z","end":"2026-07-29T14:45:47.773821Z","steps":["trace[103811626] 'process raft request' (duration: 105.321294ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:48.555045Z","caller":"traceutil/trace.go:171","msg":"trace[368002059] linearizableReadLoop","detail":"{readStateIndex:19346; appliedIndex:19344; }","duration":"121.133259ms","start":"2026-07-29T14:45:48.433883Z","end":"2026-07-29T14:45:48.555016Z","steps":["trace[368002059] 'read index received' (duration: 7.318371ms)","trace[368002059] 'applied index is now lower than readState.Index' (duration: 113.813778ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:48.555175Z","caller":"traceutil/trace.go:171","msg":"trace[1033557644] transaction","detail":"{read_only:false; response_revision:18756; number_of_response:1; }","duration":"123.30035ms","start":"2026-07-29T14:45:48.431827Z","end":"2026-07-29T14:45:48.55511Z","steps":["trace[1033557644] 'process raft request' (duration: 105.307924ms)","trace[1033557644] 'compare' (duration: 17.74409ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:48.555896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.982454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:637101"} {"level":"info","ts":"2026-07-29T14:45:48.555947Z","caller":"traceutil/trace.go:171","msg":"trace[278450948] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:18756; }","duration":"122.082716ms","start":"2026-07-29T14:45:48.433853Z","end":"2026-07-29T14:45:48.555936Z","steps":["trace[278450948] 'agreement among raft nodes before linearized reading' (duration: 121.301521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:52.299349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.452877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-2rfrj\" ","response":"range_response_count:1 size:18591"} {"level":"info","ts":"2026-07-29T14:45:52.299457Z","caller":"traceutil/trace.go:171","msg":"trace[1398075279] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-2rfrj; range_end:; response_count:1; response_revision:18779; }","duration":"105.61778ms","start":"2026-07-29T14:45:52.193814Z","end":"2026-07-29T14:45:52.299432Z","steps":["trace[1398075279] 'range keys from in-memory index tree' (duration: 105.202451ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:53.706448Z","caller":"traceutil/trace.go:171","msg":"trace[340269906] linearizableReadLoop","detail":"{readStateIndex:19379; appliedIndex:19378; }","duration":"101.494431ms","start":"2026-07-29T14:45:53.604927Z","end":"2026-07-29T14:45:53.706422Z","steps":["trace[340269906] 'read index received' (duration: 101.268277ms)","trace[340269906] 'applied index is now lower than readState.Index' (duration: 225.274µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:53.706572Z","caller":"traceutil/trace.go:171","msg":"trace[1618576216] transaction","detail":"{read_only:false; response_revision:18788; number_of_response:1; }","duration":"105.644631ms","start":"2026-07-29T14:45:53.60089Z","end":"2026-07-29T14:45:53.706535Z","steps":["trace[1618576216] 'process raft request' (duration: 105.364766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:53.706699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.614963ms","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-07-29T14:45:53.706744Z","caller":"traceutil/trace.go:171","msg":"trace[2139339303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18788; }","duration":"101.723605ms","start":"2026-07-29T14:45:53.605009Z","end":"2026-07-29T14:45:53.706733Z","steps":["trace[2139339303] 'agreement among raft nodes before linearized reading' (duration: 101.558502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:53.706739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.781827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-29T14:45:53.706844Z","caller":"traceutil/trace.go:171","msg":"trace[678514234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18788; }","duration":"101.926069ms","start":"2026-07-29T14:45:53.604891Z","end":"2026-07-29T14:45:53.706817Z","steps":["trace[678514234] 'agreement among raft nodes before linearized reading' (duration: 101.659304ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:54.483689Z","caller":"traceutil/trace.go:171","msg":"trace[1566206755] linearizableReadLoop","detail":"{readStateIndex:19385; appliedIndex:19384; }","duration":"120.446495ms","start":"2026-07-29T14:45:54.363212Z","end":"2026-07-29T14:45:54.483658Z","steps":["trace[1566206755] 'read index received' (duration: 26.503949ms)","trace[1566206755] 'applied index is now lower than readState.Index' (duration: 93.941276ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:45:54.4838Z","caller":"traceutil/trace.go:171","msg":"trace[848971656] transaction","detail":"{read_only:false; response_revision:18794; number_of_response:1; }","duration":"121.19158ms","start":"2026-07-29T14:45:54.362568Z","end":"2026-07-29T14:45:54.48376Z","steps":["trace[848971656] 'process raft request' (duration: 27.189513ms)","trace[848971656] 'compare' (duration: 93.751722ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:45:54.484046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.801672ms","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-07-29T14:45:54.48441Z","caller":"traceutil/trace.go:171","msg":"trace[401335163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18794; }","duration":"121.168428ms","start":"2026-07-29T14:45:54.363185Z","end":"2026-07-29T14:45:54.484354Z","steps":["trace[401335163] 'agreement among raft nodes before linearized reading' (duration: 120.573257ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:54.559379Z","caller":"traceutil/trace.go:171","msg":"trace[1250203423] transaction","detail":"{read_only:false; response_revision:18795; number_of_response:1; }","duration":"119.180021ms","start":"2026-07-29T14:45:54.440172Z","end":"2026-07-29T14:45:54.559352Z","steps":["trace[1250203423] 'process raft request' (duration: 119.039138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:54.559954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.521919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T14:45:54.560012Z","caller":"traceutil/trace.go:171","msg":"trace[917408973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18795; }","duration":"193.61838ms","start":"2026-07-29T14:45:54.366377Z","end":"2026-07-29T14:45:54.559996Z","steps":["trace[917408973] 'agreement among raft nodes before linearized reading' (duration: 193.494658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:54.56021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.700091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:637101"} {"level":"info","ts":"2026-07-29T14:45:54.560262Z","caller":"traceutil/trace.go:171","msg":"trace[1227505538] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:18795; }","duration":"196.798212ms","start":"2026-07-29T14:45:54.363451Z","end":"2026-07-29T14:45:54.560249Z","steps":["trace[1227505538] 'agreement among raft nodes before linearized reading' (duration: 196.028237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:45:54.560743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.117418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:637101"} {"level":"info","ts":"2026-07-29T14:45:54.560786Z","caller":"traceutil/trace.go:171","msg":"trace[139819652] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:18795; }","duration":"197.17448ms","start":"2026-07-29T14:45:54.363601Z","end":"2026-07-29T14:45:54.560775Z","steps":["trace[139819652] 'agreement among raft nodes before linearized reading' (duration: 196.682211ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:54.609864Z","caller":"traceutil/trace.go:171","msg":"trace[2077382315] transaction","detail":"{read_only:false; response_revision:18796; number_of_response:1; }","duration":"119.809093ms","start":"2026-07-29T14:45:54.490029Z","end":"2026-07-29T14:45:54.609838Z","steps":["trace[2077382315] 'process raft request' (duration: 115.205855ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:45:58.83186Z","caller":"traceutil/trace.go:171","msg":"trace[502201403] transaction","detail":"{read_only:false; response_revision:18825; number_of_response:1; }","duration":"101.413909ms","start":"2026-07-29T14:45:58.730401Z","end":"2026-07-29T14:45:58.831815Z","steps":["trace[502201403] 'process raft request' (duration: 71.590286ms)","trace[502201403] 'compare' (duration: 29.192551ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:46:00.040249Z","caller":"traceutil/trace.go:171","msg":"trace[507501981] linearizableReadLoop","detail":"{readStateIndex:19425; appliedIndex:19424; }","duration":"180.142271ms","start":"2026-07-29T14:45:59.860054Z","end":"2026-07-29T14:46:00.040196Z","steps":["trace[507501981] 'read index received' (duration: 179.980608ms)","trace[507501981] 'applied index is now lower than readState.Index' (duration: 160.093µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:46:00.040554Z","caller":"traceutil/trace.go:171","msg":"trace[247122904] transaction","detail":"{read_only:false; response_revision:18833; number_of_response:1; }","duration":"220.392316ms","start":"2026-07-29T14:45:59.819971Z","end":"2026-07-29T14:46:00.040363Z","steps":["trace[247122904] 'process raft request' (duration: 220.010788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:00.040634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.56015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-29T14:46:00.040713Z","caller":"traceutil/trace.go:171","msg":"trace[291147265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18833; }","duration":"180.710122ms","start":"2026-07-29T14:45:59.859983Z","end":"2026-07-29T14:46:00.040693Z","steps":["trace[291147265] 'agreement among raft nodes before linearized reading' (duration: 180.383216ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:00.059652Z","caller":"traceutil/trace.go:171","msg":"trace[1516898190] transaction","detail":"{read_only:false; response_revision:18834; number_of_response:1; }","duration":"118.12286ms","start":"2026-07-29T14:45:59.941509Z","end":"2026-07-29T14:46:00.059632Z","steps":["trace[1516898190] 'process raft request' (duration: 117.897986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:02.867501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.890947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T14:46:02.867715Z","caller":"traceutil/trace.go:171","msg":"trace[271107745] transaction","detail":"{read_only:false; response_revision:18851; number_of_response:1; }","duration":"212.78639ms","start":"2026-07-29T14:46:02.6549Z","end":"2026-07-29T14:46:02.867687Z","steps":["trace[271107745] 'process raft request' (duration: 95.620027ms)","trace[271107745] 'compare' (duration: 116.633642ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:46:02.867901Z","caller":"traceutil/trace.go:171","msg":"trace[58787183] transaction","detail":"{read_only:false; response_revision:18852; number_of_response:1; }","duration":"138.850059ms","start":"2026-07-29T14:46:02.728994Z","end":"2026-07-29T14:46:02.867844Z","steps":["trace[58787183] 'process raft request' (duration: 138.618554ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:12.108779Z","caller":"traceutil/trace.go:171","msg":"trace[72485812] linearizableReadLoop","detail":"{readStateIndex:19530; appliedIndex:19529; }","duration":"284.556908ms","start":"2026-07-29T14:46:11.824196Z","end":"2026-07-29T14:46:12.108753Z","steps":["trace[72485812] 'read index received' (duration: 284.437686ms)","trace[72485812] 'applied index is now lower than readState.Index' (duration: 117.942µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:46:12.108873Z","caller":"traceutil/trace.go:171","msg":"trace[981050861] transaction","detail":"{read_only:false; response_revision:18936; number_of_response:1; }","duration":"297.412765ms","start":"2026-07-29T14:46:11.811432Z","end":"2026-07-29T14:46:12.108845Z","steps":["trace[981050861] 'process raft request' (duration: 297.11579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:12.10904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.835043ms","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-07-29T14:46:12.109091Z","caller":"traceutil/trace.go:171","msg":"trace[329784668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18936; }","duration":"284.933205ms","start":"2026-07-29T14:46:11.824141Z","end":"2026-07-29T14:46:12.109074Z","steps":["trace[329784668] 'agreement among raft nodes before linearized reading' (duration: 284.760662ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:12.114107Z","caller":"traceutil/trace.go:171","msg":"trace[2123484943] transaction","detail":"{read_only:false; response_revision:18937; number_of_response:1; }","duration":"245.363685ms","start":"2026-07-29T14:46:11.86872Z","end":"2026-07-29T14:46:12.114083Z","steps":["trace[2123484943] 'process raft request' (duration: 245.188912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:12.114518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.672735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:537"} {"level":"info","ts":"2026-07-29T14:46:12.114636Z","caller":"traceutil/trace.go:171","msg":"trace[49707870] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:18938; }","duration":"189.844428ms","start":"2026-07-29T14:46:11.924766Z","end":"2026-07-29T14:46:12.11461Z","steps":["trace[49707870] 'agreement among raft nodes before linearized reading' (duration: 189.523132ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:12.510872Z","caller":"traceutil/trace.go:171","msg":"trace[1956101986] transaction","detail":"{read_only:false; response_revision:18940; number_of_response:1; }","duration":"386.656781ms","start":"2026-07-29T14:46:12.12419Z","end":"2026-07-29T14:46:12.510846Z","steps":["trace[1956101986] 'process raft request' (duration: 386.527169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:12.511008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T14:46:12.124162Z","time spent":"386.773083ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T14:46:12.545321Z","caller":"traceutil/trace.go:171","msg":"trace[1313783389] linearizableReadLoop","detail":"{readStateIndex:19535; appliedIndex:19534; }","duration":"397.107622ms","start":"2026-07-29T14:46:12.148179Z","end":"2026-07-29T14:46:12.545287Z","steps":["trace[1313783389] 'read index received' (duration: 363.219631ms)","trace[1313783389] 'applied index is now lower than readState.Index' (duration: 33.886571ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:46:12.545353Z","caller":"traceutil/trace.go:171","msg":"trace[1759725952] transaction","detail":"{read_only:false; response_revision:18942; number_of_response:1; }","duration":"253.465981ms","start":"2026-07-29T14:46:12.291857Z","end":"2026-07-29T14:46:12.545323Z","steps":["trace[1759725952] 'process raft request' (duration: 253.338489ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:12.545463Z","caller":"traceutil/trace.go:171","msg":"trace[1590650878] transaction","detail":"{read_only:false; response_revision:18943; number_of_response:1; }","duration":"128.654993ms","start":"2026-07-29T14:46:12.416771Z","end":"2026-07-29T14:46:12.545426Z","steps":["trace[1590650878] 'process raft request' (duration: 128.468389ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:12.545464Z","caller":"traceutil/trace.go:171","msg":"trace[1720713722] transaction","detail":"{read_only:false; response_revision:18941; number_of_response:1; }","duration":"419.774787ms","start":"2026-07-29T14:46:12.125624Z","end":"2026-07-29T14:46:12.545398Z","steps":["trace[1720713722] 'process raft request' (duration: 419.408741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:12.545701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.502729ms","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-07-29T14:46:12.546302Z","caller":"traceutil/trace.go:171","msg":"trace[1512024378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18943; }","duration":"398.147702ms","start":"2026-07-29T14:46:12.148133Z","end":"2026-07-29T14:46:12.546281Z","steps":["trace[1512024378] 'agreement among raft nodes before linearized reading' (duration: 397.403748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:12.546305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T14:46:12.125601Z","time spent":"420.586703ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T14:46:12.546363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T14:46:12.148108Z","time spent":"398.239254ms","remote":"127.0.0.1:33982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T14:46:12.802402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.361566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T14:46:12.802529Z","caller":"traceutil/trace.go:171","msg":"trace[770262152] linearizableReadLoop","detail":"{readStateIndex:19538; appliedIndex:19537; }","duration":"139.528352ms","start":"2026-07-29T14:46:12.662986Z","end":"2026-07-29T14:46:12.802514Z","steps":["trace[770262152] 'read index received' (duration: 22.86704ms)","trace[770262152] 'applied index is now lower than readState.Index' (duration: 116.659922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T14:46:12.802661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.677075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-29T14:46:12.80268Z","caller":"traceutil/trace.go:171","msg":"trace[1538315228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18944; }","duration":"139.734286ms","start":"2026-07-29T14:46:12.662938Z","end":"2026-07-29T14:46:12.802673Z","steps":["trace[1538315228] 'agreement among raft nodes before linearized reading' (duration: 139.612534ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:12.802732Z","caller":"traceutil/trace.go:171","msg":"trace[10879474] transaction","detail":"{read_only:false; response_revision:18944; number_of_response:1; }","duration":"251.700968ms","start":"2026-07-29T14:46:12.550995Z","end":"2026-07-29T14:46:12.802696Z","steps":["trace[10879474] 'process raft request' (duration: 134.931513ms)","trace[10879474] 'compare' (duration: 116.253014ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:46:13.094064Z","caller":"traceutil/trace.go:171","msg":"trace[741377112] transaction","detail":"{read_only:false; response_revision:18946; number_of_response:1; }","duration":"199.058095ms","start":"2026-07-29T14:46:12.894976Z","end":"2026-07-29T14:46:13.094034Z","steps":["trace[741377112] 'process raft request' (duration: 198.879741ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:13.102607Z","caller":"traceutil/trace.go:171","msg":"trace[2047752447] linearizableReadLoop","detail":"{readStateIndex:19542; appliedIndex:19540; }","duration":"144.826964ms","start":"2026-07-29T14:46:12.957752Z","end":"2026-07-29T14:46:13.102579Z","steps":["trace[2047752447] 'read index received' (duration: 136.176688ms)","trace[2047752447] 'applied index is now lower than readState.Index' (duration: 8.648826ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T14:46:13.102743Z","caller":"traceutil/trace.go:171","msg":"trace[1583446849] transaction","detail":"{read_only:false; response_revision:18947; number_of_response:1; }","duration":"199.806021ms","start":"2026-07-29T14:46:12.902923Z","end":"2026-07-29T14:46:13.102729Z","steps":["trace[1583446849] 'process raft request' (duration: 199.492885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T14:46:13.103275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.506846ms","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-07-29T14:46:13.103378Z","caller":"traceutil/trace.go:171","msg":"trace[375226191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18947; }","duration":"145.668029ms","start":"2026-07-29T14:46:12.957685Z","end":"2026-07-29T14:46:13.103353Z","steps":["trace[375226191] 'agreement among raft nodes before linearized reading' (duration: 145.447245ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T14:46:24.479314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16889} {"level":"info","ts":"2026-07-29T14:46:24.56048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16889,"took":"78.396337ms","hash":181761576,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":16097280,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-29T14:46:24.560554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":181761576,"revision":16889,"compact-revision":14409} {"level":"info","ts":"2026-07-29T14:47:11.367175Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T14:47:11.372939Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-29T14:47:11.37306Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-29T14:51:24.494988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19084} {"level":"info","ts":"2026-07-29T14:51:24.577095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19084,"took":"79.640507ms","hash":2616357251,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":14872576,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-29T14:51:24.577179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2616357251,"revision":19084,"compact-revision":16889} {"level":"info","ts":"2026-07-29T14:56:24.503165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21067} {"level":"info","ts":"2026-07-29T14:56:24.586524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21067,"took":"81.496643ms","hash":2351458050,"current-db-size-bytes":35983360,"current-db-size":"36 MB","current-db-size-in-use-bytes":14188544,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-29T14:56:24.586607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2351458050,"revision":21067,"compact-revision":19084}